builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0070 starttime: 1491348516.68 results: success (0) buildid: 20170404153430 builduid: a0857b3095c24f4886b14163a31b6d4b revision: 41323426061e6cd60125b5f0728442faca1cbe60 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:36.680717) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:36.681141) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:36.681415) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:36.710076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-04 16:28:36.710356) ========= 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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-04 16:28:36-- 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% 74.0M=0s 2017-04-04 16:28:36 (74.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090561 ========= master_lag: 3.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-04 16:28:39.931026) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:39.931382) ========= 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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021967 ========= master_lag: 0.71 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:40.663130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-04 16:28:40.663588) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 41323426061e6cd60125b5f0728442faca1cbe60 --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 41323426061e6cd60125b5f0728442faca1cbe60 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6UHHsNkMJ8/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.JADfRt5KWI/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-04 16:28:40,753 truncating revision to first 12 chars 2017-04-04 16:28:40,753 Setting DEBUG logging. 2017-04-04 16:28:40,753 attempt 1/10 2017-04-04 16:28:40,753 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/41323426061e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-04 16:28:42,505 unpacking tar archive at: mozilla-beta-41323426061e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.029179 ========= master_lag: 2.39 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-04 16:28:45.083727) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:45.084061) ========= script_repo_revision: 41323426061e6cd60125b5f0728442faca1cbe60 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:45.084514) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:45.084796) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 16:28:45.106642) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-04-04 16:28:45.106950) ========= /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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:28:45 INFO - MultiFileLogger online at 20170404 16:28:45 in /builds/slave/test 16:28:45 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 16:28:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:45 INFO - {'append_to_log': False, 16:28:45 INFO - 'base_work_dir': '/builds/slave/test', 16:28:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:28:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:45 INFO - 'branch': 'Mozilla-Beta', 16:28:45 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:45 INFO - 'config_files': ('talos/mac_config.py',), 16:28:45 INFO - 'default_actions': ('clobber', 16:28:45 INFO - 'read-buildbot-config', 16:28:45 INFO - 'download-and-extract', 16:28:45 INFO - 'populate-webroot', 16:28:45 INFO - 'create-virtualenv', 16:28:45 INFO - 'install', 16:28:45 INFO - 'run-tests'), 16:28:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:45 INFO - 'download_minidump_stackwalk': True, 16:28:45 INFO - 'download_symbols': 'ondemand', 16:28:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:45 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:45 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:45 INFO - 'installer_path': 'installer.exe', 16:28:45 INFO - 'log_level': 'info', 16:28:45 INFO - 'log_name': 'talos', 16:28:45 INFO - 'log_to_console': True, 16:28:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:28:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:28:45 INFO - 'opt_config_files': (), 16:28:45 INFO - 'pip_index': False, 16:28:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:45 INFO - 'cmd': ('bash', 16:28:45 INFO - '-c', 16:28:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:45 INFO - 'enabled': True, 16:28:45 INFO - 'halt_on_failure': False, 16:28:45 INFO - 'name': 'check_screen_resolution'},), 16:28:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:45 INFO - 'cmd': ('bash', 16:28:45 INFO - '-c', 16:28:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:45 INFO - 'enabled': True, 16:28:45 INFO - 'halt_on_failure': False, 16:28:45 INFO - 'name': 'check_screen_resolution'},), 16:28:45 INFO - 'run_cmd_checks_enabled': True, 16:28:45 INFO - 'sps_profile': False, 16:28:45 INFO - 'sps_profile_interval': 0, 16:28:45 INFO - 'suite': 'dromaeojs-e10s', 16:28:45 INFO - 'system_bits': '32', 16:28:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:45 INFO - 'title': 't-yosemite-r7-0070', 16:28:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:45 INFO - 'use_talos_json': True, 16:28:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:45 INFO - 'work_dir': 'build'} 16:28:45 INFO - [mozharness: 2017-04-04 23:28:45.319369Z] Running clobber step. 16:28:45 INFO - Running pre-action listener: _resource_record_pre_action 16:28:45 INFO - Running main action method: clobber 16:28:45 INFO - rmtree: /builds/slave/test/build 16:28:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:48 INFO - Running post-action listener: _resource_record_post_action 16:28:48 INFO - [mozharness: 2017-04-04 23:28:48.551534Z] Finished clobber step (success) 16:28:48 INFO - [mozharness: 2017-04-04 23:28:48.551792Z] Running read-buildbot-config step. 16:28:48 INFO - Running pre-action listener: _resource_record_pre_action 16:28:48 INFO - Running main action method: read_buildbot_config 16:28:48 INFO - Using buildbot properties: 16:28:48 INFO - { 16:28:48 INFO - "project": "", 16:28:48 INFO - "product": "firefox", 16:28:48 INFO - "script_repo_revision": "production", 16:28:48 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 16:28:48 INFO - "repository": "", 16:28:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 16:28:48 INFO - "buildid": "20170404153430", 16:28:48 INFO - "pgo_build": "False", 16:28:48 INFO - "basedir": "/builds/slave/test", 16:28:48 INFO - "buildnumber": 8, 16:28:48 INFO - "slavename": "t-yosemite-r7-0070", 16:28:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:28:48 INFO - "platform": "yosemite_r7", 16:28:48 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:28:48 INFO - "branch": "mozilla-beta", 16:28:48 INFO - "repo_path": "releases/mozilla-beta", 16:28:48 INFO - "revision": "41323426061e6cd60125b5f0728442faca1cbe60", 16:28:48 INFO - "stage_platform": "macosx64", 16:28:48 INFO - "builduid": "a0857b3095c24f4886b14163a31b6d4b", 16:28:48 INFO - "slavebuilddir": "test" 16:28:48 INFO - } 16:28:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 16:28:48 INFO - Running post-action listener: _resource_record_post_action 16:28:48 INFO - [mozharness: 2017-04-04 23:28:48.557520Z] Finished read-buildbot-config step (success) 16:28:48 INFO - [mozharness: 2017-04-04 23:28:48.557683Z] Running download-and-extract step. 16:28:48 INFO - Running pre-action listener: _resource_record_pre_action 16:28:48 INFO - Running main action method: download_and_extract 16:28:48 INFO - mkdir: /builds/slave/test/build/tests 16:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:48 INFO - trying https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 16:28:48 INFO - Downloading https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/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 16:28:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/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 16:28:49 INFO - Downloaded 1165 bytes. 16:28:49 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 16:28:49 INFO - Using the following test package requirements: 16:28:49 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 16:28:49 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:49 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 16:28:49 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:49 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 16:28:49 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:28:49 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:49 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 16:28:49 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 16:28:49 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:49 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 16:28:49 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:49 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 16:28:49 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:49 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 16:28:49 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:49 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 16:28:49 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 16:28:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 16:28:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 16:28:49 INFO - Fetch https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 16:28:50 INFO - Expected file size: 16116743 16:28:50 INFO - Obtained file size: 16116743 16:28:57 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 16:28:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 16:28:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 16:28:57 INFO - Fetch https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 16:28:58 INFO - Expected file size: 16116743 16:28:58 INFO - Obtained file size: 16116743 16:29:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 16:29:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 16:29:04 INFO - Fetch https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 16:29:05 INFO - Expected file size: 14403663 16:29:05 INFO - Obtained file size: 14403663 16:29: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')]} 16:29:05 INFO - trying https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:29:05 INFO - Downloading https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 16:29:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/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 16:29:07 INFO - Downloaded 58740468 bytes. 16:29:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:29:07 INFO - mkdir: /builds/slave/test/properties 16:29:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:29:07 INFO - Writing to file /builds/slave/test/properties/build_url 16:29:07 INFO - Contents: 16:29:07 INFO - build_url:https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:29:08 INFO - Running post-action listener: _resource_record_post_action 16:29:08 INFO - Running post-action listener: set_extra_try_arguments 16:29:08 INFO - [mozharness: 2017-04-04 23:29:08.000914Z] Finished download-and-extract step (success) 16:29:08 INFO - [mozharness: 2017-04-04 23:29:08.001066Z] Running populate-webroot step. 16:29:08 INFO - Running pre-action listener: _resource_record_pre_action 16:29:08 INFO - Running main action method: populate_webroot 16:29:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:29:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:29:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:29:08 INFO - '--tppagecycles', 16:29:08 INFO - '7'], 16:29:08 INFO - 'tests': ['tsvgm']}}, 16:29:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:29:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:29:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:29:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:29:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:29:08 INFO - 'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:29:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:29:08 INFO - 'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['damp', 'tps']}, 16:29:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:29:08 INFO - 'tests': ['damp', 'tps']}, 16:29:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['dromaeo_dom']}, 16:29:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['basic_compositor_video']}, 16:29:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:29:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['a11yr', 16:29:08 INFO - 'ts_paint', 16:29:08 INFO - 'tpaint', 16:29:08 INFO - 'sessionrestore', 16:29:08 INFO - 'sessionrestore_no_auto_restore', 16:29:08 INFO - 'tabpaint']}, 16:29:08 INFO - 'other-e10s': {'tests': ['a11yr', 16:29:08 INFO - 'ts_paint', 16:29:08 INFO - 'tpaint', 16:29:08 INFO - 'sessionrestore', 16:29:08 INFO - 'sessionrestore_no_auto_restore', 16:29:08 INFO - 'tabpaint']}, 16:29:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:29:08 INFO - 'ts_paint', 16:29:08 INFO - 'tpaint', 16:29:08 INFO - 'sessionrestore', 16:29:08 INFO - 'sessionrestore_no_auto_restore', 16:29:08 INFO - 'tabpaint']}, 16:29:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:29:08 INFO - 'ts_paint', 16:29:08 INFO - 'tpaint', 16:29:08 INFO - 'sessionrestore', 16:29:08 INFO - 'sessionrestore_no_auto_restore', 16:29:08 INFO - 'tabpaint']}, 16:29:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['a11yr', 16:29:08 INFO - 'ts_paint', 16:29:08 INFO - 'tpaint', 16:29:08 INFO - 'sessionrestore', 16:29:08 INFO - 'sessionrestore_no_auto_restore', 16:29:08 INFO - 'tabpaint']}, 16:29:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['a11yr', 16:29:08 INFO - 'ts_paint', 16:29:08 INFO - 'tpaint', 16:29:08 INFO - 'sessionrestore', 16:29:08 INFO - 'sessionrestore_no_auto_restore', 16:29:08 INFO - 'tabpaint']}, 16:29:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['tsvgx', 16:29:08 INFO - 'tsvgr_opacity', 16:29:08 INFO - 'tart', 16:29:08 INFO - 'tscrollx', 16:29:08 INFO - 'cart', 16:29:08 INFO - 'tsvg_static']}, 16:29:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:29:08 INFO - 'tsvgr_opacity', 16:29:08 INFO - 'tart', 16:29:08 INFO - 'tscrollx', 16:29:08 INFO - 'cart', 16:29:08 INFO - 'tsvg_static']}, 16:29:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:29:08 INFO - 'talos_options': ['--disable-e10s'], 16:29:08 INFO - 'tests': ['tp5o']}, 16:29:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:29:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:29:08 INFO - 'talos_options': ['--disable-e10s', 16:29:08 INFO - '--xperf_path', 16:29:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:08 INFO - 'tests': ['tp5n']}, 16:29:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:29:08 INFO - 'talos_options': ['--xperf_path', 16:29:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:08 INFO - 'tests': ['tp5n']}}, 16:29:08 INFO - 'talos.zip': {'path': '', 16:29:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:29:08 INFO - Running post-action listener: _resource_record_post_action 16:29:08 INFO - [mozharness: 2017-04-04 23:29:08.020840Z] Finished populate-webroot step (success) 16:29:08 INFO - [mozharness: 2017-04-04 23:29:08.020988Z] Running create-virtualenv step. 16:29:08 INFO - Running pre-action listener: _resource_record_pre_action 16:29:08 INFO - Running main action method: create_virtualenv 16:29:08 INFO - Creating virtualenv /builds/slave/test/build/venv 16:29:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:29:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:29:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:08 INFO - Using real prefix '/tools/python27' 16:29:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:29:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:11 INFO - Installing pip.................done. 16:29:11 INFO - Return code: 0 16:29:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:29:11 INFO - Reading from file tmpfile_stdout 16:29:11 INFO - Output received: 16:29:11 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) 16:29:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:11 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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:11 INFO - 'HOME': '/Users/cltbld', 16:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:11 INFO - 'LOGNAME': 'cltbld', 16:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:11 INFO - 'MOZ_NO_REMOTE': '1', 16:29:11 INFO - 'NO_EM_RESTART': '1', 16:29:11 INFO - 'PAGER': '/bin/cat', 16:29:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:11 INFO - 'PWD': '/builds/slave/test', 16:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:11 INFO - 'SHELL': '/bin/bash', 16:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:11 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:11 INFO - 'USER': 'cltbld', 16:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:11 INFO - 'XPC_FLAGS': '0x0', 16:29:11 INFO - 'XPC_SERVICE_NAME': '0', 16:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:29:12 INFO - Cleaning up... 16:29:12 INFO - Return code: 0 16:29:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:12 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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:12 INFO - 'HOME': '/Users/cltbld', 16:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:12 INFO - 'LOGNAME': 'cltbld', 16:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:12 INFO - 'MOZ_NO_REMOTE': '1', 16:29:12 INFO - 'NO_EM_RESTART': '1', 16:29:12 INFO - 'PAGER': '/bin/cat', 16:29:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:12 INFO - 'PWD': '/builds/slave/test', 16:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:12 INFO - 'SHELL': '/bin/bash', 16:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:12 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:12 INFO - 'USER': 'cltbld', 16:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:12 INFO - 'XPC_FLAGS': '0x0', 16:29:12 INFO - 'XPC_SERVICE_NAME': '0', 16:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:12 INFO - Downloading/unpacking psutil>=3.1.1 16:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:16 INFO - Installing collected packages: psutil 16:29:16 INFO - Running setup.py install for psutil 16:29:16 INFO - building 'psutil._psutil_osx' extension 16:29:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:29:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:29:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:29:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:29:16 INFO - building 'psutil._psutil_posix' extension 16:29:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:29:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:29:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:29:16 INFO - ^ 16:29:16 INFO - 1 warning generated. 16:29:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:29:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:17 INFO - Successfully installed psutil 16:29:17 INFO - Cleaning up... 16:29:17 INFO - Return code: 0 16:29:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:29:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:17 INFO - 'HOME': '/Users/cltbld', 16:29:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:17 INFO - 'LOGNAME': 'cltbld', 16:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:17 INFO - 'MOZ_NO_REMOTE': '1', 16:29:17 INFO - 'NO_EM_RESTART': '1', 16:29:17 INFO - 'PAGER': '/bin/cat', 16:29:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:17 INFO - 'PWD': '/builds/slave/test', 16:29:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:17 INFO - 'SHELL': '/bin/bash', 16:29:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:17 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:17 INFO - 'USER': 'cltbld', 16:29:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:17 INFO - 'XPC_FLAGS': '0x0', 16:29:17 INFO - 'XPC_SERVICE_NAME': '0', 16:29:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:29:20 INFO - Installing collected packages: mozsystemmonitor 16:29:20 INFO - Running setup.py install for mozsystemmonitor 16:29:20 INFO - Successfully installed mozsystemmonitor 16:29:20 INFO - Cleaning up... 16:29:20 INFO - Return code: 0 16:29:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:20 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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:20 INFO - 'HOME': '/Users/cltbld', 16:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:20 INFO - 'LOGNAME': 'cltbld', 16:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:20 INFO - 'MOZ_NO_REMOTE': '1', 16:29:20 INFO - 'NO_EM_RESTART': '1', 16:29:20 INFO - 'PAGER': '/bin/cat', 16:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:20 INFO - 'PWD': '/builds/slave/test', 16:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:20 INFO - 'SHELL': '/bin/bash', 16:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:20 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:20 INFO - 'USER': 'cltbld', 16:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:20 INFO - 'XPC_FLAGS': '0x0', 16:29:20 INFO - 'XPC_SERVICE_NAME': '0', 16:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:20 INFO - Downloading/unpacking jsonschema==2.5.1 16:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:23 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:23 INFO - warning: no files found matching '*.txt' 16:29:23 INFO - no previously-included directories found matching 'build' 16:29:23 INFO - no previously-included directories found matching 'dist' 16:29:23 INFO - no previously-included directories found matching '.git*' 16:29:23 INFO - Installing collected packages: jsonschema, functools32 16:29:23 INFO - Running setup.py install for functools32 16:29:23 INFO - warning: no files found matching '*.txt' 16:29:23 INFO - no previously-included directories found matching 'build' 16:29:23 INFO - no previously-included directories found matching 'dist' 16:29:23 INFO - no previously-included directories found matching '.git*' 16:29:23 INFO - Successfully installed jsonschema functools32 16:29:23 INFO - Cleaning up... 16:29:23 INFO - Return code: 0 16:29:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:23 INFO - 'HOME': '/Users/cltbld', 16:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:23 INFO - 'LOGNAME': 'cltbld', 16:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:23 INFO - 'MOZ_NO_REMOTE': '1', 16:29:23 INFO - 'NO_EM_RESTART': '1', 16:29:23 INFO - 'PAGER': '/bin/cat', 16:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:23 INFO - 'PWD': '/builds/slave/test', 16:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:23 INFO - 'SHELL': '/bin/bash', 16:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:23 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:23 INFO - 'USER': 'cltbld', 16:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:23 INFO - 'XPC_FLAGS': '0x0', 16:29:23 INFO - 'XPC_SERVICE_NAME': '0', 16:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:29:24 INFO - Cleaning up... 16:29:24 INFO - Return code: 0 16:29:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:24 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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:24 INFO - 'HOME': '/Users/cltbld', 16:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:24 INFO - 'LOGNAME': 'cltbld', 16:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:24 INFO - 'MOZ_NO_REMOTE': '1', 16:29:24 INFO - 'NO_EM_RESTART': '1', 16:29:24 INFO - 'PAGER': '/bin/cat', 16:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:24 INFO - 'PWD': '/builds/slave/test', 16:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:24 INFO - 'SHELL': '/bin/bash', 16:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:24 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:24 INFO - 'USER': 'cltbld', 16:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:24 INFO - 'XPC_FLAGS': '0x0', 16:29:24 INFO - 'XPC_SERVICE_NAME': '0', 16:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:24 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:27 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29:28 INFO - Downloading docopt-0.6.1.tar.gz 16:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:28 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:28 INFO - Running setup.py install for blobuploader 16:29:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:28 INFO - Running setup.py install for requests 16:29:29 INFO - Running setup.py install for docopt 16:29:29 INFO - Successfully installed blobuploader requests docopt 16:29:29 INFO - Cleaning up... 16:29:29 INFO - Return code: 0 16:29:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:29 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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:29 INFO - 'HOME': '/Users/cltbld', 16:29:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:29 INFO - 'LOGNAME': 'cltbld', 16:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:29 INFO - 'MOZ_NO_REMOTE': '1', 16:29:29 INFO - 'NO_EM_RESTART': '1', 16:29:29 INFO - 'PAGER': '/bin/cat', 16:29:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:29 INFO - 'PWD': '/builds/slave/test', 16:29:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:29 INFO - 'SHELL': '/bin/bash', 16:29:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:29 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:29 INFO - 'USER': 'cltbld', 16:29:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:29 INFO - 'XPC_FLAGS': '0x0', 16:29:29 INFO - 'XPC_SERVICE_NAME': '0', 16:29:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:29 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-lyTG0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:29 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-QYuQXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:29 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-yl2XFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:29 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-y_N8bX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-r7vFrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-fGVnc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-XUV1CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-CwLxUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-NyYYvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-4kagKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-9U3e5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-1vPCFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:30 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-iNMpqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:31 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-pJ_XXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:31 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Vp1jJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:31 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Y_gWCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:31 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Y2AfWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:31 INFO - Running setup.py install for manifestparser 16:29:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Running setup.py install for mozcrash 16:29:31 INFO - Running setup.py install for mozdebug 16:29:31 INFO - Running setup.py install for mozdevice 16:29:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for mozfile 16:29:32 INFO - Running setup.py install for mozhttpd 16:29:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for mozinfo 16:29:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for mozInstall 16:29:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for mozleak 16:29:32 INFO - Running setup.py install for mozlog 16:29:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for moznetwork 16:29:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:33 INFO - Running setup.py install for mozprocess 16:29:33 INFO - Running setup.py install for mozprofile 16:29:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:33 INFO - Running setup.py install for mozrunner 16:29:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:33 INFO - Running setup.py install for mozscreenshot 16:29:33 INFO - Running setup.py install for moztest 16:29:33 INFO - Running setup.py install for mozversion 16:29:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:34 INFO - Cleaning up... 16:29:34 INFO - Return code: 0 16:29:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:34 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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:29:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:34 INFO - 'HOME': '/Users/cltbld', 16:29:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:34 INFO - 'LOGNAME': 'cltbld', 16:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:34 INFO - 'MOZ_NO_REMOTE': '1', 16:29:34 INFO - 'NO_EM_RESTART': '1', 16:29:34 INFO - 'PAGER': '/bin/cat', 16:29:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:34 INFO - 'PWD': '/builds/slave/test', 16:29:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:34 INFO - 'SHELL': '/bin/bash', 16:29:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:34 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:34 INFO - 'USER': 'cltbld', 16:29:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:34 INFO - 'XPC_FLAGS': '0x0', 16:29:34 INFO - 'XPC_SERVICE_NAME': '0', 16:29:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-GjiAjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-qlamFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:35 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)) 16:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-6pp8oB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-aOMJTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:35 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)) 16:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-jtrddh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-G0kVo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-HZjB9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-gKZN8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-G8vTUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-U2brU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-VYA3UH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-3eozxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:36 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)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-cvG_Ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Bc7VLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:36 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)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-2_S6tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-HN93Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:36 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)) 16:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-xDrWeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:29:37 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)) 16:29:37 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)) 16:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:29:37 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)) 16:29:37 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)) 16:29:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:39 INFO - Downloading blessings-1.6.tar.gz 16:29:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:39 INFO - Installing collected packages: blessings 16:29:39 INFO - Running setup.py install for blessings 16:29:39 INFO - Successfully installed blessings 16:29:39 INFO - Cleaning up... 16:29:39 INFO - Return code: 0 16:29:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:40 INFO - Reading from file tmpfile_stdout 16:29:40 INFO - Current package versions: 16:29:40 INFO - blessings == 1.6 16:29:40 INFO - blobuploader == 1.2.4 16:29:40 INFO - docopt == 0.6.1 16:29:40 INFO - functools32 == 3.2.3-2 16:29:40 INFO - jsonschema == 2.5.1 16:29:40 INFO - manifestparser == 1.1 16:29:40 INFO - mozInstall == 1.12 16:29:40 INFO - mozcrash == 1.0 16:29:40 INFO - mozdebug == 0.1 16:29:40 INFO - mozdevice == 0.49 16:29:40 INFO - mozfile == 1.2 16:29:40 INFO - mozhttpd == 0.7 16:29:40 INFO - mozinfo == 0.9 16:29:40 INFO - mozleak == 0.1 16:29:40 INFO - mozlog == 3.4 16:29:40 INFO - moznetwork == 0.27 16:29:40 INFO - mozprocess == 0.24 16:29:40 INFO - mozprofile == 0.28 16:29:40 INFO - mozrunner == 6.13 16:29:40 INFO - mozscreenshot == 0.1 16:29:40 INFO - mozsystemmonitor == 0.3 16:29:40 INFO - moztest == 0.8 16:29:40 INFO - mozversion == 1.4 16:29:40 INFO - psutil == 3.1.1 16:29:40 INFO - requests == 1.2.3 16:29:40 INFO - wsgiref == 0.1.2 16:29:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:40 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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:40 INFO - 'HOME': '/Users/cltbld', 16:29:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:40 INFO - 'LOGNAME': 'cltbld', 16:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:40 INFO - 'MOZ_NO_REMOTE': '1', 16:29:40 INFO - 'NO_EM_RESTART': '1', 16:29:40 INFO - 'PAGER': '/bin/cat', 16:29:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:40 INFO - 'PWD': '/builds/slave/test', 16:29:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:40 INFO - 'SHELL': '/bin/bash', 16:29:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:40 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:40 INFO - 'USER': 'cltbld', 16:29:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:40 INFO - 'XPC_FLAGS': '0x0', 16:29:40 INFO - 'XPC_SERVICE_NAME': '0', 16:29:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:29:40 INFO - Cleaning up... 16:29:40 INFO - Return code: 0 16:29:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:40 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 0x10c273b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed21471030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, '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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:29:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:40 INFO - 'HOME': '/Users/cltbld', 16:29:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:40 INFO - 'LOGNAME': 'cltbld', 16:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:40 INFO - 'MOZ_NO_REMOTE': '1', 16:29:40 INFO - 'NO_EM_RESTART': '1', 16:29:40 INFO - 'PAGER': '/bin/cat', 16:29:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:40 INFO - 'PWD': '/builds/slave/test', 16:29:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:40 INFO - 'SHELL': '/bin/bash', 16:29:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:40 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:40 INFO - 'USER': 'cltbld', 16:29:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:40 INFO - 'XPC_FLAGS': '0x0', 16:29:40 INFO - 'XPC_SERVICE_NAME': '0', 16:29:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:40 INFO - Cleaning up... 16:29:40 INFO - Return code: 0 16:29:40 INFO - Running post-action listener: _resource_record_post_action 16:29:40 INFO - Running post-action listener: _start_resource_monitoring 16:29:40 INFO - Starting resource monitoring. 16:29:40 INFO - [mozharness: 2017-04-04 23:29:40.826473Z] Finished create-virtualenv step (success) 16:29:40 INFO - [mozharness: 2017-04-04 23:29:40.827433Z] Running install step. 16:29:40 INFO - Running pre-action listener: _resource_record_pre_action 16:29:40 INFO - Running main action method: install 16:29:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:41 INFO - Reading from file tmpfile_stdout 16:29:41 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:41 INFO - Reading from file tmpfile_stdout 16:29:41 INFO - Output received: 16:29:41 INFO - Usage: mozinstall [options] installer 16:29:41 INFO - Options: 16:29:41 INFO - -h, --help show this help message and exit 16:29:41 INFO - -d DEST, --destination=DEST 16:29:41 INFO - Directory to install application into. [default: 16:29:41 INFO - "/builds/slave/test"] 16:29:41 INFO - --app=APP Application being installed. [default: firefox] 16:29:41 INFO - mkdir: /builds/slave/test/build/application 16:29:41 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'] 16:29:41 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 16:29:55 INFO - Reading from file tmpfile_stdout 16:29:55 INFO - Output received: 16:29:55 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:29:55 INFO - Running post-action listener: _resource_record_post_action 16:29:55 INFO - [mozharness: 2017-04-04 23:29:55.594321Z] Finished install step (success) 16:29:55 INFO - [mozharness: 2017-04-04 23:29:55.594502Z] Running run-tests step. 16:29:55 INFO - Running pre-action listener: _resource_record_pre_action 16:29:55 INFO - Running main action method: run_tests 16:29:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:29:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:29:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:29:55 INFO - 2017-04-04 16:29:55.609 screenresolution[1896:60341] starting screenresolution argv=screenresolution get 16:29:55 INFO - 2017-04-04 16:29:55.611 screenresolution[1896:60341] Display 0: 1600x1200x32@60 16:29:55 INFO - 2017-04-04 16:29:55.617 screenresolution[1897:60343] starting screenresolution argv=screenresolution list 16:29:55 INFO - Available Modes on Display 0 16:29:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:29:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:29:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:29:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:29:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:29:55 INFO - Intel Iris: 16:29:55 INFO - Chipset Model: Intel Iris 16:29:55 INFO - Type: GPU 16:29:55 INFO - Bus: Built-In 16:29:55 INFO - VRAM (Dynamic, Max): 1536 MB 16:29:55 INFO - Vendor: Intel (0x8086) 16:29:55 INFO - Device ID: 0x0a2e 16:29:55 INFO - Revision ID: 0x0009 16:29:55 INFO - Displays: 16:29:55 INFO - Display: 16:29:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:29:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:29:55 INFO - Display Serial Number: 1600x1200 60 16:29:55 INFO - Main Display: Yes 16:29:55 INFO - Mirror: Off 16:29:55 INFO - Online: Yes 16:29:55 INFO - Rotation: Supported 16:29:55 INFO - Return code: 0 16:29:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:29:55 INFO - Python 2.7.3 16:29:55 INFO - Return code: 0 16:29:55 INFO - grabbing minidump binary from tooltool 16:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:55 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c051d30>, '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 0x10bf78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c046be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:29:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:29:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:29:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:29:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:29:55 INFO - Return code: 0 16:29:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:29:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:29:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:29:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:29:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:29:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:29:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UHHsNkMJ8/Render', 16:29:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:55 INFO - 'HOME': '/Users/cltbld', 16:29:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:55 INFO - 'LOGNAME': 'cltbld', 16:29:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:29:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:55 INFO - 'MOZ_NO_REMOTE': '1', 16:29:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:29:55 INFO - 'NO_EM_RESTART': '1', 16:29:55 INFO - 'PAGER': '/bin/cat', 16:29:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:55 INFO - 'PWD': '/builds/slave/test', 16:29:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:29:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:55 INFO - 'SHELL': '/bin/bash', 16:29:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JADfRt5KWI/Listeners', 16:29:55 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 16:29:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:55 INFO - 'USER': 'cltbld', 16:29:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:55 INFO - 'XPC_FLAGS': '0x0', 16:29:55 INFO - 'XPC_SERVICE_NAME': '0', 16:29:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:29:56 INFO - mozversion application_buildid: 20170404153430 16:29:56 INFO - mozversion application_changeset: 41323426061e6cd60125b5f0728442faca1cbe60 16:29:56 INFO - mozversion application_display_name: Firefox 16:29:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:29:56 INFO - mozversion application_name: Firefox 16:29:56 INFO - mozversion application_remotingname: firefox 16:29:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:29:56 INFO - mozversion application_vendor: Mozilla 16:29:56 INFO - mozversion application_version: 53.0 16:29:56 INFO - mozversion platform_buildid: 20170404153430 16:29:56 INFO - mozversion platform_changeset: 41323426061e6cd60125b5f0728442faca1cbe60 16:29:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:29:56 INFO - mozversion platform_version: 53.0 16:29:56 INFO - using testdate: 1491348596 16:29:56 INFO - actual date: 1491348596 16:29:56 INFO - starting webserver on 'localhost:65383' 16:29:56 INFO - SUITE-START | Running 2 tests 16:29:56 INFO - TEST-START | dromaeo_css 16:29:56 INFO - operating with platform_type : mac_ 16:29:56 INFO - Initialising browser for dromaeo_css test... 16:29:56 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpfLrN3D/profile http://localhost:65383/getInfo.html) 16:29:59 INFO - PROCESS | 1907 | 2017-04-04 16:29:59.158 plugin-container[1910:60496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 16:29:59 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:29:59 INFO - PROCESS | 1907 | 2017-04-04 16:29:59.159 plugin-container[1910:60496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 16:29:59 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:29:59 INFO - PROCESS | 1907 | 2017-04-04 16:29:59.159 plugin-container[1910:60496] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:29:59 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 16:29:59 INFO - PROCESS | 1907 | colorDepth:24 16:29:59 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 16:29:59 INFO - PROCESS | 1907 | __metrics 16:29:59 ERROR - PROCESS | 1907 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 16:30:00 INFO - TEST-INFO | 1907: exit 0 16:30:00 INFO - Browser initialized. 16:30:00 INFO - Running cycle 1/1 for dromaeo_css test... 16:30:00 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpfLrN3D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:02 INFO - PROCESS | 1912 | 2017-04-04 16:30:02.463 plugin-container[1913:60634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 16:30:02 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:02 INFO - PROCESS | 1912 | 2017-04-04 16:30:02.463 plugin-container[1913:60634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 16:30:02 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:02 INFO - PROCESS | 1912 | 2017-04-04 16:30:02.463 plugin-container[1913:60634] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:12 INFO - PROCESS | 1912 | RSS: Main: 173314048 16:30:12 INFO - PROCESS | 1912 | 16:33:13 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:65383/tests/dromaeo/cssquery-dojo.html (next: http://localhost:65383/tests/dromaeo/cssquery-ext.html) 16:33:15 INFO - PROCESS | 1912 | RSS: Main: 190779392 16:33:15 INFO - PROCESS | 1912 | 16:36:18 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:65383/tests/dromaeo/cssquery-ext.html (next: http://localhost:65383/tests/dromaeo/cssquery-jquery.html) 16:36:18 INFO - PROCESS | 1912 | RSS: Main: 200351744 16:36:18 INFO - PROCESS | 1912 | 16:39:20 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:65383/tests/dromaeo/cssquery-jquery.html (next: http://localhost:65383/tests/dromaeo/cssquery-mootools.html) 16:39:21 INFO - PROCESS | 1912 | RSS: Main: 203902976 16:39:21 INFO - PROCESS | 1912 | 16:42:22 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:65383/tests/dromaeo/cssquery-mootools.html (next: http://localhost:65383/tests/dromaeo/cssquery-prototype.html) 16:42:23 INFO - PROCESS | 1912 | RSS: Main: 206954496 16:42:23 INFO - PROCESS | 1912 | 16:45:26 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:65383/tests/dromaeo/cssquery-prototype.html (next: http://localhost:65383/tests/dromaeo/cssquery-yui.html) 16:45:27 INFO - PROCESS | 1912 | RSS: Main: 210079744 16:45:27 INFO - PROCESS | 1912 | 16:48:29 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:65383/tests/dromaeo/cssquery-yui.html (next: http://localhost:65383/tests/dromaeo/cssquery-dojo.html) 16:48:30 INFO - PROCESS | 1912 | RSS: Main: 210161664 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - PROCESS | 1912 | __start_tp_report 16:48:30 INFO - PROCESS | 1912 | _x_x_mozilla_page_load 16:48:30 INFO - PROCESS | 1912 | _x_x_mozilla_page_load_details 16:48:30 INFO - PROCESS | 1912 | |i|pagename|runs| 16:48:30 INFO - PROCESS | 1912 | |0;dojo.html;3419.846106925188;3442.2254992626654;3456.5506484157054;3464.8787292444763;3485.7734247273943;4897.191963326047;5146.198830409359;5647.237622920905;5673.943260567393;5729.512991395726;5693.572982026344;5755.769769209227;5762.769489220426;5789.131630255453;5825.708714564276;5177.663451875635;5231.11071117909;5265.341832270966;5268.92096618551;5317.441668624797;5287.735613219343;5303.416624171338;5329.307190065286;5341.973290133544;5419.783208671648;5213.2701421800975;5247.501487358714;5321.52106310432;5326.2543243945875;5344.465553444644;4961.875953101166;4975.353204083446;5296.311479507659;5331.546818520431;5390.838274851761;5188.066148093341;5286.920696189561;5295.152775555931;5301;5353.732313384354;5484.890302193934;5608.635438696471;5727.198192253088;5762.510186634141;5878;6157.322694503601;6167.414095660905;6173.444390004921;6217.751289948396;6265.185525881606;5233.816816411445;6178.691065446755;6194.287656919467;6234.127222188898;6300.590461620026;5991.131285963555;5992.221011268507;5996.5202783777195;6005.459508644243;6006.969965150146;6137.171481849982;6147.569670123138;6156.415140561639;6168.19813424252;6175.44421002112;6128.295246046672;6136.059583474799;6137.989923150331;6157.96921015401;6188.319284878659;6140.600860944024;6182.505399568024;6227.003679411363;6229.7508099676415;6238.844028899223;5106.514881086291;5235.973820130875;6122.846928826726;6123.5876691309595;6168.938310616926;4947.985662939161;5878.323992740804;6202.782902598387;6232.906506402408;6262.6868656566985;4943.678660887031;4985.351904252424;5002.324686167321;5032.547070763576;5056.26684130799;4033.8789836304372;4247.426597409372;4399.714018588781;4519.276915693474;4522.5025247222775;5739.368669446358;5821.330546987066;5841.591088623755;5852.502537284378;5899.380565040609;6191.659458729723;6260.092286618415;6264.373562643699;6275.96862015687;6278.152449419361;4796.640251981115;5641.097424412074;5814.825555233349;5874.765009399576;5879.970600146972;4534.843614878185;4967.254911763264;5485.780568777204;5488.121900495901;5501.339839219319;5278.709670968134;6323.430891219812;6339.302676705559;6347.333529979378;6353.301136875032;6144.863200307957;6178.969105154445;6183.5671502994355;6204.658743768956;6262.937370626418;6297.307296197323;6304.495640348853;6319.178506794087;6322.715477803602;6344.777932772331;6162.845928851814;6197.81406557804;6247.375262473716;6276.560640755103;6286.7799627014665;6165.321814600301;6172.506199503939;6211.223597050369;6288.937110629018;6290.591111577733;6048.092786082123;6166.475849552839;6171.84570385726;6172.043333283348;6212.192414986023;6138.5396095292135;6140.048292514667;6146.139540464249;6153.446189842935;6183.134361189347;5893.793717219876;5907.822765317047;5916.763329466773;6040.456358927718;6100.481459076028;4972.8756781080765;5921.3190483094995;6121.204243448323;6159.599626024294;6175.59858609189;6006.879862402815;6018.8796224074395;6035.336113027652;6058.394160583906;6063.454289114001 16:48:30 INFO - PROCESS | 1912 | |1;ext.html;454.39111590468775;466.70131116085713;540.0629907111164;546.7348336057013;557.3618207152813;40234.396484052726;43162.78418607903;43289.56710432895;43308.783456082754;43361.56638433618;45339.54660453394;45558.77220613892;46013.07973840521;46081.76959115208;46195;9141.177294043535;9328.906710932906;9621.855672164924;9859.35914165577;9878.209743220525;11999.460024298927;12255.571055013077;12278.38608069593;12448.004159667205;12461.06542009353;12083.93958030213;12105.455254513525;12145.45345459452;12147.93926030373;12198.963088137474;12080.697982550506;12093.758124837497;12113.93943030288;12162.756744865053;12169.087318451151;12060.276383416935;12103.152779305452;12113;12127.757444851142;12143.878561214364;44049.55950440487;44153;44360.334594981105;44496.3325550117;44539.554604453864;4113.403556484293;4144.647704945083;4156;4203.810828512725;4222.535521092677;31388.21529461759;31421.8428907854;31426;33432.66567334344;33524.49713254303;3279.672032796725;3493.737969652286;3509.8069606171653;3511.4206155984234;3511.8235391144;378426;381376;382617;383398.083009586;386171;57451.71274143686;69919.65040174767;70334;70610;70947.64526177336;65339.67330163414;70179.94730079053;72982;74029.25970740331;74320.62839685768;15642.608934776721;18067.909660451613;18187.63624727498;18447.89312641246;18509.7223541647;12718.427670754652;12981.545645902537;13507.864921350661;13768.65578360549;13866.930665346608;17381.304747810198;18570.628587428175;19451.227986578582;19485.51286217856;19514.609707805765;7492.325690687864;7667.731629393056;8110.229528194812;8112.59437028158;8181.468204566803;805.8186907945666;851.9616617252238;857.9532969776863;888.2139306713605;920.4523308631353;32148;32197.839010804797;32307;32432;32531.674683252866;1063.7500187455942;1117.80997230471;1144.2047776795214;1151.407025381929;1228.2016689152122;14869.347059498172;16232.918835405748;16463.176841158132;16492.587685307724;17349.95900245989;623924;672082;683341;684496;879830.6008469916;58776.70611646914;59619;59659;59673.40326596679;59863;1482.6367539952564;1483.4881965721809;1505.5408100529444;1512.138081293652;1513.9470118545798;29265;30695.2326191847;31039.400519775194;31143;31219.84390078035;50569.74715126548;50828.74585627196;51140;51295.97408051893;51400;546601.2669936625;554670;556172;558211;559180;26628.66856657198;26649.312455289444;26683.599746003438;26957.460850783267;27019.324516887234;38862.4170637435;39814.80092599519;45628;45737.77131114323;46447;31369.8431507841;31828.68171318257;32008.83995580007;32656.51015234726;32820.83589582037;31685;32102.35795284128;32160.035198944068;32900.74885771792;33170.50244246387;36616.81691591525;37091.88724338274;37231.6276837239;38110.428343575426;38115.80942095272;29124.7087529122;31919.521207181446;31939.520907185946;32163.35673286568;32783.672163279014;23983.880080599487;24048.278551643478;25270.87364563239;25739.742602574483;25816.483670326113 16:48:30 INFO - PROCESS | 1912 | |2;jquery.html;334.83760376217515;359.6942598791028;404.8178319756109;428.50721670079423;430.8965848196432;17618.559536011573;19843.40469785907;20898.686519702216;21093.68359474607;21341.466463338424;17880.64238715225;20632;21479.785202148014;21720;21831;5863.677497737623;5879.7648094076185;6018.8194354169445;6935.306469353075;7009.158900931895;4540.455145382559;7016.473764467685;7056.223815380304;7061.894071588905;7065.6113913734725;6231.127642130105;6321.5890967087225;6349.111124442582;6351.9682401587925;6356.713947872334;6108.694565271719;6233.532485063638;6284.308726040132;6322.873542529147;6355.332690067523;5196.350456192976;5211.687298762086;6157.199564056688;6201.9689901550655;6221.253449586034;12110;13150.408231629599;13616.523421680293;13686.247256400893;14060.578182654537;2616.306678730138;2693.784497240236;2699.7300269973043;2702.04077552468;2714.309227753597;10309.53607087683;10332.793344133075;10818.945905270502;13499;15159.924200378928;1878.3238034307637;1878.5209771508178;1879.0604697651174;1887.1979408751363;1898.3071179019696;132082;133736.33131834376;137861.31069344588;149015;154711.45288547082;10083.495825208856;11005.669829905115;12350.876491235153;12368.257904525757;12765.361731913554;12849.550265740654;15898.682026359407;16046.438374656831;16113.355465781471;16148.919255403647;7433.628318584049;9103.817923641622;9167.587458564381;9190.540472976325;9277.072292770668;9026.954865225633;9090.090990900857;9104;9257.861132083024;9308.720738377859;4136.524299705512;4747.287906813936;4790.473047964721;4848.321235027099;4861.756912154449;1728.5937804615905;2080.074366906712;2106.789321067881;2118.1739121742535;2120.544083022158;798.3373799746197;803.3292201012104;883.1477624092781;900.3562452846115;932.9906700932904;21367.35897923065;21728.130874764833;21801.345959621238;21832.345029649136;22095.558088838134;930.7952250504878;940.586142097475;958.6644674364056;960.0831206198204;975.0639386189196;10929.180311476672;11357.97778199966;11439.828402573969;11488.540458381738;11548.133889958286;669013;680658.7901181487;694554;701449;712882;110824.44587777171;114305;151482.24258878635;152182.23908880606;157772;1082.2045796339685;1103.9558417663204;1121.831725241204;1127.8928501792316;1131.643532287327;19521.414357569294;19535.511612209808;20558;20853.061612227182;21707.457313566654;62224;62664;63024;63264.68367658132;63658;88540.1145988532;89261;91152.54423727839;91901;92947;6514.446272066927;8317.792055198668;8658.956705216433;8732.214100730966;8844.601992910206;12258.509659613515;13246.139000964906;13427.530036448677;13532.59402217935;13693;11255.437228138397;11786.882131178578;11808.291502509877;11860.703482413008;11892.583759568373;10570.524326405177;10723.839142413026;10918.01737843598;11131.276467029616;11201.887981120084;10840.945795270973;10953.507092180711;11851.407429628312;11913.404329783303;12052.216605920587;7373.152087510005;7508;7691.230876912264;7872.921270787218;8619.094995025684;10098.646547370807;11522;11538.480768365276;11694;11919.284842909454 16:48:30 INFO - PROCESS | 1912 | |3;mootools.html;1484.6140003599062;1508.464495104238;1511.1915125407902;1512.4026009726158;1522.7335216337142;7587.127480339763;7871.8425631487335;8243.134470880561;8657;8669.653213871454;6456.741730330793;8441.155884411555;8465.492070475775;8557.614907329169;8568.65725370986;2401.363638635763;2426.6845310109748;2429.0648100481267;2434.987825060872;2438.756124387565;2410.710714714237;2434.6469761884514;2449.289705985262;2460.0036985021097;2463.9897641966795;2371.95256094878;2392.1507864708037;2400.147947478646;2408.95182096358;2424.8423852449623;2189.861272138479;2359.4101474631343;2363.1492662641435;2378.131981826638;2390.4501964548163;1911.5221194701326;2022.4943764058985;2022.9480670051637;2416.8066554675584;2427.320350301918;8444.957775211145;8566.143385661446;8587.270082043035;8596.183362580545;8629.784255393603;5258.737063146866;5402;5414.675119492843;5419.945800541984;5446.91829622556;3145.820317380982;3625.728070394731;3634.872779452733;3655.2872189922978;3728.478013078144;1262.1480500662017;1262.7158889249938;1288.6778305423645;1319.8350206224222;1320.7754681704037;10997.560097596086;11015.504302306415;11025.83461248082;11036.613718519893;11044.779104417947;4731.716097034189;4792.664513484093;4820.517948205152;4827.517248275215;4914.72968986702;9750.853737193947;9756.90243097574;9769;9791.461469619097;9810.313278070467;5361.195820626859;5452.890942181134;5466.1527463243265;5475.561955043667;5530.889382212414;4394.12117576486;4646.395968524071;4906.215005599159;4965.726885021286;4998.675086119463;3943.093088589583;4007.458993035961;4116.835326586903;4155.812988415529;4168.145530166309;1127.5264388956657;1311.265691212924;1328.1698938163647;1328.335832083958;1336.5522549945683;863.8963324400986;936.1434287626881;970.7379007668031;970.7670159161892;1000.8798944126752;5685.630434021776;5792.8551786204835;5794.478496935296;6022.939770602326;6131.570790044743;969.1120263835799;994.4983075555963;996.8056229035413;1013.3261381181579;1050.2123407444417;3305.9008229753144;3796.924061518754;3930.0960779020984;3998;4042.171354872244;9958;10150.543225554868;10355.964403559734;10359.689209323733;10392.792144157074;10287.845682314772;10289.3311934724;10310.639127630497;10313.27807053514;10331.483425828828;7395.630218489162;7836.216378362232;7983.960080199562;8011.519308841488;8113.716019939274;5925.822225333247;5964.224650795369;6008.068749343859;6013.5189184865985;6108.114323423168;9387.10822471864;9440.05599440078;9499.857502137334;9638.373505722106;9692.9030709692;9236.953815230881;9468.100530449596;9525.428474291564;9688.224942004763;9879.901200987899;7282.417406607565;7448.217937116525;7509.211532788979;7518.210587888193;7519.73680921187;8070.636821342936;8073.5963201838495;8115.391345648982;8127.79680507992;8170.387220958571;7139;7184.892226616709;7247.456440766858;7306.305900939402;7316.780496585111;6181.196444462191;6742.898856517058;6954.930450695429;7055;7092.787216383516;6502.967485162544;6611.239707433707;6621.238557565942;6632.966835165793;6639.7012134455035;5012.924806127838;5069.467705890976;5132.945897191165;5204.869878253074;5228.686278823283;4907.312976183409;4978.352814134139;5056.140456122542;5165.483451654804;5289.127293996601 16:48:30 INFO - PROCESS | 1912 | |4;prototype.html;367.69297636473544;373.5498724037534;381.44118865861515;385.97877116758565;387.76733959624227;4466.709663871846;5349.625526213166;5497.477739614736;5502.972485137574;5624.550035997116;4536.863894083174;5165.431802501722;5272.393674727407;5275.472452754722;5415.83752487425;4233.555476674951;4344.89137771555;4363.4327537420095;4364.454443194601;4365.297533961758;4153.172604054222;4348;4366.257736184841;4401.36180253863;4412.713173643719;3893.0072831427947;3928.2732694451474;3987.9601203988022;4272.87181384559;4288.442502474674;3954.8813535593977;4249.298865687173;4250.426192464009;4299.419578356913;4302.7848607569495;3201.167696398953;3834.181876320902;3835.6000059978187;3846.442265871461;3858.961410385916;4415.558444155558;4431.49037860647;5024.62315326352;5175.870603234912;5245.763940622681;4105.80931529856;4594.770261486946;4739.857804265877;4745.288206768978;4784.808607655689;4981.850544483671;5385.5960802939935;5762.481376676119;5762.538996880239;5786.276715410574;3831.0613899594778;3883.2427676603074;3904.414337849317;3931.9803400983096;3964.405339199114;5358.678479291255;5819.476247137735;5984.820455386345;6024.909626355652;6049.818505444844;3396.524486571882;4003.679705623543;4031.6090948622677;4050.837966481308;4053.6149065839286;3831.942520862189;4031.065088757413;4045.5347635021762;4074.348104303297;4083.734557253769;3923.6468717815533;4485.865424037284;4673.672842901033;4707.929381059287;4729.408823897013;5778.508826749689;5783.681897495594;5801.622894511843;5881.500217444524;5887.852803679856;1059.3961441978;1235.529719633633;1312.3569450968957;1318.5187406596715;1321.6299436157892;750.9436792240604;773.7910764093775;874.9212570868525;892.8794612727198;913.92613678927;4641.537915556589;4943.90112197754;5172.456892026358;5180.974095129575;5185.585153187736;4664.760914760934;5349.732513374316;5554.334578729754;5686.488216060575;5701.432106170753;4581.983927072144;4896.779644915988;4937.802487900443;5185.248139019821;5195.7142357170715;2933.9559906601417;2937.4418860416454;2984.388200418909;2993.536001919706;2994.31130839908;5942.673152976541;5957.404259574095;5973.880522389528;6016.458518733335;6026.6986650667295;4730.09766942274;5059.924101138412;5478.123500240023;5755.4460295720755;5835.970820145957;5305.469453054664;5818.883622327529;5855.2973643163095;5898.587098903035;5934.902043122036;5083.694978301313;5471.288732464914;5715.742791574306;5725.570582206268;5748.228969955011;5022.673526220784;5552.972235138798;5607.80372686954;5613.298337707786;5649.943500564942;5467.555440072729;5852.531797456279;5880.7356418370255;5943.554233432424;5988.233471126116;3626.474161246552;3673.669369756735;3770.7549009314307;3805.1818858945458;3826.9525803696;4875.6099512038245;4954.083494553519;5339.199120131856;5403.189521571796;5661.952538780339;5147.2279158126585;5442.6462279951675;5537.584681148849;5547.833564993057;5680;5486.945130548644;5658.377578466283;5751.281089863767;5778.855528611818;5789.768409263583;5522.530584900329;5642.746076426489;5658.830235092953;5670.688112153871;5687.829365119053;4734.360861283821;5339.652922560022;5692.5730570208225;5736.4263573643975;5809.79665711715;5640.73083556213;5658.830235092953;5674.779203133045;5779.295107943083;5783.421657834351 16:48:30 INFO - PROCESS | 1912 | |5;yui.html;650.4048795352253;771.9768406947793;778.2023425988363;784.8077221080835;814.7881550796791;12552.623421297352;16396.590085247874;16398.590035249126;16418.671626567462;16423.34306627735;14923.955323127386;15023.699526009474;15029.72247358973;15032.323545440455;15064.548063558083;700.6881937537797;716.8387112899608;846.9745907622781;863.4689665855485;873.8907636545432;825.6119623776815;878.4773060029272;892.9062448442918;895.677556079814;897.7979954510248;912.6851236323491;935.8081593273397;936.7377134402378;940.2759874896326;943.0333907744582;927.656766996209;928.2342067794096;930.4975313330793;933.3000249812641;941.8634298026748;685.4379408884716;693.6566399632164;694.3924066441863;694.9617771022591;695.0408436357794;15449.304781284756;15856.762148567781;15953.282102305307;16534.6693066138;16785.832141678547;14638.82889368848;15055.473058442902;15135.621609459742;15233.923830380778;15286.694266114726;10811.783764324748;11846.526138954432;11993;12066.276023438622;12282.570110046196;3801.2967600993907;3913.6477717005323;3957.4657421248053;4069.97965010176;4085.816138273785;109505;111074;111595.44202279017;112431;112831.87168128295;11577;11588.420578971187;11798.233114847679;11832.467538960767;11861.822072668745;24264.029438822603;24796.884140213733;24868.502629947663;24897.004119835365;24936.625950610756;7071.752488662975;8120.6345714443;8135.511869287861;8200.671973121127;8286.668533258722;4366.433663987873;4396.560343965578;4523.457185137804;4613.653975951817;4705.1765940960195;3746.8875933722034;3790.639889210521;3807.5430948286385;3853.094522787143;3855.8264878080554;621.8973869311513;663.9468842492589;689.5586824432368;692.9618870962045;700.2927043685812;687.8968154776824;699.2937133495104;712.4478529139855;715.7602203261862;761.1969372312153;10413.843792343121;10637.574497020032;10734.355938643706;10854.34873907568;10977.39624320654;696.6481926627122;712.8574285142992;741.7366834773685;743.6727839750492;745.3478206569251;4983.850484485399;5167.896642067138;5168.173092305288;5169.276301317818;5197.922031169535;104974.95025049805;106745.46627266814;108920;110163.34754978682;113336.86663133428;85199;88848.11151888527;90583;91237.08762912419;92122;82287;82692.58653706693;82926.58536707278;83241.16758832334;86400.1359986392;10065.496725163859;10107.494625268855;10150.644727434505;10151.593936242467;10154.644587439405;38137.80931095327;44782.55217447784;47342;48315.27527087026;56267;64624.67687661532;68300.97548536875;68739;69661.65169174122;70974.64512677403;11343.376114313462;11351.716207094889;11364.090872729996;11592.246503977214;14314.713705725619;12774.552890649155;15270.541883743506;15460.9226953869;15491.07053576789;15684.529464116094;13562.254076025913;14324.997250192386;14405.135691858522;14432.680483488646;14458.783118253024;13661.795073073714;13952.511662091778;14437.566872993826;14615.196164211071;14953.32710028073;13518.526851560147;13981.44074237019;14337.810281268694;14566.635834104232;14766.228052633753;9920.801583968425;10095.108897459742;10390.948045260027;10428.27002109845;10577.153827693919;7444.553326800409;10617.203709721647;11342.489587968395;11461.484233209692;11596.304221746723 16:48:30 INFO - PROCESS | 1912 | __end_tp_report 16:48:30 INFO - PROCESS | 1912 | __start_cc_report 16:48:30 INFO - PROCESS | 1912 | _x_x_mozilla_cycle_collect,370 16:48:30 INFO - PROCESS | 1912 | __end_cc_report 16:48:30 INFO - PROCESS | 1912 | __startTimestamp1491349710196__endTimestamp 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - PROCESS | 1912 | ------- Summary: start ------- 16:48:30 INFO - PROCESS | 1912 | Number of tests: 6 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - PROCESS | 1912 | [#0] dojo.html Cycles:165 Average:5714.91 Median:5992.22 stddev:646.18 (10.8%) stddev-sans-first:622.57 16:48:30 INFO - PROCESS | 1912 | Values: 3419.8 3442.2 3456.6 3464.9 3485.8 4897.2 5146.2 5647.2 5673.9 5729.5 5693.6 5755.8 5762.8 5789.1 5825.7 5177.7 5231.1 5265.3 5268.9 5317.4 5287.7 5303.4 5329.3 5342.0 5419.8 5213.3 5247.5 5321.5 5326.3 5344.5 4961.9 4975.4 5296.3 5331.5 5390.8 5188.1 5286.9 5295.2 5301.0 5353.7 5484.9 5608.6 5727.2 5762.5 5878.0 6157.3 6167.4 6173.4 6217.8 6265.2 5233.8 6178.7 6194.3 6234.1 6300.6 5991.1 5992.2 5996.5 6005.5 6007.0 6137.2 6147.6 6156.4 6168.2 6175.4 6128.3 6136.1 6138.0 6158.0 6188.3 6140.6 6182.5 6227.0 6229.8 6238.8 5106.5 5236.0 6122.8 6123.6 6168.9 4948.0 5878.3 6202.8 6232.9 6262.7 4943.7 4985.4 5002.3 5032.5 5056.3 4033.9 4247.4 4399.7 4519.3 4522.5 5739.4 5821.3 5841.6 5852.5 5899.4 6191.7 6260.1 6264.4 6276.0 6278.2 4796.6 5641.1 5814.8 5874.8 5880.0 4534.8 4967.3 5485.8 5488.1 5501.3 5278.7 6323.4 6339.3 6347.3 6353.3 6144.9 6179.0 6183.6 6204.7 6262.9 6297.3 6304.5 6319.2 6322.7 6344.8 6162.8 6197.8 6247.4 6276.6 6286.8 6165.3 6172.5 6211.2 6288.9 6290.6 6048.1 6166.5 6171.8 6172.0 6212.2 6138.5 6140.0 6146.1 6153.4 6183.1 5893.8 5907.8 5916.8 6040.5 6100.5 4972.9 5921.3 6121.2 6159.6 6175.6 6006.9 6018.9 6035.3 6058.4 6063.5 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - PROCESS | 1912 | [#1] ext.html Cycles:180 Average:69351.54 Median:18509.12 stddev:151861.66 (820.5%) stddev-sans-first:152199.57 16:48:30 INFO - PROCESS | 1912 | Values: 454.4 466.7 540.1 546.7 557.4 40234.4 43162.8 43289.6 43308.8 43361.6 45339.5 45558.8 46013.1 46081.8 46195.0 9141.2 9328.9 9621.9 9859.4 9878.2 11999.5 12255.6 12278.4 12448.0 12461.1 12083.9 12105.5 12145.5 12147.9 12199.0 12080.7 12093.8 12113.9 12162.8 12169.1 12060.3 12103.2 12113.0 12127.8 12143.9 44049.6 44153.0 44360.3 44496.3 44539.6 4113.4 4144.6 4156.0 4203.8 4222.5 31388.2 31421.8 31426.0 33432.7 33524.5 3279.7 3493.7 3509.8 3511.4 3511.8 378426.0 381376.0 382617.0 383398.1 386171.0 57451.7 69919.7 70334.0 70610.0 70947.6 65339.7 70179.9 72982.0 74029.3 74320.6 15642.6 18067.9 18187.6 18447.9 18509.7 12718.4 12981.5 13507.9 13768.7 13866.9 17381.3 18570.6 19451.2 19485.5 19514.6 7492.3 7667.7 8110.2 8112.6 8181.5 805.8 852.0 858.0 888.2 920.5 32148.0 32197.8 32307.0 32432.0 32531.7 1063.8 1117.8 1144.2 1151.4 1228.2 14869.3 16232.9 16463.2 16492.6 17350.0 623924.0 672082.0 683341.0 684496.0 879830.6 58776.7 59619.0 59659.0 59673.4 59863.0 1482.6 1483.5 1505.5 1512.1 1513.9 29265.0 30695.2 31039.4 31143.0 31219.8 50569.7 50828.7 51140.0 51296.0 51400.0 546601.3 554670.0 556172.0 558211.0 559180.0 26628.7 26649.3 26683.6 26957.5 27019.3 38862.4 39814.8 45628.0 45737.8 46447.0 31369.8 31828.7 32008.8 32656.5 32820.8 31685.0 32102.4 32160.0 32900.7 33170.5 36616.8 37091.9 37231.6 38110.4 38115.8 29124.7 31919.5 31939.5 32163.4 32783.7 23983.9 24048.3 25270.9 25739.7 25816.5 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - PROCESS | 1912 | [#2] jquery.html Cycles:180 Average:39159.85 Median:21764.74 stddev:115653.83 (531.4%) stddev-sans-first:115941.53 16:48:30 INFO - PROCESS | 1912 | Values: 334.8 359.7 404.8 428.5 430.9 17618.6 19843.4 20898.7 21093.7 21341.5 17880.6 20632.0 21479.8 21720.0 21831.0 5863.7 5879.8 6018.8 6935.3 7009.2 4540.5 7016.5 7056.2 7061.9 7065.6 6231.1 6321.6 6349.1 6352.0 6356.7 6108.7 6233.5 6284.3 6322.9 6355.3 5196.4 5211.7 6157.2 6202.0 6221.3 12110.0 13150.4 13616.5 13686.2 14060.6 2616.3 2693.8 2699.7 2702.0 2714.3 10309.5 10332.8 10818.9 13499.0 15159.9 1878.3 1878.5 1879.1 1887.2 1898.3 132082.0 133736.3 137861.3 149015.0 154711.5 10083.5 11005.7 12350.9 12368.3 12765.4 12849.6 15898.7 16046.4 16113.4 16148.9 7433.6 9103.8 9167.6 9190.5 9277.1 9027.0 9090.1 9104.0 9257.9 9308.7 4136.5 4747.3 4790.5 4848.3 4861.8 1728.6 2080.1 2106.8 2118.2 2120.5 798.3 803.3 883.1 900.4 933.0 21367.4 21728.1 21801.3 21832.3 22095.6 930.8 940.6 958.7 960.1 975.1 10929.2 11358.0 11439.8 11488.5 11548.1 669013.0 680658.8 694554.0 701449.0 712882.0 110824.4 114305.0 151482.2 152182.2 157772.0 1082.2 1104.0 1121.8 1127.9 1131.6 19521.4 19535.5 20558.0 20853.1 21707.5 62224.0 62664.0 63024.0 63264.7 63658.0 88540.1 89261.0 91152.5 91901.0 92947.0 6514.4 8317.8 8659.0 8732.2 8844.6 12258.5 13246.1 13427.5 13532.6 13693.0 11255.4 11786.9 11808.3 11860.7 11892.6 10570.5 10723.8 10918.0 11131.3 11201.9 10840.9 10953.5 11851.4 11913.4 12052.2 7373.2 7508.0 7691.2 7872.9 8619.1 10098.6 11522.0 11538.5 11694.0 11919.3 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - PROCESS | 1912 | [#3] mootools.html Cycles:180 Average:5588.61 Median:5243.71 stddev:3018.58 (57.6%) stddev-sans-first:3011.29 16:48:30 INFO - PROCESS | 1912 | Values: 1484.6 1508.5 1511.2 1512.4 1522.7 7587.1 7871.8 8243.1 8657.0 8669.7 6456.7 8441.2 8465.5 8557.6 8568.7 2401.4 2426.7 2429.1 2435.0 2438.8 2410.7 2434.6 2449.3 2460.0 2464.0 2372.0 2392.2 2400.1 2409.0 2424.8 2189.9 2359.4 2363.1 2378.1 2390.5 1911.5 2022.5 2022.9 2416.8 2427.3 8445.0 8566.1 8587.3 8596.2 8629.8 5258.7 5402.0 5414.7 5419.9 5446.9 3145.8 3625.7 3634.9 3655.3 3728.5 1262.1 1262.7 1288.7 1319.8 1320.8 10997.6 11015.5 11025.8 11036.6 11044.8 4731.7 4792.7 4820.5 4827.5 4914.7 9750.9 9756.9 9769.0 9791.5 9810.3 5361.2 5452.9 5466.2 5475.6 5530.9 4394.1 4646.4 4906.2 4965.7 4998.7 3943.1 4007.5 4116.8 4155.8 4168.1 1127.5 1311.3 1328.2 1328.3 1336.6 863.9 936.1 970.7 970.8 1000.9 5685.6 5792.9 5794.5 6022.9 6131.6 969.1 994.5 996.8 1013.3 1050.2 3305.9 3796.9 3930.1 3998.0 4042.2 9958.0 10150.5 10356.0 10359.7 10392.8 10287.8 10289.3 10310.6 10313.3 10331.5 7395.6 7836.2 7984.0 8011.5 8113.7 5925.8 5964.2 6008.1 6013.5 6108.1 9387.1 9440.1 9499.9 9638.4 9692.9 9237.0 9468.1 9525.4 9688.2 9879.9 7282.4 7448.2 7509.2 7518.2 7519.7 8070.6 8073.6 8115.4 8127.8 8170.4 7139.0 7184.9 7247.5 7306.3 7316.8 6181.2 6742.9 6954.9 7055.0 7092.8 6503.0 6611.2 6621.2 6633.0 6639.7 5012.9 5069.5 5132.9 5204.9 5228.7 4907.3 4978.4 5056.1 5165.5 5289.1 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - PROCESS | 1912 | [#4] prototype.html Cycles:180 Average:4606.63 Median:5168.94 stddev:1391.11 (26.9%) stddev-sans-first:1358.15 16:48:30 INFO - PROCESS | 1912 | Values: 367.7 373.5 381.4 386.0 387.8 4466.7 5349.6 5497.5 5503.0 5624.6 4536.9 5165.4 5272.4 5275.5 5415.8 4233.6 4344.9 4363.4 4364.5 4365.3 4153.2 4348.0 4366.3 4401.4 4412.7 3893.0 3928.3 3988.0 4272.9 4288.4 3954.9 4249.3 4250.4 4299.4 4302.8 3201.2 3834.2 3835.6 3846.4 3859.0 4415.6 4431.5 5024.6 5175.9 5245.8 4105.8 4594.8 4739.9 4745.3 4784.8 4981.9 5385.6 5762.5 5762.5 5786.3 3831.1 3883.2 3904.4 3932.0 3964.4 5358.7 5819.5 5984.8 6024.9 6049.8 3396.5 4003.7 4031.6 4050.8 4053.6 3831.9 4031.1 4045.5 4074.3 4083.7 3923.6 4485.9 4673.7 4707.9 4729.4 5778.5 5783.7 5801.6 5881.5 5887.9 1059.4 1235.5 1312.4 1318.5 1321.6 750.9 773.8 874.9 892.9 913.9 4641.5 4943.9 5172.5 5181.0 5185.6 4664.8 5349.7 5554.3 5686.5 5701.4 4582.0 4896.8 4937.8 5185.2 5195.7 2934.0 2937.4 2984.4 2993.5 2994.3 5942.7 5957.4 5973.9 6016.5 6026.7 4730.1 5059.9 5478.1 5755.4 5836.0 5305.5 5818.9 5855.3 5898.6 5934.9 5083.7 5471.3 5715.7 5725.6 5748.2 5022.7 5553.0 5607.8 5613.3 5649.9 5467.6 5852.5 5880.7 5943.6 5988.2 3626.5 3673.7 3770.8 3805.2 3827.0 4875.6 4954.1 5339.2 5403.2 5662.0 5147.2 5442.6 5537.6 5547.8 5680.0 5486.9 5658.4 5751.3 5778.9 5789.8 5522.5 5642.7 5658.8 5670.7 5687.8 4734.4 5339.7 5692.6 5736.4 5809.8 5640.7 5658.8 5674.8 5779.3 5783.4 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - PROCESS | 1912 | [#5] yui.html Cycles:180 Average:21189.90 Median:3804.42 stddev:30577.23 (803.7%) stddev-sans-first:30624.12 16:48:30 INFO - PROCESS | 1912 | Values: 650.4 772.0 778.2 784.8 814.8 12552.6 16396.6 16398.6 16418.7 16423.3 14924.0 15023.7 15029.7 15032.3 15064.5 700.7 716.8 847.0 863.5 873.9 825.6 878.5 892.9 895.7 897.8 912.7 935.8 936.7 940.3 943.0 927.7 928.2 930.5 933.3 941.9 685.4 693.7 694.4 695.0 695.0 15449.3 15856.8 15953.3 16534.7 16785.8 14638.8 15055.5 15135.6 15233.9 15286.7 10811.8 11846.5 11993.0 12066.3 12282.6 3801.3 3913.6 3957.5 4070.0 4085.8 109505.0 111074.0 111595.4 112431.0 112831.9 11577.0 11588.4 11798.2 11832.5 11861.8 24264.0 24796.9 24868.5 24897.0 24936.6 7071.8 8120.6 8135.5 8200.7 8286.7 4366.4 4396.6 4523.5 4613.7 4705.2 3746.9 3790.6 3807.5 3853.1 3855.8 621.9 663.9 689.6 693.0 700.3 687.9 699.3 712.4 715.8 761.2 10413.8 10637.6 10734.4 10854.3 10977.4 696.6 712.9 741.7 743.7 745.3 4983.9 5167.9 5168.2 5169.3 5197.9 104975.0 106745.5 108920.0 110163.3 113336.9 85199.0 88848.1 90583.0 91237.1 92122.0 82287.0 82692.6 82926.6 83241.2 86400.1 10065.5 10107.5 10150.6 10151.6 10154.6 38137.8 44782.6 47342.0 48315.3 56267.0 64624.7 68301.0 68739.0 69661.7 70974.6 11343.4 11351.7 11364.1 11592.2 14314.7 12774.6 15270.5 15460.9 15491.1 15684.5 13562.3 14325.0 14405.1 14432.7 14458.8 13661.8 13952.5 14437.6 14615.2 14953.3 13518.5 13981.4 14337.8 14566.6 14766.2 9920.8 10095.1 10390.9 10428.3 10577.2 7444.6 10617.2 11342.5 11461.5 11596.3 16:48:30 INFO - PROCESS | 1912 | -------- Summary: end -------- 16:48:30 INFO - PROCESS | 1912 | 16:48:30 INFO - TEST-INFO | 1912: exit 0 16:48:30 INFO - TEST-OK | dromaeo_css | took 1114593ms 16:48:30 INFO - TEST-START | kraken 16:48:30 INFO - operating with platform_type : mac_ 16:48:30 INFO - Initialising browser for kraken test... 16:48:30 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpSV3IfJ/profile http://localhost:65383/getInfo.html) 16:48:33 INFO - PROCESS | 1916 | 2017-04-04 16:48:33.507 plugin-container[1918:62447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.tsm.portname' 16:48:33 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:33 INFO - PROCESS | 1916 | 2017-04-04 16:48:33.508 plugin-container[1918:62447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 16:48:33 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:33 INFO - PROCESS | 1916 | 2017-04-04 16:48:33.508 plugin-container[1918:62447] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:48:33 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 16:48:33 INFO - PROCESS | 1916 | colorDepth:24 16:48:33 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 16:48:33 INFO - PROCESS | 1916 | __metrics 16:48:33 ERROR - PROCESS | 1916 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 16:48:34 INFO - TEST-INFO | 1916: exit 0 16:48:34 INFO - Browser initialized. 16:48:34 INFO - Running cycle 1/1 for kraken test... 16:48:34 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpSV3IfJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:36 INFO - PROCESS | 1919 | 2017-04-04 16:48:36.727 plugin-container[1920:62576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x842f, name = 'com.apple.tsm.portname' 16:48:36 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:36 INFO - PROCESS | 1919 | 2017-04-04 16:48:36.728 plugin-container[1920:62576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8717, name = 'com.apple.CFPasteboardClient' 16:48:36 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:36 INFO - PROCESS | 1919 | 2017-04-04 16:48:36.728 plugin-container[1920:62576] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:48:46 INFO - PROCESS | 1919 | RSS: Main: 173133824 16:48:46 INFO - PROCESS | 1919 | 16:48:49 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:65383/tests/kraken/driver.html?testName=audio-beat-detection) 16:48:50 INFO - PROCESS | 1919 | RSS: Main: 186032128 16:48:50 INFO - PROCESS | 1919 | 16:48:52 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:65383/tests/kraken/driver.html?testName=audio-dft) 16:48:53 INFO - PROCESS | 1919 | RSS: Main: 193744896 16:48:53 INFO - PROCESS | 1919 | 16:48:56 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:65383/tests/kraken/driver.html?testName=audio-fft) 16:48:57 INFO - PROCESS | 1919 | RSS: Main: 197439488 16:48:57 INFO - PROCESS | 1919 | 16:48:59 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:65383/tests/kraken/driver.html?testName=audio-oscillator) 16:48:59 INFO - PROCESS | 1919 | RSS: Main: 198086656 16:48:59 INFO - PROCESS | 1919 | 16:49:01 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:65383/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:02 INFO - PROCESS | 1919 | RSS: Main: 197869568 16:49:02 INFO - PROCESS | 1919 | 16:49:05 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:65383/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:05 INFO - PROCESS | 1919 | RSS: Main: 202076160 16:49:05 INFO - PROCESS | 1919 | 16:49:08 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:65383/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:09 INFO - PROCESS | 1919 | RSS: Main: 202207232 16:49:09 INFO - PROCESS | 1919 | 16:49:12 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:65383/tests/kraken/driver.html?testName=json-parse-financial) 16:49:13 INFO - PROCESS | 1919 | RSS: Main: 201580544 16:49:13 INFO - PROCESS | 1919 | 16:49:14 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:65383/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:15 INFO - PROCESS | 1919 | RSS: Main: 202199040 16:49:15 INFO - PROCESS | 1919 | 16:49:17 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:65383/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:17 INFO - PROCESS | 1919 | RSS: Main: 202403840 16:49:17 INFO - PROCESS | 1919 | 16:49:20 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:65383/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:20 INFO - PROCESS | 1919 | RSS: Main: 202194944 16:49:20 INFO - PROCESS | 1919 | 16:49:23 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:65383/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:24 INFO - PROCESS | 1919 | RSS: Main: 202190848 16:49:24 INFO - PROCESS | 1919 | 16:49:27 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:65383/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:28 INFO - PROCESS | 1919 | RSS: Main: 202579968 16:49:28 INFO - PROCESS | 1919 | 16:49:29 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:65383/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:65383/tests/kraken/driver.html?testName=ai-astar) 16:49:30 INFO - PROCESS | 1919 | RSS: Main: 204292096 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | __start_tp_report 16:49:30 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 16:49:30 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 16:49:30 INFO - PROCESS | 1919 | |i|pagename|runs| 16:49:30 INFO - PROCESS | 1919 | |0;ai-astar;109;119;116;118;119;117;118;116;141;172 16:49:30 INFO - PROCESS | 1919 | |1;audio-beat-detection;146;108;111;145;106;111;111;109;129;114 16:49:30 INFO - PROCESS | 1919 | |2;audio-dft;201;203;196;200;197;199;234;228;198;199 16:49:30 INFO - PROCESS | 1919 | |3;audio-fft;85;58;57;88;59;61;60;59;71;60 16:49:30 INFO - PROCESS | 1919 | |4;audio-oscillator;89;71;84;73;70;73;83;68;72;79 16:49:30 INFO - PROCESS | 1919 | |5;imaging-gaussian-blur;88;90;110;86;135;133;89;85;88;87 16:49:30 INFO - PROCESS | 1919 | |6;imaging-darkroom;94;94;95;94;96;95;101;154;152;95 16:49:30 INFO - PROCESS | 1919 | |7;imaging-desaturate;116;100;98;93;91;90;93;93;94;92 16:49:30 INFO - PROCESS | 1919 | |8;json-parse-financial;59;59;56;59;63;61;61;62;61;61 16:49:30 INFO - PROCESS | 1919 | |9;json-stringify-tinderbox;67;68;76;78;83;75;69;71;72;73 16:49:30 INFO - PROCESS | 1919 | |10;stanford-crypto-aes;84;83;85;86;78;87;81;84;101;87 16:49:30 INFO - PROCESS | 1919 | |11;stanford-crypto-ccm;124;154;118;143;148;148;153;153;197;130 16:49:30 INFO - PROCESS | 1919 | |12;stanford-crypto-pbkdf2;210;170;168;224;228;181;167;168;169;165 16:49:30 INFO - PROCESS | 1919 | |13;stanford-crypto-sha256-iterative;67;69;63;57;82;82;63;58;60;86 16:49:30 INFO - PROCESS | 1919 | __end_tp_report 16:49:30 INFO - PROCESS | 1919 | __start_cc_report 16:49:30 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,937 16:49:30 INFO - PROCESS | 1919 | __end_cc_report 16:49:30 INFO - PROCESS | 1919 | __startTimestamp1491349770108__endTimestamp 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | ------- Summary: start ------- 16:49:30 INFO - PROCESS | 1919 | Number of tests: 14 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#0] ai-astar Cycles:10 Average:124.50 Median:118.50 stddev:18.60 (15.7%) stddev-sans-first:18.87 16:49:30 INFO - PROCESS | 1919 | Values: 109.0 119.0 116.0 118.0 119.0 117.0 118.0 116.0 141.0 172.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:112.50 stddev:15.32 (13.6%) stddev-sans-first:12.76 16:49:30 INFO - PROCESS | 1919 | Values: 146.0 108.0 111.0 145.0 106.0 111.0 111.0 109.0 129.0 114.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#2] audio-dft Cycles:10 Average:205.50 Median:200.50 stddev:13.66 (6.8%) stddev-sans-first:14.39 16:49:30 INFO - PROCESS | 1919 | Values: 201.0 203.0 196.0 200.0 197.0 199.0 234.0 228.0 198.0 199.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#3] audio-fft Cycles:10 Average:65.80 Median:60.50 stddev:11.59 (19.2%) stddev-sans-first:10.00 16:49:30 INFO - PROCESS | 1919 | Values: 85.0 58.0 57.0 88.0 59.0 61.0 60.0 59.0 71.0 60.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:76.00 stddev:7.07 (9.3%) stddev-sans-first:5.78 16:49:30 INFO - PROCESS | 1919 | Values: 89.0 71.0 84.0 73.0 70.0 73.0 83.0 68.0 72.0 79.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#5] imaging-gaussian-blur Cycles:10 Average:99.10 Median:87.50 stddev:19.73 (22.6%) stddev-sans-first:20.52 16:49:30 INFO - PROCESS | 1919 | Values: 88.0 90.0 110.0 86.0 135.0 133.0 89.0 85.0 88.0 87.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#6] imaging-darkroom Cycles:10 Average:107.00 Median:94.50 stddev:24.34 (25.8%) stddev-sans-first:25.35 16:49:30 INFO - PROCESS | 1919 | Values: 94.0 94.0 95.0 94.0 96.0 95.0 101.0 154.0 152.0 95.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:93.00 stddev:7.66 (8.2%) stddev-sans-first:3.23 16:49:30 INFO - PROCESS | 1919 | Values: 116.0 100.0 98.0 93.0 91.0 90.0 93.0 93.0 94.0 92.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:61.00 stddev:1.99 (3.3%) stddev-sans-first:2.06 16:49:30 INFO - PROCESS | 1919 | Values: 59.0 59.0 56.0 59.0 63.0 61.0 61.0 62.0 61.0 61.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:74.00 stddev:4.94 (6.7%) stddev-sans-first:4.70 16:49:30 INFO - PROCESS | 1919 | Values: 67.0 68.0 76.0 78.0 83.0 75.0 69.0 71.0 72.0 73.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:84.50 stddev:6.08 (7.2%) stddev-sans-first:6.42 16:49:30 INFO - PROCESS | 1919 | Values: 84.0 83.0 85.0 86.0 78.0 87.0 81.0 84.0 101.0 87.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:150.50 stddev:21.85 (14.5%) stddev-sans-first:21.56 16:49:30 INFO - PROCESS | 1919 | Values: 124.0 154.0 118.0 143.0 148.0 148.0 153.0 153.0 197.0 130.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.00 Median:175.50 stddev:25.37 (14.5%) stddev-sans-first:25.25 16:49:30 INFO - PROCESS | 1919 | Values: 210.0 170.0 168.0 224.0 228.0 181.0 167.0 168.0 169.0 165.0 16:49:30 INFO - PROCESS | 1919 | 16:49:30 INFO - PROCESS | 1919 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.00 stddev:10.79 (15.9%) stddev-sans-first:11.43 16:49:30 INFO - PROCESS | 1919 | Values: 67.0 69.0 63.0 57.0 82.0 82.0 63.0 58.0 60.0 86.0 16:49:30 INFO - PROCESS | 1919 | -------- Summary: end -------- 16:49:30 INFO - PROCESS | 1919 | 16:49:31 INFO - TEST-INFO | 1919: exit 0 16:49:31 INFO - TEST-OK | kraken | took 60283ms 16:49:31 INFO - SUITE-END | took 1174s 16:49:31 INFO - Completed test suite (00:19:35) 16:49:31 INFO - javascript benchmark 16:49:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7472.537268021459, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3419.846106925188, 3442.2254992626654, 3456.5506484157054, 3464.8787292444763, 3485.7734247273943, 4897.191963326047, 5146.198830409359, 5647.237622920905, 5673.943260567393, 5729.512991395726, 5693.572982026344, 5755.769769209227, 5762.769489220426, 5789.131630255453, 5825.708714564276, 5177.663451875635, 5231.11071117909, 5265.341832270966, 5268.92096618551, 5317.441668624797, 5287.735613219343, 5303.416624171338, 5329.307190065286, 5341.973290133544, 5419.783208671648, 5213.2701421800975, 5247.501487358714, 5321.52106310432, 5326.2543243945875, 5344.465553444644, 4961.875953101166, 4975.353204083446, 5296.311479507659, 5331.546818520431, 5390.838274851761, 5188.066148093341, 5286.920696189561, 5295.152775555931, 5301.0, 5353.732313384354, 5484.890302193934, 5608.635438696471, 5727.198192253088, 5762.510186634141, 5878.0, 6157.322694503601, 6167.414095660905, 6173.444390004921, 6217.751289948396, 6265.185525881606, 5233.816816411445, 6178.691065446755, 6194.287656919467, 6234.127222188898, 6300.590461620026, 5991.131285963555, 5992.221011268507, 5996.5202783777195, 6005.459508644243, 6006.969965150146, 6137.171481849982, 6147.569670123138, 6156.415140561639, 6168.19813424252, 6175.44421002112, 6128.295246046672, 6136.059583474799, 6137.989923150331, 6157.96921015401, 6188.319284878659, 6140.600860944024, 6182.505399568024, 6227.003679411363, 6229.7508099676415, 6238.844028899223, 5106.514881086291, 5235.973820130875, 6122.846928826726, 6123.5876691309595, 6168.938310616926, 4947.985662939161, 5878.323992740804, 6202.782902598387, 6232.906506402408, 6262.6868656566985, 4943.678660887031, 4985.351904252424, 5002.324686167321, 5032.547070763576, 5056.26684130799, 4033.8789836304372, 4247.426597409372, 4399.714018588781, 4519.276915693474, 4522.5025247222775, 5739.368669446358, 5821.330546987066, 5841.591088623755, 5852.502537284378, 5899.380565040609, 6191.659458729723, 6260.092286618415, 6264.373562643699, 6275.96862015687, 6278.152449419361, 4796.640251981115, 5641.097424412074, 5814.825555233349, 5874.765009399576, 5879.970600146972, 4534.843614878185, 4967.254911763264, 5485.780568777204, 5488.121900495901, 5501.339839219319, 5278.709670968134, 6323.430891219812, 6339.302676705559, 6347.333529979378, 6353.301136875032, 6144.863200307957, 6178.969105154445, 6183.5671502994355, 6204.658743768956, 6262.937370626418, 6297.307296197323, 6304.495640348853, 6319.178506794087, 6322.715477803602, 6344.777932772331, 6162.845928851814, 6197.81406557804, 6247.375262473716, 6276.560640755103, 6286.7799627014665, 6165.321814600301, 6172.506199503939, 6211.223597050369, 6288.937110629018, 6290.591111577733, 6048.092786082123, 6166.475849552839, 6171.84570385726, 6172.043333283348, 6212.192414986023, 6138.5396095292135, 6140.048292514667, 6146.139540464249, 6153.446189842935, 6183.134361189347, 5893.793717219876, 5907.822765317047, 5916.763329466773, 6040.456358927718, 6100.481459076028, 4972.8756781080765, 5921.3190483094995, 6121.204243448323, 6159.599626024294, 6175.59858609189, 6006.879862402815, 6018.8796224074395, 6035.336113027652, 6058.394160583906, 6063.454289114001], "value": 5676.926238576496, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.39111590468775, 466.70131116085713, 540.0629907111164, 546.7348336057013, 557.3618207152813, 40234.396484052726, 43162.78418607903, 43289.56710432895, 43308.783456082754, 43361.56638433618, 45339.54660453394, 45558.77220613892, 46013.07973840521, 46081.76959115208, 46195.0, 9141.177294043535, 9328.906710932906, 9621.855672164924, 9859.35914165577, 9878.209743220525, 11999.460024298927, 12255.571055013077, 12278.38608069593, 12448.004159667205, 12461.06542009353, 12083.93958030213, 12105.455254513525, 12145.45345459452, 12147.93926030373, 12198.963088137474, 12080.697982550506, 12093.758124837497, 12113.93943030288, 12162.756744865053, 12169.087318451151, 12060.276383416935, 12103.152779305452, 12113.0, 12127.757444851142, 12143.878561214364, 44049.55950440487, 44153.0, 44360.334594981105, 44496.3325550117, 44539.554604453864, 4113.403556484293, 4144.647704945083, 4156.0, 4203.810828512725, 4222.535521092677, 31388.21529461759, 31421.8428907854, 31426.0, 33432.66567334344, 33524.49713254303, 3279.672032796725, 3493.737969652286, 3509.8069606171653, 3511.4206155984234, 3511.8235391144, 378426.0, 381376.0, 382617.0, 383398.083009586, 386171.0, 57451.71274143686, 69919.65040174767, 70334.0, 70610.0, 70947.64526177336, 65339.67330163414, 70179.94730079053, 72982.0, 74029.25970740331, 74320.62839685768, 15642.608934776721, 18067.909660451613, 18187.63624727498, 18447.89312641246, 18509.7223541647, 12718.427670754652, 12981.545645902537, 13507.864921350661, 13768.65578360549, 13866.930665346608, 17381.304747810198, 18570.628587428175, 19451.227986578582, 19485.51286217856, 19514.609707805765, 7492.325690687864, 7667.731629393056, 8110.229528194812, 8112.59437028158, 8181.468204566803, 805.8186907945666, 851.9616617252238, 857.9532969776863, 888.2139306713605, 920.4523308631353, 32148.0, 32197.839010804797, 32307.0, 32432.0, 32531.674683252866, 1063.7500187455942, 1117.80997230471, 1144.2047776795214, 1151.407025381929, 1228.2016689152122, 14869.347059498172, 16232.918835405748, 16463.176841158132, 16492.587685307724, 17349.95900245989, 623924.0, 672082.0, 683341.0, 684496.0, 879830.6008469916, 58776.70611646914, 59619.0, 59659.0, 59673.40326596679, 59863.0, 1482.6367539952564, 1483.4881965721809, 1505.5408100529444, 1512.138081293652, 1513.9470118545798, 29265.0, 30695.2326191847, 31039.400519775194, 31143.0, 31219.84390078035, 50569.74715126548, 50828.74585627196, 51140.0, 51295.97408051893, 51400.0, 546601.2669936625, 554670.0, 556172.0, 558211.0, 559180.0, 26628.66856657198, 26649.312455289444, 26683.599746003438, 26957.460850783267, 27019.324516887234, 38862.4170637435, 39814.80092599519, 45628.0, 45737.77131114323, 46447.0, 31369.8431507841, 31828.68171318257, 32008.83995580007, 32656.51015234726, 32820.83589582037, 31685.0, 32102.35795284128, 32160.035198944068, 32900.74885771792, 33170.50244246387, 36616.81691591525, 37091.88724338274, 37231.6276837239, 38110.428343575426, 38115.80942095272, 29124.7087529122, 31919.521207181446, 31939.520907185946, 32163.35673286568, 32783.672163279014, 23983.880080599487, 24048.278551643478, 25270.87364563239, 25739.742602574483, 25816.483670326113], "value": 20818.696321832474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.83760376217515, 359.6942598791028, 404.8178319756109, 428.50721670079423, 430.8965848196432, 17618.559536011573, 19843.40469785907, 20898.686519702216, 21093.68359474607, 21341.466463338424, 17880.64238715225, 20632.0, 21479.785202148014, 21720.0, 21831.0, 5863.677497737623, 5879.7648094076185, 6018.8194354169445, 6935.306469353075, 7009.158900931895, 4540.455145382559, 7016.473764467685, 7056.223815380304, 7061.894071588905, 7065.6113913734725, 6231.127642130105, 6321.5890967087225, 6349.111124442582, 6351.9682401587925, 6356.713947872334, 6108.694565271719, 6233.532485063638, 6284.308726040132, 6322.873542529147, 6355.332690067523, 5196.350456192976, 5211.687298762086, 6157.199564056688, 6201.9689901550655, 6221.253449586034, 12110.0, 13150.408231629599, 13616.523421680293, 13686.247256400893, 14060.578182654537, 2616.306678730138, 2693.784497240236, 2699.7300269973043, 2702.04077552468, 2714.309227753597, 10309.53607087683, 10332.793344133075, 10818.945905270502, 13499.0, 15159.924200378928, 1878.3238034307637, 1878.5209771508178, 1879.0604697651174, 1887.1979408751363, 1898.3071179019696, 132082.0, 133736.33131834376, 137861.31069344588, 149015.0, 154711.45288547082, 10083.495825208856, 11005.669829905115, 12350.876491235153, 12368.257904525757, 12765.361731913554, 12849.550265740654, 15898.682026359407, 16046.438374656831, 16113.355465781471, 16148.919255403647, 7433.628318584049, 9103.817923641622, 9167.587458564381, 9190.540472976325, 9277.072292770668, 9026.954865225633, 9090.090990900857, 9104.0, 9257.861132083024, 9308.720738377859, 4136.524299705512, 4747.287906813936, 4790.473047964721, 4848.321235027099, 4861.756912154449, 1728.5937804615905, 2080.074366906712, 2106.789321067881, 2118.1739121742535, 2120.544083022158, 798.3373799746197, 803.3292201012104, 883.1477624092781, 900.3562452846115, 932.9906700932904, 21367.35897923065, 21728.130874764833, 21801.345959621238, 21832.345029649136, 22095.558088838134, 930.7952250504878, 940.586142097475, 958.6644674364056, 960.0831206198204, 975.0639386189196, 10929.180311476672, 11357.97778199966, 11439.828402573969, 11488.540458381738, 11548.133889958286, 669013.0, 680658.7901181487, 694554.0, 701449.0, 712882.0, 110824.44587777171, 114305.0, 151482.24258878635, 152182.23908880606, 157772.0, 1082.2045796339685, 1103.9558417663204, 1121.831725241204, 1127.8928501792316, 1131.643532287327, 19521.414357569294, 19535.511612209808, 20558.0, 20853.061612227182, 21707.457313566654, 62224.0, 62664.0, 63024.0, 63264.68367658132, 63658.0, 88540.1145988532, 89261.0, 91152.54423727839, 91901.0, 92947.0, 6514.446272066927, 8317.792055198668, 8658.956705216433, 8732.214100730966, 8844.601992910206, 12258.509659613515, 13246.139000964906, 13427.530036448677, 13532.59402217935, 13693.0, 11255.437228138397, 11786.882131178578, 11808.291502509877, 11860.703482413008, 11892.583759568373, 10570.524326405177, 10723.839142413026, 10918.01737843598, 11131.276467029616, 11201.887981120084, 10840.945795270973, 10953.507092180711, 11851.407429628312, 11913.404329783303, 12052.216605920587, 7373.152087510005, 7508.0, 7691.230876912264, 7872.921270787218, 8619.094995025684, 10098.646547370807, 11522.0, 11538.480768365276, 11694.0, 11919.284842909454], "value": 9967.695145685679, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1484.6140003599062, 1508.464495104238, 1511.1915125407902, 1512.4026009726158, 1522.7335216337142, 7587.127480339763, 7871.8425631487335, 8243.134470880561, 8657.0, 8669.653213871454, 6456.741730330793, 8441.155884411555, 8465.492070475775, 8557.614907329169, 8568.65725370986, 2401.363638635763, 2426.6845310109748, 2429.0648100481267, 2434.987825060872, 2438.756124387565, 2410.710714714237, 2434.6469761884514, 2449.289705985262, 2460.0036985021097, 2463.9897641966795, 2371.95256094878, 2392.1507864708037, 2400.147947478646, 2408.95182096358, 2424.8423852449623, 2189.861272138479, 2359.4101474631343, 2363.1492662641435, 2378.131981826638, 2390.4501964548163, 1911.5221194701326, 2022.4943764058985, 2022.9480670051637, 2416.8066554675584, 2427.320350301918, 8444.957775211145, 8566.143385661446, 8587.270082043035, 8596.183362580545, 8629.784255393603, 5258.737063146866, 5402.0, 5414.675119492843, 5419.945800541984, 5446.91829622556, 3145.820317380982, 3625.728070394731, 3634.872779452733, 3655.2872189922978, 3728.478013078144, 1262.1480500662017, 1262.7158889249938, 1288.6778305423645, 1319.8350206224222, 1320.7754681704037, 10997.560097596086, 11015.504302306415, 11025.83461248082, 11036.613718519893, 11044.779104417947, 4731.716097034189, 4792.664513484093, 4820.517948205152, 4827.517248275215, 4914.72968986702, 9750.853737193947, 9756.90243097574, 9769.0, 9791.461469619097, 9810.313278070467, 5361.195820626859, 5452.890942181134, 5466.1527463243265, 5475.561955043667, 5530.889382212414, 4394.12117576486, 4646.395968524071, 4906.215005599159, 4965.726885021286, 4998.675086119463, 3943.093088589583, 4007.458993035961, 4116.835326586903, 4155.812988415529, 4168.145530166309, 1127.5264388956657, 1311.265691212924, 1328.1698938163647, 1328.335832083958, 1336.5522549945683, 863.8963324400986, 936.1434287626881, 970.7379007668031, 970.7670159161892, 1000.8798944126752, 5685.630434021776, 5792.8551786204835, 5794.478496935296, 6022.939770602326, 6131.570790044743, 969.1120263835799, 994.4983075555963, 996.8056229035413, 1013.3261381181579, 1050.2123407444417, 3305.9008229753144, 3796.924061518754, 3930.0960779020984, 3998.0, 4042.171354872244, 9958.0, 10150.543225554868, 10355.964403559734, 10359.689209323733, 10392.792144157074, 10287.845682314772, 10289.3311934724, 10310.639127630497, 10313.27807053514, 10331.483425828828, 7395.630218489162, 7836.216378362232, 7983.960080199562, 8011.519308841488, 8113.716019939274, 5925.822225333247, 5964.224650795369, 6008.068749343859, 6013.5189184865985, 6108.114323423168, 9387.10822471864, 9440.05599440078, 9499.857502137334, 9638.373505722106, 9692.9030709692, 9236.953815230881, 9468.100530449596, 9525.428474291564, 9688.224942004763, 9879.901200987899, 7282.417406607565, 7448.217937116525, 7509.211532788979, 7518.210587888193, 7519.73680921187, 8070.636821342936, 8073.5963201838495, 8115.391345648982, 8127.79680507992, 8170.387220958571, 7139.0, 7184.892226616709, 7247.456440766858, 7306.305900939402, 7316.780496585111, 6181.196444462191, 6742.898856517058, 6954.930450695429, 7055.0, 7092.787216383516, 6502.967485162544, 6611.239707433707, 6621.238557565942, 6632.966835165793, 6639.7012134455035, 5012.924806127838, 5069.467705890976, 5132.945897191165, 5204.869878253074, 5228.686278823283, 4907.312976183409, 4978.352814134139, 5056.140456122542, 5165.483451654804, 5289.127293996601], "value": 4563.173074949818, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.69297636473544, 373.5498724037534, 381.44118865861515, 385.97877116758565, 387.76733959624227, 4466.709663871846, 5349.625526213166, 5497.477739614736, 5502.972485137574, 5624.550035997116, 4536.863894083174, 5165.431802501722, 5272.393674727407, 5275.472452754722, 5415.83752487425, 4233.555476674951, 4344.89137771555, 4363.4327537420095, 4364.454443194601, 4365.297533961758, 4153.172604054222, 4348.0, 4366.257736184841, 4401.36180253863, 4412.713173643719, 3893.0072831427947, 3928.2732694451474, 3987.9601203988022, 4272.87181384559, 4288.442502474674, 3954.8813535593977, 4249.298865687173, 4250.426192464009, 4299.419578356913, 4302.7848607569495, 3201.167696398953, 3834.181876320902, 3835.6000059978187, 3846.442265871461, 3858.961410385916, 4415.558444155558, 4431.49037860647, 5024.62315326352, 5175.870603234912, 5245.763940622681, 4105.80931529856, 4594.770261486946, 4739.857804265877, 4745.288206768978, 4784.808607655689, 4981.850544483671, 5385.5960802939935, 5762.481376676119, 5762.538996880239, 5786.276715410574, 3831.0613899594778, 3883.2427676603074, 3904.414337849317, 3931.9803400983096, 3964.405339199114, 5358.678479291255, 5819.476247137735, 5984.820455386345, 6024.909626355652, 6049.818505444844, 3396.524486571882, 4003.679705623543, 4031.6090948622677, 4050.837966481308, 4053.6149065839286, 3831.942520862189, 4031.065088757413, 4045.5347635021762, 4074.348104303297, 4083.734557253769, 3923.6468717815533, 4485.865424037284, 4673.672842901033, 4707.929381059287, 4729.408823897013, 5778.508826749689, 5783.681897495594, 5801.622894511843, 5881.500217444524, 5887.852803679856, 1059.3961441978, 1235.529719633633, 1312.3569450968957, 1318.5187406596715, 1321.6299436157892, 750.9436792240604, 773.7910764093775, 874.9212570868525, 892.8794612727198, 913.92613678927, 4641.537915556589, 4943.90112197754, 5172.456892026358, 5180.974095129575, 5185.585153187736, 4664.760914760934, 5349.732513374316, 5554.334578729754, 5686.488216060575, 5701.432106170753, 4581.983927072144, 4896.779644915988, 4937.802487900443, 5185.248139019821, 5195.7142357170715, 2933.9559906601417, 2937.4418860416454, 2984.388200418909, 2993.536001919706, 2994.31130839908, 5942.673152976541, 5957.404259574095, 5973.880522389528, 6016.458518733335, 6026.6986650667295, 4730.09766942274, 5059.924101138412, 5478.123500240023, 5755.4460295720755, 5835.970820145957, 5305.469453054664, 5818.883622327529, 5855.2973643163095, 5898.587098903035, 5934.902043122036, 5083.694978301313, 5471.288732464914, 5715.742791574306, 5725.570582206268, 5748.228969955011, 5022.673526220784, 5552.972235138798, 5607.80372686954, 5613.298337707786, 5649.943500564942, 5467.555440072729, 5852.531797456279, 5880.7356418370255, 5943.554233432424, 5988.233471126116, 3626.474161246552, 3673.669369756735, 3770.7549009314307, 3805.1818858945458, 3826.9525803696, 4875.6099512038245, 4954.083494553519, 5339.199120131856, 5403.189521571796, 5661.952538780339, 5147.2279158126585, 5442.6462279951675, 5537.584681148849, 5547.833564993057, 5680.0, 5486.945130548644, 5658.377578466283, 5751.281089863767, 5778.855528611818, 5789.768409263583, 5522.530584900329, 5642.746076426489, 5658.830235092953, 5670.688112153871, 5687.829365119053, 4734.360861283821, 5339.652922560022, 5692.5730570208225, 5736.4263573643975, 5809.79665711715, 5640.73083556213, 5658.830235092953, 5674.779203133045, 5779.295107943083, 5783.421657834351], "value": 4170.697209268528, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.4048795352253, 771.9768406947793, 778.2023425988363, 784.8077221080835, 814.7881550796791, 12552.623421297352, 16396.590085247874, 16398.590035249126, 16418.671626567462, 16423.34306627735, 14923.955323127386, 15023.699526009474, 15029.72247358973, 15032.323545440455, 15064.548063558083, 700.6881937537797, 716.8387112899608, 846.9745907622781, 863.4689665855485, 873.8907636545432, 825.6119623776815, 878.4773060029272, 892.9062448442918, 895.677556079814, 897.7979954510248, 912.6851236323491, 935.8081593273397, 936.7377134402378, 940.2759874896326, 943.0333907744582, 927.656766996209, 928.2342067794096, 930.4975313330793, 933.3000249812641, 941.8634298026748, 685.4379408884716, 693.6566399632164, 694.3924066441863, 694.9617771022591, 695.0408436357794, 15449.304781284756, 15856.762148567781, 15953.282102305307, 16534.6693066138, 16785.832141678547, 14638.82889368848, 15055.473058442902, 15135.621609459742, 15233.923830380778, 15286.694266114726, 10811.783764324748, 11846.526138954432, 11993.0, 12066.276023438622, 12282.570110046196, 3801.2967600993907, 3913.6477717005323, 3957.4657421248053, 4069.97965010176, 4085.816138273785, 109505.0, 111074.0, 111595.44202279017, 112431.0, 112831.87168128295, 11577.0, 11588.420578971187, 11798.233114847679, 11832.467538960767, 11861.822072668745, 24264.029438822603, 24796.884140213733, 24868.502629947663, 24897.004119835365, 24936.625950610756, 7071.752488662975, 8120.6345714443, 8135.511869287861, 8200.671973121127, 8286.668533258722, 4366.433663987873, 4396.560343965578, 4523.457185137804, 4613.653975951817, 4705.1765940960195, 3746.8875933722034, 3790.639889210521, 3807.5430948286385, 3853.094522787143, 3855.8264878080554, 621.8973869311513, 663.9468842492589, 689.5586824432368, 692.9618870962045, 700.2927043685812, 687.8968154776824, 699.2937133495104, 712.4478529139855, 715.7602203261862, 761.1969372312153, 10413.843792343121, 10637.574497020032, 10734.355938643706, 10854.34873907568, 10977.39624320654, 696.6481926627122, 712.8574285142992, 741.7366834773685, 743.6727839750492, 745.3478206569251, 4983.850484485399, 5167.896642067138, 5168.173092305288, 5169.276301317818, 5197.922031169535, 104974.95025049805, 106745.46627266814, 108920.0, 110163.34754978682, 113336.86663133428, 85199.0, 88848.11151888527, 90583.0, 91237.08762912419, 92122.0, 82287.0, 82692.58653706693, 82926.58536707278, 83241.16758832334, 86400.1359986392, 10065.496725163859, 10107.494625268855, 10150.644727434505, 10151.593936242467, 10154.644587439405, 38137.80931095327, 44782.55217447784, 47342.0, 48315.27527087026, 56267.0, 64624.67687661532, 68300.97548536875, 68739.0, 69661.65169174122, 70974.64512677403, 11343.376114313462, 11351.716207094889, 11364.090872729996, 11592.246503977214, 14314.713705725619, 12774.552890649155, 15270.541883743506, 15460.9226953869, 15491.07053576789, 15684.529464116094, 13562.254076025913, 14324.997250192386, 14405.135691858522, 14432.680483488646, 14458.783118253024, 13661.795073073714, 13952.511662091778, 14437.566872993826, 14615.196164211071, 14953.32710028073, 13518.526851560147, 13981.44074237019, 14337.810281268694, 14566.635834104232, 14766.228052633753, 9920.801583968425, 10095.108897459742, 10390.948045260027, 10428.27002109845, 10577.153827693919, 7444.553326800409, 10617.203709721647, 11342.489587968395, 11461.484233209692, 11596.304221746723], "value": 7764.687174536951, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 116.0, 118.0, 119.0, 117.0, 118.0, 116.0, 141.0, 172.0], "value": 124.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 108.0, 111.0, 145.0, 106.0, 111.0, 111.0, 109.0, 129.0, 114.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 203.0, 196.0, 200.0, 197.0, 199.0, 234.0, 228.0, 198.0, 199.0], "value": 205.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 57.0, 88.0, 59.0, 61.0, 60.0, 59.0, 71.0, 60.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 84.0, 73.0, 70.0, 73.0, 83.0, 68.0, 72.0, 79.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 110.0, 86.0, 135.0, 133.0, 89.0, 85.0, 88.0, 87.0], "value": 99.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 96.0, 95.0, 101.0, 154.0, 152.0, 95.0], "value": 107.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 100.0, 98.0, 93.0, 91.0, 90.0, 93.0, 93.0, 94.0, 92.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 56.0, 59.0, 63.0, 61.0, 61.0, 62.0, 61.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 76.0, 78.0, 83.0, 75.0, 69.0, 71.0, 72.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 86.0, 78.0, 87.0, 81.0, 84.0, 101.0, 87.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 154.0, 118.0, 143.0, 148.0, 148.0, 153.0, 153.0, 197.0, 130.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 170.0, 168.0, 224.0, 228.0, 181.0, 167.0, 168.0, 169.0, 165.0], "value": 185.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 63.0, 57.0, 82.0, 82.0, 63.0, 58.0, 60.0, 86.0], "value": 68.7, "unit": "score"}]}]} 16:49:31 INFO - Return code: 0 16:49:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:49:31 ERROR - # TBPL SUCCESS # 16:49:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:49:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:49:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:49:31 INFO - 2017-04-04 16:49:31.387 screenresolution[1924:62943] starting screenresolution argv=screenresolution get 16:49:31 INFO - 2017-04-04 16:49:31.389 screenresolution[1924:62943] Display 0: 1600x1200x32@60 16:49:31 INFO - 2017-04-04 16:49:31.396 screenresolution[1925:62946] starting screenresolution argv=screenresolution list 16:49:31 INFO - Available Modes on Display 0 16:49:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:49:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:49:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:49:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:49:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:49:31 INFO - Intel Iris: 16:49:31 INFO - Chipset Model: Intel Iris 16:49:31 INFO - Type: GPU 16:49:31 INFO - Bus: Built-In 16:49:31 INFO - VRAM (Dynamic, Max): 1536 MB 16:49:31 INFO - Vendor: Intel (0x8086) 16:49:31 INFO - Device ID: 0x0a2e 16:49:31 INFO - Revision ID: 0x0009 16:49:31 INFO - Displays: 16:49:31 INFO - Display: 16:49:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:49:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:49:31 INFO - Display Serial Number: 1600x1200 60 16:49:31 INFO - Main Display: Yes 16:49:31 INFO - Mirror: Off 16:49:31 INFO - Online: Yes 16:49:31 INFO - Rotation: Supported 16:49:31 INFO - Return code: 0 16:49:31 INFO - Running post-action listener: _resource_record_post_action 16:49:31 INFO - [mozharness: 2017-04-04 23:49:31.637345Z] Finished run-tests step (success) 16:49:31 INFO - Running post-run listener: _resource_record_post_run 16:49:31 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 1491968; Write bytes: 424202240; Read time: 15; Write time: 1613 16:49:31 INFO - TinderboxPrint: CPU usage
54.4% 16:49:31 INFO - TinderboxPrint: I/O read bytes / time
1,491,968 / 15 16:49:31 INFO - TinderboxPrint: I/O write bytes / time
424,202,240 / 1,613 16:49:31 INFO - TinderboxPrint: CPU idle
2,168.9 (45.6%) 16:49:31 INFO - TinderboxPrint: CPU system
960.1 (20.2%) 16:49:31 INFO - TinderboxPrint: CPU user
1,632.6 (34.3%) 16:49:31 INFO - TinderboxPrint: Swap in / out
122,970,112 / 0 16:49:31 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 113361408; Write bytes: 114534400; Read time: 10036; Write time: 289 16:49:31 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1491968; Write bytes: 299722752; Read time: 15; Write time: 1294 16:49:32 INFO - Running post-run listener: _upload_blobber_files 16:49:32 INFO - Blob upload gear active. 16:49:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:49:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:49:32 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'] 16:49:32 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 16:49:32 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:49:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:49:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:33 INFO - (blobuploader) - INFO - Done attempting. 16:49:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:49:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:49:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:34 INFO - (blobuploader) - INFO - Done attempting. 16:49:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:49:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:49:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:35 INFO - (blobuploader) - INFO - Done attempting. 16:49:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:49:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:49:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:38 INFO - (blobuploader) - INFO - Done attempting. 16:49:38 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:38 INFO - Return code: 0 16:49:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00cffe197f7918db66d7c174039000fb6e628e3ce90cbde6fd32ac54031d55e4b6073844205e5026c9c529deda9c9cc0b9e3e20070da03c4633046b8c9277673", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/448786833bc501da081dc7b6b45b44d53ac2a75f6e0ebea1952f82c686d6f868f3abf8d73b386619362580941cb46fd42ac3fffe415615c5954870d780e2255e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/436167c7ea8570925bbda52ebc1a9a2fe21090451fb207279358e384af6b0b29cffa27ae835ad18ac71daa128decbde3bbd4c0e2d0a9aa690d38fd5ad38ec79d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9093bb1138d7fca3fd85443db599775d46fffb2f6b2d2e9c3ee97857f0c7fa9f90fca188a755022bd71d5fa034cf7124849ecc51fb481447d12ad9a381cc228f"} 16:49:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:38 INFO - Contents: 16:49:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00cffe197f7918db66d7c174039000fb6e628e3ce90cbde6fd32ac54031d55e4b6073844205e5026c9c529deda9c9cc0b9e3e20070da03c4633046b8c9277673", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/448786833bc501da081dc7b6b45b44d53ac2a75f6e0ebea1952f82c686d6f868f3abf8d73b386619362580941cb46fd42ac3fffe415615c5954870d780e2255e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/436167c7ea8570925bbda52ebc1a9a2fe21090451fb207279358e384af6b0b29cffa27ae835ad18ac71daa128decbde3bbd4c0e2d0a9aa690d38fd5ad38ec79d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9093bb1138d7fca3fd85443db599775d46fffb2f6b2d2e9c3ee97857f0c7fa9f90fca188a755022bd71d5fa034cf7124849ecc51fb481447d12ad9a381cc228f"} 16:49:38 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:38 INFO - Copying logs to upload dir... 16:49:38 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.803329 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-04-04 16:49:38.949474) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 16:49:38.952779) ========= 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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00cffe197f7918db66d7c174039000fb6e628e3ce90cbde6fd32ac54031d55e4b6073844205e5026c9c529deda9c9cc0b9e3e20070da03c4633046b8c9277673", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/448786833bc501da081dc7b6b45b44d53ac2a75f6e0ebea1952f82c686d6f868f3abf8d73b386619362580941cb46fd42ac3fffe415615c5954870d780e2255e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/436167c7ea8570925bbda52ebc1a9a2fe21090451fb207279358e384af6b0b29cffa27ae835ad18ac71daa128decbde3bbd4c0e2d0a9aa690d38fd5ad38ec79d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9093bb1138d7fca3fd85443db599775d46fffb2f6b2d2e9c3ee97857f0c7fa9f90fca188a755022bd71d5fa034cf7124849ecc51fb481447d12ad9a381cc228f"} build_url:https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011169 build_url: 'https://queue.taskcluster.net/v1/task/I6pwP8tCTI6E16lBuErKnw/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/00cffe197f7918db66d7c174039000fb6e628e3ce90cbde6fd32ac54031d55e4b6073844205e5026c9c529deda9c9cc0b9e3e20070da03c4633046b8c9277673", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/448786833bc501da081dc7b6b45b44d53ac2a75f6e0ebea1952f82c686d6f868f3abf8d73b386619362580941cb46fd42ac3fffe415615c5954870d780e2255e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/436167c7ea8570925bbda52ebc1a9a2fe21090451fb207279358e384af6b0b29cffa27ae835ad18ac71daa128decbde3bbd4c0e2d0a9aa690d38fd5ad38ec79d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9093bb1138d7fca3fd85443db599775d46fffb2f6b2d2e9c3ee97857f0c7fa9f90fca188a755022bd71d5fa034cf7124849ecc51fb481447d12ad9a381cc228f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 16:49:38.986110) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 16:49:38.986390) ========= 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.6UHHsNkMJ8/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.JADfRt5KWI/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005211 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 16:49:39.035174) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 16:49:39.035472) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 16:49:39.035793) ========= ========= Total master_lag: 6.34 =========