builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0181 starttime: 1479252309.2 results: success (0) buildid: 20161115120248 builduid: f1e14276ff5f488e92138bc7fb7afb08 revision: 8616872c1e2299bd55ae435654de757f3175c494 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:09.200163) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:09.201049) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:09.201743) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:09.226664) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:09.226937) ========= 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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-15 15:25:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-11-15 15:25:09 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088484 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:09.341647) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:09.342110) ========= 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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.028242 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:09.396320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-15 15:25:09.396989) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 8616872c1e2299bd55ae435654de757f3175c494 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 8616872c1e2299bd55ae435654de757f3175c494 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-15 15:25:09,656 truncating revision to first 12 chars 2016-11-15 15:25:09,656 Setting DEBUG logging. 2016-11-15 15:25:09,656 attempt 1/10 2016-11-15 15:25:09,656 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/8616872c1e22?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-15 15:25:10,969 unpacking tar archive at: larch-8616872c1e22/testing/mozharness/ program finished with exit code 0 elapsedTime=1.575022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-15 15:25:10.996080) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:10.996448) ========= script_repo_revision: 8616872c1e2299bd55ae435654de757f3175c494 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:10.996917) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:10.997225) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 15:25:11.016240) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-15 15:25:11.016546) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:25:11 INFO - MultiFileLogger online at 20161115 15:25:11 in /builds/slave/test 15:25:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 15:25:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:25:11 INFO - {'append_to_log': False, 15:25:11 INFO - 'base_work_dir': '/builds/slave/test', 15:25:11 INFO - 'blob_upload_branch': 'Larch', 15:25:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:25:11 INFO - 'branch': 'Larch', 15:25:11 INFO - 'buildbot_json_path': 'buildprops.json', 15:25:11 INFO - 'config_files': ('talos/mac_config.py',), 15:25:11 INFO - 'default_actions': ('clobber', 15:25:11 INFO - 'read-buildbot-config', 15:25:11 INFO - 'download-and-extract', 15:25:11 INFO - 'populate-webroot', 15:25:11 INFO - 'create-virtualenv', 15:25:11 INFO - 'install', 15:25:11 INFO - 'run-tests'), 15:25:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:25:11 INFO - 'download_minidump_stackwalk': True, 15:25:11 INFO - 'download_symbols': 'ondemand', 15:25:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:25:11 INFO - 'tooltool.py': '/tools/tooltool.py', 15:25:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:25:11 INFO - '/tools/misc-python/virtualenv.py')}, 15:25:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:25:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:25:11 INFO - 'installer_path': 'installer.exe', 15:25:11 INFO - 'log_level': 'info', 15:25:11 INFO - 'log_name': 'talos', 15:25:11 INFO - 'log_to_console': True, 15:25:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:25:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:25:11 INFO - 'opt_config_files': (), 15:25:11 INFO - 'pip_index': False, 15:25:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:25:11 INFO - 'cmd': ('bash', 15:25:11 INFO - '-c', 15:25:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:25:11 INFO - 'enabled': True, 15:25:11 INFO - 'halt_on_failure': False, 15:25:11 INFO - 'name': 'check_screen_resolution'},), 15:25:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:25:11 INFO - 'cmd': ('bash', 15:25:11 INFO - '-c', 15:25:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:25:11 INFO - 'enabled': True, 15:25:11 INFO - 'halt_on_failure': False, 15:25:11 INFO - 'name': 'check_screen_resolution'},), 15:25:11 INFO - 'run_cmd_checks_enabled': True, 15:25:11 INFO - 'sps_profile': False, 15:25:11 INFO - 'sps_profile_interval': 0, 15:25:11 INFO - 'suite': 'dromaeojs-e10s', 15:25:11 INFO - 'system_bits': '32', 15:25:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:25:11 INFO - 'title': 't-yosemite-r7-0181', 15:25:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:25:11 INFO - 'use_talos_json': True, 15:25:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:25:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:25:11 INFO - 'work_dir': 'build'} 15:25:11 INFO - [mozharness: 2016-11-15 23:25:11.390090Z] Running clobber step. 15:25:11 INFO - Running pre-action listener: _resource_record_pre_action 15:25:11 INFO - Running main action method: clobber 15:25:11 INFO - rmtree: /builds/slave/test/build 15:25:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:25:13 INFO - Running post-action listener: _resource_record_post_action 15:25:13 INFO - [mozharness: 2016-11-15 23:25:13.691253Z] Finished clobber step (success) 15:25:13 INFO - [mozharness: 2016-11-15 23:25:13.691397Z] Running read-buildbot-config step. 15:25:13 INFO - Running pre-action listener: _resource_record_pre_action 15:25:13 INFO - Running main action method: read_buildbot_config 15:25:13 INFO - Using buildbot properties: 15:25:13 INFO - { 15:25:13 INFO - "project": "", 15:25:13 INFO - "product": "firefox", 15:25:13 INFO - "script_repo_revision": "production", 15:25:13 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 15:25:13 INFO - "repository": "", 15:25:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 15:25:13 INFO - "buildid": "20161115120248", 15:25:13 INFO - "pgo_build": "False", 15:25:13 INFO - "basedir": "/builds/slave/test", 15:25:13 INFO - "buildnumber": 1, 15:25:13 INFO - "slavename": "t-yosemite-r7-0181", 15:25:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:25:13 INFO - "platform": "yosemite_r7", 15:25:13 INFO - "scheduler": "tests-larch-macosx64-talos", 15:25:13 INFO - "branch": "larch", 15:25:13 INFO - "repo_path": "projects/larch", 15:25:13 INFO - "revision": "8616872c1e2299bd55ae435654de757f3175c494", 15:25:13 INFO - "stage_platform": "macosx64", 15:25:13 INFO - "builduid": "f1e14276ff5f488e92138bc7fb7afb08", 15:25:13 INFO - "slavebuilddir": "test" 15:25:13 INFO - } 15:25:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:25:13 INFO - Running post-action listener: _resource_record_post_action 15:25:13 INFO - [mozharness: 2016-11-15 23:25:13.694591Z] Finished read-buildbot-config step (success) 15:25:13 INFO - [mozharness: 2016-11-15 23:25:13.694678Z] Running download-and-extract step. 15:25:13 INFO - Running pre-action listener: _resource_record_pre_action 15:25:13 INFO - Running main action method: download_and_extract 15:25:13 INFO - mkdir: /builds/slave/test/build/tests 15:25:13 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')]} 15:25:13 INFO - trying https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:25:13 INFO - Downloading https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:25:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 15:25:14 INFO - Downloaded 1199 bytes. 15:25:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:25:14 INFO - Using the following test package requirements: 15:25:14 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:25:14 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:25:14 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:25:14 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'jsshell-mac.zip'], 15:25:14 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:25:14 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:25:14 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:25:14 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:25:14 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:25:14 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:25:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:25:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:25:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:25:14 INFO - Fetch https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:25:15 INFO - Http code: 200 15:25:15 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 15:25:15 INFO - x-amz-cf-id: Ad-pMC1XGhziwmXbdPRVh5Sl0-5K7-BIg7WFeu2jaZQpPAZVe86NwQ== 15:25:15 INFO - x-amz-version-id: tvSGG_W8ng9tQJpiwjnXM4UHVDDm65UU 15:25:15 INFO - Expected file size: 16328517 15:25:15 INFO - Obtained file size: 16328517 15:25:22 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:25:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:25:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:25:22 INFO - Fetch https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:25:23 INFO - Http code: 200 15:25:23 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 15:25:23 INFO - x-amz-cf-id: 8wrfdjrJ5QcDTRLqCZR6uIL2q3ErlNMbiKOA3S9e3Ng7zjoNq3dpcw== 15:25:23 INFO - x-amz-version-id: tvSGG_W8ng9tQJpiwjnXM4UHVDDm65UU 15:25:23 INFO - Expected file size: 16328517 15:25:23 INFO - Obtained file size: 16328517 15:25:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:25:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:25:29 INFO - Fetch https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:25:30 INFO - Http code: 200 15:25:30 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 15:25:30 INFO - x-amz-cf-id: vI9crGOI8PTro8SjBaWXUFeQnmVDDYwV3rzHetYgexTZSQKb61riMA== 15:25:30 INFO - x-amz-version-id: vEMWpkvOEdRGsHHNtym3LVTrz7pXk2XJ 15:25:31 INFO - Expected file size: 14409038 15:25:31 INFO - Obtained file size: 14409038 15:25:31 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')]} 15:25:31 INFO - trying https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:25:31 INFO - Downloading https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:25:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 15:25:34 INFO - Downloaded 110984883 bytes. 15:25:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:25:34 INFO - mkdir: /builds/slave/test/properties 15:25:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:25:34 INFO - Writing to file /builds/slave/test/properties/build_url 15:25:34 INFO - Contents: 15:25:34 INFO - build_url:https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:25:34 INFO - Running post-action listener: _resource_record_post_action 15:25:34 INFO - Running post-action listener: set_extra_try_arguments 15:25:34 INFO - [mozharness: 2016-11-15 23:25:34.973677Z] Finished download-and-extract step (success) 15:25:34 INFO - [mozharness: 2016-11-15 23:25:34.973798Z] Running populate-webroot step. 15:25:34 INFO - Running pre-action listener: _resource_record_pre_action 15:25:34 INFO - Running main action method: populate_webroot 15:25:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:25:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:25:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:25:34 INFO - '--tppagecycles', 15:25:34 INFO - '7'], 15:25:34 INFO - 'tests': ['tsvgm']}}, 15:25:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:25:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:25:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:25:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:25:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:34 INFO - 'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:34 INFO - 'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['damp', 'tps']}, 15:25:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:34 INFO - 'tests': ['damp', 'tps']}, 15:25:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['dromaeo_dom']}, 15:25:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:25:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['basic_compositor_video']}, 15:25:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:25:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['a11yr', 15:25:34 INFO - 'ts_paint', 15:25:34 INFO - 'tpaint', 15:25:34 INFO - 'sessionrestore', 15:25:34 INFO - 'sessionrestore_no_auto_restore', 15:25:34 INFO - 'tabpaint']}, 15:25:34 INFO - 'other-e10s': {'tests': ['a11yr', 15:25:34 INFO - 'ts_paint', 15:25:34 INFO - 'tpaint', 15:25:34 INFO - 'sessionrestore', 15:25:34 INFO - 'sessionrestore_no_auto_restore', 15:25:34 INFO - 'tabpaint']}, 15:25:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:25:34 INFO - 'ts_paint', 15:25:34 INFO - 'tpaint', 15:25:34 INFO - 'sessionrestore', 15:25:34 INFO - 'sessionrestore_no_auto_restore', 15:25:34 INFO - 'tabpaint']}, 15:25:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:25:34 INFO - 'ts_paint', 15:25:34 INFO - 'tpaint', 15:25:34 INFO - 'sessionrestore', 15:25:34 INFO - 'sessionrestore_no_auto_restore', 15:25:34 INFO - 'tabpaint']}, 15:25:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['a11yr', 15:25:34 INFO - 'ts_paint', 15:25:34 INFO - 'tpaint', 15:25:34 INFO - 'sessionrestore', 15:25:34 INFO - 'sessionrestore_no_auto_restore', 15:25:34 INFO - 'tabpaint']}, 15:25:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['a11yr', 15:25:34 INFO - 'ts_paint', 15:25:34 INFO - 'tpaint', 15:25:34 INFO - 'sessionrestore', 15:25:34 INFO - 'sessionrestore_no_auto_restore', 15:25:34 INFO - 'tabpaint']}, 15:25:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['tsvgx', 15:25:34 INFO - 'tsvgr_opacity', 15:25:34 INFO - 'tart', 15:25:34 INFO - 'tscrollx', 15:25:34 INFO - 'cart']}, 15:25:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:25:34 INFO - 'tsvgr_opacity', 15:25:34 INFO - 'tart', 15:25:34 INFO - 'tscrollx', 15:25:34 INFO - 'cart']}, 15:25:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:34 INFO - 'talos_options': ['--disable-e10s'], 15:25:34 INFO - 'tests': ['tp5o']}, 15:25:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:34 INFO - 'tests': ['tp5o']}, 15:25:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:34 INFO - 'talos_options': ['--disable-e10s', 15:25:34 INFO - '--xperf_path', 15:25:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:34 INFO - 'tests': ['tp5n']}, 15:25:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:34 INFO - 'talos_options': ['--xperf_path', 15:25:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:34 INFO - 'tests': ['tp5n']}}, 15:25:34 INFO - 'talos.zip': {'path': '', 15:25:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:25:34 INFO - Running post-action listener: _resource_record_post_action 15:25:34 INFO - [mozharness: 2016-11-15 23:25:34.992453Z] Finished populate-webroot step (success) 15:25:34 INFO - [mozharness: 2016-11-15 23:25:34.992565Z] Running create-virtualenv step. 15:25:34 INFO - Running pre-action listener: _resource_record_pre_action 15:25:34 INFO - Running main action method: create_virtualenv 15:25:34 INFO - Creating virtualenv /builds/slave/test/build/venv 15:25:34 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 15:25:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:25:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:25:35 INFO - Using real prefix '/tools/python27' 15:25:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:25:35 INFO - Installing distribute.............................................................................................................................................................................................done. 15:25:38 INFO - Installing pip.................done. 15:25:38 INFO - Return code: 0 15:25:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:25:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:25:38 INFO - Reading from file tmpfile_stdout 15:25:38 INFO - Output received: 15:25:38 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) 15:25:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:38 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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:25:38 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 15:25:38 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 15:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:38 INFO - 'HOME': '/Users/cltbld', 15:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:38 INFO - 'LOGNAME': 'cltbld', 15:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:38 INFO - 'MOZ_NO_REMOTE': '1', 15:25:38 INFO - 'NO_EM_RESTART': '1', 15:25:38 INFO - 'PAGER': '/bin/cat', 15:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:38 INFO - 'PWD': '/builds/slave/test', 15:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:38 INFO - 'SHELL': '/bin/bash', 15:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:25:38 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:38 INFO - 'USER': 'cltbld', 15:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:38 INFO - 'XPC_FLAGS': '0x0', 15:25:38 INFO - 'XPC_SERVICE_NAME': '0', 15:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:39 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 15:25:39 INFO - Cleaning up... 15:25:39 INFO - Return code: 0 15:25:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:25:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:25:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:25:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:39 INFO - 'HOME': '/Users/cltbld', 15:25:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:39 INFO - 'LOGNAME': 'cltbld', 15:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:39 INFO - 'MOZ_NO_REMOTE': '1', 15:25:39 INFO - 'NO_EM_RESTART': '1', 15:25:39 INFO - 'PAGER': '/bin/cat', 15:25:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:39 INFO - 'PWD': '/builds/slave/test', 15:25:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:39 INFO - 'SHELL': '/bin/bash', 15:25:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:25:39 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:25:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:39 INFO - 'USER': 'cltbld', 15:25:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:39 INFO - 'XPC_FLAGS': '0x0', 15:25:39 INFO - 'XPC_SERVICE_NAME': '0', 15:25:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:39 INFO - Downloading/unpacking psutil>=3.1.1 15:25:39 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 15:25:39 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 15:25:39 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 15:25:39 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 15:25:39 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 15:25:39 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 15:25:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:25:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:25:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:25:42 INFO - Installing collected packages: psutil 15:25:42 INFO - Running setup.py install for psutil 15:25:43 INFO - building 'psutil._psutil_osx' extension 15:25:43 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 15:25:43 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 15:25:43 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 15:25:43 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 15:25:43 INFO - building 'psutil._psutil_posix' extension 15:25:43 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 15:25:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:25:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:25:43 INFO - ^ 15:25:44 INFO - 1 warning generated. 15:25:44 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 15:25:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:25:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:25:44 INFO - Successfully installed psutil 15:25:44 INFO - Cleaning up... 15:25:44 INFO - Return code: 0 15:25:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:44 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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:25:44 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 15:25:44 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 15:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:44 INFO - 'HOME': '/Users/cltbld', 15:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:44 INFO - 'LOGNAME': 'cltbld', 15:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:44 INFO - 'MOZ_NO_REMOTE': '1', 15:25:44 INFO - 'NO_EM_RESTART': '1', 15:25:44 INFO - 'PAGER': '/bin/cat', 15:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:44 INFO - 'PWD': '/builds/slave/test', 15:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:44 INFO - 'SHELL': '/bin/bash', 15:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:25:44 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:44 INFO - 'USER': 'cltbld', 15:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:44 INFO - 'XPC_FLAGS': '0x0', 15:25:44 INFO - 'XPC_SERVICE_NAME': '0', 15:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:25:44 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 15:25:44 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 15:25:44 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 15:25:44 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 15:25:44 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 15:25:44 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 15:25:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:25:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:25:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:25:47 INFO - Installing collected packages: mozsystemmonitor 15:25:47 INFO - Running setup.py install for mozsystemmonitor 15:25:47 INFO - Successfully installed mozsystemmonitor 15:25:47 INFO - Cleaning up... 15:25:47 INFO - Return code: 0 15:25:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:47 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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:25:47 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 15:25:47 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 15:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:47 INFO - 'HOME': '/Users/cltbld', 15:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:47 INFO - 'LOGNAME': 'cltbld', 15:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:47 INFO - 'MOZ_NO_REMOTE': '1', 15:25:47 INFO - 'NO_EM_RESTART': '1', 15:25:47 INFO - 'PAGER': '/bin/cat', 15:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:47 INFO - 'PWD': '/builds/slave/test', 15:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:47 INFO - 'SHELL': '/bin/bash', 15:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:25:47 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:47 INFO - 'USER': 'cltbld', 15:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:47 INFO - 'XPC_FLAGS': '0x0', 15:25:47 INFO - 'XPC_SERVICE_NAME': '0', 15:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:47 INFO - Downloading/unpacking jsonschema==2.5.1 15:25:47 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 15:25:47 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 15:25:47 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 15:25:47 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 15:25:47 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 15:25:47 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 15:25:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:25:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 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 15:25:50 INFO - Downloading functools32-3.2.3-2.tar.gz 15:25:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:25:50 INFO - warning: no files found matching '*.txt' 15:25:50 INFO - no previously-included directories found matching 'build' 15:25:50 INFO - no previously-included directories found matching 'dist' 15:25:50 INFO - no previously-included directories found matching '.git*' 15:25:50 INFO - Installing collected packages: jsonschema, functools32 15:25:50 INFO - Running setup.py install for functools32 15:25:50 INFO - warning: no files found matching '*.txt' 15:25:50 INFO - no previously-included directories found matching 'build' 15:25:50 INFO - no previously-included directories found matching 'dist' 15:25:50 INFO - no previously-included directories found matching '.git*' 15:25:50 INFO - Successfully installed jsonschema functools32 15:25:50 INFO - Cleaning up... 15:25:50 INFO - Return code: 0 15:25:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:25:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:25:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:25:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:25:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:50 INFO - 'HOME': '/Users/cltbld', 15:25:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:50 INFO - 'LOGNAME': 'cltbld', 15:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:50 INFO - 'MOZ_NO_REMOTE': '1', 15:25:50 INFO - 'NO_EM_RESTART': '1', 15:25:50 INFO - 'PAGER': '/bin/cat', 15:25:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:50 INFO - 'PWD': '/builds/slave/test', 15:25:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:50 INFO - 'SHELL': '/bin/bash', 15:25:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:25:50 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:25:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:50 INFO - 'USER': 'cltbld', 15:25:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:50 INFO - 'XPC_FLAGS': '0x0', 15:25:50 INFO - 'XPC_SERVICE_NAME': '0', 15:25:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:25:51 INFO - Cleaning up... 15:25:51 INFO - Return code: 0 15:25:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:25:51 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')]} 15:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:51 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')]} 15:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:51 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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:25:51 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 15:25:51 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 15:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:51 INFO - 'HOME': '/Users/cltbld', 15:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:51 INFO - 'LOGNAME': 'cltbld', 15:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:51 INFO - 'MOZ_NO_REMOTE': '1', 15:25:51 INFO - 'NO_EM_RESTART': '1', 15:25:51 INFO - 'PAGER': '/bin/cat', 15:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:51 INFO - 'PWD': '/builds/slave/test', 15:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:51 INFO - 'SHELL': '/bin/bash', 15:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:25:51 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:51 INFO - 'USER': 'cltbld', 15:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:51 INFO - 'XPC_FLAGS': '0x0', 15:25:51 INFO - 'XPC_SERVICE_NAME': '0', 15:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:51 INFO - Downloading/unpacking blobuploader==1.2.4 15:25:51 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 15:25:51 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 15:25:51 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 15:25:51 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 15:25:51 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 15:25:51 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 15:25:53 INFO - Downloading blobuploader-1.2.4.tar.gz 15:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:25:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:25:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:25:54 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 15:25:54 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 15:25:54 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 15:25:54 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 15:25:54 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 15:25:54 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 15:25:55 INFO - Downloading docopt-0.6.1.tar.gz 15:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:25:55 INFO - Installing collected packages: blobuploader, requests, docopt 15:25:55 INFO - Running setup.py install for blobuploader 15:25:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:25:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:25:55 INFO - Running setup.py install for requests 15:25:55 INFO - Running setup.py install for docopt 15:25:55 INFO - Successfully installed blobuploader requests docopt 15:25:55 INFO - Cleaning up... 15:25:56 INFO - Return code: 0 15:25:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:56 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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:25:56 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 15:25:56 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 15:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:56 INFO - 'HOME': '/Users/cltbld', 15:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:56 INFO - 'LOGNAME': 'cltbld', 15:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:56 INFO - 'MOZ_NO_REMOTE': '1', 15:25:56 INFO - 'NO_EM_RESTART': '1', 15:25:56 INFO - 'PAGER': '/bin/cat', 15:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:56 INFO - 'PWD': '/builds/slave/test', 15:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:56 INFO - 'SHELL': '/bin/bash', 15:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:25:56 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:56 INFO - 'USER': 'cltbld', 15:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:56 INFO - 'XPC_FLAGS': '0x0', 15:25:56 INFO - 'XPC_SERVICE_NAME': '0', 15:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:25:56 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-6NKPzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:25:56 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-S8TT2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:25:56 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-o5BDQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:25:56 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-SIfErw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:25:56 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-UCD5kh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:25:56 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-jsR0_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:25:56 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-AxjmlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-yLvEQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-3xthp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-IMgX8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ju7WuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-I0pb_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-WBt8I3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-taFco7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-FYJYMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-OkjgKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:25:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-1GnCbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:25:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:25:58 INFO - Running setup.py install for manifestparser 15:25:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:25:58 INFO - Running setup.py install for mozcrash 15:25:58 INFO - Running setup.py install for mozdebug 15:25:58 INFO - Running setup.py install for mozdevice 15:25:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:25:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:25:58 INFO - Running setup.py install for mozfile 15:25:58 INFO - Running setup.py install for mozhttpd 15:25:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:25:58 INFO - Running setup.py install for mozinfo 15:25:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:25:59 INFO - Running setup.py install for mozInstall 15:25:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:25:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:25:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:25:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:25:59 INFO - Running setup.py install for mozleak 15:25:59 INFO - Running setup.py install for mozlog 15:25:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:25:59 INFO - Running setup.py install for moznetwork 15:25:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:25:59 INFO - Running setup.py install for mozprocess 15:25:59 INFO - Running setup.py install for mozprofile 15:26:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:26:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:26:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:26:00 INFO - Running setup.py install for mozrunner 15:26:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:26:00 INFO - Running setup.py install for mozscreenshot 15:26:00 INFO - Running setup.py install for moztest 15:26:00 INFO - Running setup.py install for mozversion 15:26:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:26:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:26:00 INFO - Cleaning up... 15:26:00 INFO - Return code: 0 15:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:00 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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:26:00 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 15:26:00 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 15:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:00 INFO - 'HOME': '/Users/cltbld', 15:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:00 INFO - 'LOGNAME': 'cltbld', 15:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:00 INFO - 'MOZ_NO_REMOTE': '1', 15:26:00 INFO - 'NO_EM_RESTART': '1', 15:26:00 INFO - 'PAGER': '/bin/cat', 15:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:00 INFO - 'PWD': '/builds/slave/test', 15:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:00 INFO - 'SHELL': '/bin/bash', 15:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:26:00 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:00 INFO - 'USER': 'cltbld', 15:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:00 INFO - 'XPC_FLAGS': '0x0', 15:26:00 INFO - 'XPC_SERVICE_NAME': '0', 15:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:26:00 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-tMAqMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-HRVtEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-PUjN6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-iv8f0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JzTY2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-k49ef4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-nddqwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-I9xv8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bsglOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:26:01 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ICje0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:26:01 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)) 15:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:26:02 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-kOZ4u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:26:02 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)) 15:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:26:02 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ox8l6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:26:02 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-PwAw0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:26:02 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)) 15:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:26:02 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-YzAWc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:26:02 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)) 15:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:02 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JDc86W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:02 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)) 15:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:26:02 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ZyOKmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:26:02 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-UJYFCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:26:02 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)) 15:26:02 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)) 15:26:02 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)) 15:26:02 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)) 15:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:26:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:26:02 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 15:26:02 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 15:26:02 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 15:26:02 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 15:26:02 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 15:26:02 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 15:26:05 INFO - Downloading blessings-1.6.tar.gz 15:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:26:05 INFO - Installing collected packages: blessings 15:26:05 INFO - Running setup.py install for blessings 15:26:05 INFO - Successfully installed blessings 15:26:05 INFO - Cleaning up... 15:26:05 INFO - Return code: 0 15:26:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:26:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:26:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:26:06 INFO - Reading from file tmpfile_stdout 15:26:06 INFO - Current package versions: 15:26:06 INFO - blessings == 1.6 15:26:06 INFO - blobuploader == 1.2.4 15:26:06 INFO - docopt == 0.6.1 15:26:06 INFO - functools32 == 3.2.3-2 15:26:06 INFO - jsonschema == 2.5.1 15:26:06 INFO - manifestparser == 1.1 15:26:06 INFO - mozInstall == 1.12 15:26:06 INFO - mozcrash == 1.0 15:26:06 INFO - mozdebug == 0.1 15:26:06 INFO - mozdevice == 0.48 15:26:06 INFO - mozfile == 1.2 15:26:06 INFO - mozhttpd == 0.7 15:26:06 INFO - mozinfo == 0.9 15:26:06 INFO - mozleak == 0.1 15:26:06 INFO - mozlog == 3.4 15:26:06 INFO - moznetwork == 0.27 15:26:06 INFO - mozprocess == 0.23 15:26:06 INFO - mozprofile == 0.28 15:26:06 INFO - mozrunner == 6.13 15:26:06 INFO - mozscreenshot == 0.1 15:26:06 INFO - mozsystemmonitor == 0.3 15:26:06 INFO - moztest == 0.7 15:26:06 INFO - mozversion == 1.4 15:26:06 INFO - psutil == 3.1.1 15:26:06 INFO - requests == 1.2.3 15:26:06 INFO - wsgiref == 0.1.2 15:26:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:06 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')]} 15:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:06 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')]} 15:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:26:07 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 15:26:07 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 15:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:07 INFO - 'HOME': '/Users/cltbld', 15:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:07 INFO - 'LOGNAME': 'cltbld', 15:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:07 INFO - 'MOZ_NO_REMOTE': '1', 15:26:07 INFO - 'NO_EM_RESTART': '1', 15:26:07 INFO - 'PAGER': '/bin/cat', 15:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:07 INFO - 'PWD': '/builds/slave/test', 15:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:07 INFO - 'SHELL': '/bin/bash', 15:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:26:07 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:07 INFO - 'USER': 'cltbld', 15:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:07 INFO - 'XPC_FLAGS': '0x0', 15:26:07 INFO - 'XPC_SERVICE_NAME': '0', 15:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 INFO - Cleaning up... 15:26:07 INFO - Return code: 0 15:26:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:26:07 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')]} 15:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 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')]} 15:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 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 0x1018c1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101837570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70157ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, '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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:26:07 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 15:26:07 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 15:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:07 INFO - 'HOME': '/Users/cltbld', 15:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:07 INFO - 'LOGNAME': 'cltbld', 15:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:07 INFO - 'MOZ_NO_REMOTE': '1', 15:26:07 INFO - 'NO_EM_RESTART': '1', 15:26:07 INFO - 'PAGER': '/bin/cat', 15:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:07 INFO - 'PWD': '/builds/slave/test', 15:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:07 INFO - 'SHELL': '/bin/bash', 15:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:26:07 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:07 INFO - 'USER': 'cltbld', 15:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:07 INFO - 'XPC_FLAGS': '0x0', 15:26:07 INFO - 'XPC_SERVICE_NAME': '0', 15:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:26:08 INFO - Cleaning up... 15:26:08 INFO - Return code: 0 15:26:08 INFO - Running post-action listener: _resource_record_post_action 15:26:08 INFO - Running post-action listener: _start_resource_monitoring 15:26:08 INFO - Starting resource monitoring. 15:26:08 INFO - [mozharness: 2016-11-15 23:26:08.063847Z] Finished create-virtualenv step (success) 15:26:08 INFO - [mozharness: 2016-11-15 23:26:08.064859Z] Running install step. 15:26:08 INFO - Running pre-action listener: _resource_record_pre_action 15:26:08 INFO - Running main action method: install 15:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:26:08 INFO - Reading from file tmpfile_stdout 15:26:08 INFO - Detecting whether we're running mozinstall >=1.0... 15:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:26:08 INFO - Reading from file tmpfile_stdout 15:26:08 INFO - Output received: 15:26:08 INFO - Usage: mozinstall [options] installer 15:26:08 INFO - Options: 15:26:08 INFO - -h, --help show this help message and exit 15:26:08 INFO - -d DEST, --destination=DEST 15:26:08 INFO - Directory to install application into. [default: 15:26:08 INFO - "/builds/slave/test"] 15:26:08 INFO - --app=APP Application being installed. [default: firefox] 15:26:08 INFO - mkdir: /builds/slave/test/build/application 15:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:26:42 INFO - Reading from file tmpfile_stdout 15:26:42 INFO - Output received: 15:26:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:26:42 INFO - Running post-action listener: _resource_record_post_action 15:26:42 INFO - [mozharness: 2016-11-15 23:26:42.793221Z] Finished install step (success) 15:26:42 INFO - [mozharness: 2016-11-15 23:26:42.793374Z] Running run-tests step. 15:26:42 INFO - Running pre-action listener: _resource_record_pre_action 15:26:42 INFO - Running main action method: run_tests 15:26:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:26:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:26:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:26:42 INFO - 2016-11-15 15:26:42.807 screenresolution[1641:7480] starting screenresolution argv=screenresolution get 15:26:42 INFO - 2016-11-15 15:26:42.810 screenresolution[1641:7480] Display 0: 1600x1200x32@60 15:26:42 INFO - 2016-11-15 15:26:42.816 screenresolution[1642:7482] starting screenresolution argv=screenresolution list 15:26:42 INFO - Available Modes on Display 0 15:26:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:26:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:26:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:26:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:26:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:26:43 INFO - Intel Iris: 15:26:43 INFO - Chipset Model: Intel Iris 15:26:43 INFO - Type: GPU 15:26:43 INFO - Bus: Built-In 15:26:43 INFO - VRAM (Dynamic, Max): 1536 MB 15:26:43 INFO - Vendor: Intel (0x8086) 15:26:43 INFO - Device ID: 0x0a2e 15:26:43 INFO - Revision ID: 0x0009 15:26:43 INFO - Displays: 15:26:43 INFO - Display: 15:26:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:26:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:26:43 INFO - Display Serial Number: 1600x1200 60 15:26:43 INFO - Main Display: Yes 15:26:43 INFO - Mirror: Off 15:26:43 INFO - Online: Yes 15:26:43 INFO - Rotation: Supported 15:26:43 INFO - Return code: 0 15:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:26:43 INFO - Python 2.7.3 15:26:43 INFO - Return code: 0 15:26:43 INFO - grabbing minidump binary from tooltool 15:26:43 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')]} 15:26:43 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 0x1016d5d30>, '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 0x1014fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016cdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:26:43 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 15:26:43 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 15:26:43 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 15:26:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:26:43 INFO - Return code: 0 15:26:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:26:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:26:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:26:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:26:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 15:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --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 15:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hp5QsmLk4Z/Render', 15:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:43 INFO - 'HOME': '/Users/cltbld', 15:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:43 INFO - 'LOGNAME': 'cltbld', 15:26:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:26:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:43 INFO - 'MOZ_NO_REMOTE': '1', 15:26:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:26:43 INFO - 'NO_EM_RESTART': '1', 15:26:43 INFO - 'PAGER': '/bin/cat', 15:26:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:43 INFO - 'PWD': '/builds/slave/test', 15:26:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:43 INFO - 'SHELL': '/bin/bash', 15:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RQ0MEedHJm/Listeners', 15:26:43 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:43 INFO - 'USER': 'cltbld', 15:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:43 INFO - 'XPC_FLAGS': '0x0', 15:26:43 INFO - 'XPC_SERVICE_NAME': '0', 15:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 15:26:43 INFO - mozversion application_buildid: 20161115120248 15:26:43 INFO - mozversion application_changeset: 8616872c1e2299bd55ae435654de757f3175c494 15:26:43 INFO - mozversion application_display_name: Nightly 15:26:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:26:43 INFO - mozversion application_name: Firefox 15:26:43 INFO - mozversion application_remotingname: firefox 15:26:43 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:26:43 INFO - mozversion application_vendor: Mozilla 15:26:43 INFO - mozversion application_version: 52.0a1 15:26:43 INFO - mozversion platform_buildid: 20161115120248 15:26:43 INFO - mozversion platform_changeset: 8616872c1e2299bd55ae435654de757f3175c494 15:26:43 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:26:43 INFO - mozversion platform_version: 52.0a1 15:26:43 INFO - using testdate: 1479252403 15:26:43 INFO - actual date: 1479252403 15:26:43 INFO - starting webserver on 'localhost:49240' 15:26:43 INFO - SUITE-START | Running 2 tests 15:26:43 INFO - TEST-START | dromaeo_css 15:26:43 INFO - operating with platform_type : mac_ 15:26:43 INFO - Initialising browser for dromaeo_css test... 15:26:43 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpbx4qdE/profile http://localhost:49240/getInfo.html) 15:26:46 INFO - PROCESS | 1652 | 2016-11-15 15:26:46.440 plugin-container[1654:7660] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 15:26:46 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:46 INFO - PROCESS | 1652 | 2016-11-15 15:26:46.444 plugin-container[1654:7660] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 15:26:46 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:46 INFO - PROCESS | 1652 | 2016-11-15 15:26:46.444 plugin-container[1654:7660] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:26:46 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 15:26:46 INFO - PROCESS | 1652 | colorDepth:24 15:26:46 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 15:26:46 INFO - PROCESS | 1652 | __metrics 15:26:46 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3174: TypeError: this.tabs is undefined 15:26:47 INFO - TEST-INFO | 1652: exit 0 15:26:47 INFO - Browser initialized. 15:26:47 INFO - Running cycle 1/1 for dromaeo_css test... 15:26:47 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpbx4qdE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:26:49 INFO - PROCESS | 1655 | 2016-11-15 15:26:49.704 plugin-container[1656:7785] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 15:26:49 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:49 INFO - PROCESS | 1655 | 2016-11-15 15:26:49.705 plugin-container[1656:7785] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 15:26:49 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:49 INFO - PROCESS | 1655 | 2016-11-15 15:26:49.705 plugin-container[1656:7785] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:26:59 INFO - PROCESS | 1655 | RSS: Main: 243417088 15:26:59 INFO - PROCESS | 1655 | 15:27:49 INFO - PROCESS | 1655 | 1479252469068 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:30:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 15:30:05 INFO - PROCESS | 1655 | RSS: Main: 257269760 15:30:05 INFO - PROCESS | 1655 | 15:33:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 15:33:08 INFO - PROCESS | 1655 | RSS: Main: 266297344 15:33:08 INFO - PROCESS | 1655 | 15:36:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 15:36:10 INFO - PROCESS | 1655 | RSS: Main: 270745600 15:36:10 INFO - PROCESS | 1655 | 15:39:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 15:39:13 INFO - PROCESS | 1655 | RSS: Main: 269504512 15:39:13 INFO - PROCESS | 1655 | 15:42:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 15:42:16 INFO - PROCESS | 1655 | RSS: Main: 273932288 15:42:16 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 15:45:19 INFO - PROCESS | 1655 | RSS: Main: 276885504 15:45:19 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | __start_tp_report 15:45:19 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 15:45:19 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 15:45:19 INFO - PROCESS | 1655 | |i|pagename|runs| 15:45:19 INFO - PROCESS | 1655 | |0;dojo.html;3397.1676939149897;3404.9148771280716;3453.2575496268328;3454.9309013819725;3460.567429071366;4157.3971774092815;5412.269343638608;5661.801836935708;5749.971250143754;5791.594588378815;4452.532484089164;5494.011078005958;5649.067903795879;5749.511291540213;5770.855728606787;5205.089109405858;5279.894402111955;5320.9467905320835;5321.946780532203;5361.035013697533;5237.685738855681;5334;5357.517823395913;5388.515033646971;5446.537044351235;4314.590113939171;5320.9467905320835;5339.172428273624;5363.222332761748;5387.622866399353;4270.957290427087;5169.405518365361;5256.579473642099;5280.1815718563685;5350.705711185883;5156.3296771419855;5195.246689230078;5222.399424066243;5225.242339860699;5301.151815709468;5516.779328826842;5723.799667011676;5875.236219291465;5890.322612899528;5921.437463440966;6154.784582539634;6206.131141640174;6223.595466294676;6252.937470625327;6268.4671802896355;6158.107074474221;6173.351798061229;6197.938020619781;6250.093736408241;6268.467180289681;5898.8230353089475;5981;5985.25184351956;6018.067199584028;6023.759049637965;6155.138280640714;6304.810855674337;6388.616682999035;6403.583767055126;6417.358264173638;6126.877462450726;6128.228138398591;6129.908988904127;6133;6228.28374736902;6234.937650623527;6239.532035097386;6239.750409983641;6261.405166509175;6275.497960163176;6107.908381374283;6135.2326666699655;6145.033589251397;6202.751889924442;6212.316645169028;6158.860610787019;6208.50331973441;6245.469135123565;6273.7176827042895;6333.746650133943;4923.606111511071;4969.006198760262;4976.90046199074;4994.825181118644;4997.100521906092;4396.362527433568;4420.911581768347;4448.399466072104;4466.285394336891;4482.574155455227;5905.232319798484;5920.585559010828;5987.820365389045;5998.7300571473515;6037.909431358533;6276.435120839146;6286.748530058838;6287;6295.150154729145;6337.461315788118;5965.075413310944;6031.336552979256;6032.457078862835;6033.493920623954;6035.004224302941;5358.142697168434;5395.946040539623;5397.78408863641;5452.700101494456;5558.944410555923;6292.402221789014;6314.305426403092;6343.238811342669;6363.077353783583;6475.708593113415;6262.248530176225;6276.121343012073;6280.309165991828;6287.811365659038;6304.779332723333;6387.712552935036;6465.191851018622;6482.57863238888;6484.254310754135;6497.707603157964;6111.04482360895;6119.015143218386;6141.570090093829;6143.969280153749;6180.6600636965395;6207.96896015517;6208.937910620836;6214.5960512566535;6246.531510136849;6250.749970001149;5988.910166347596;6072.757089716361;6103.11504831788;6117.938820611737;6122.448979591858;6038.396160383926;6079.635221886524;6093.482054025458;6110.816675499742;6130.540209484396;5191;5465.262189604511;5597.384287728265;6017.428344307284;6034.939650603438;4938.777755001105;6030;6102.389761023862;6194.752209911553;6201.2868520118955;5944.851378715567;6010.398960103955;6082.148499210202;6100.702285529835;6141.1402403664415 15:45:19 INFO - PROCESS | 1655 | |1;ext.html;471.6344832754615;489.71107046842354;507.8146476536065;523.8323736404351;524.9055170069388;35445.936621901354;37367.62632373675;37686;37765.62234377655;37802;39723.205535889334;40334;40388.596114038926;40405.797971010106;40483.79758101213;9085.227755640779;9264.583093760762;9270.21203197729;9302.162805347518;9308.301877359192;7461.8507629847645;9239.769005774842;9243.26053915685;9253.074692530761;9282.907170928305;9208.539573021322;9237.722868313962;9261.907380926206;9277.211437027827;9293.21007714345;9202.079792020777;9246.445213287225;9264.841894763122;9266.351355405124;9285.350025498217;9080.40977336471;9126.13301736334;9157.908420915772;9161;9234.538273086318;37632;37964;38066;38069;38214.23571528582;3158.0683698309053;3161.873525058995;3252.9512057319157;3619.1314084619767;3821.1211421372964;29508.557371639443;29803.40393192124;30109.247268818453;30612.846935765403;30655;2983.1199796059996;2989.671136175019;3168.5405616185526;3189.346184032268;3297.3570153820015;363290;363973;365441;453706;454377.4562254368;54020.72989635027;54397.1840422394;54435.45564544304;54458;54480.45519544754;50815.491845081815;61509.769804604555;62002.68998655068;62133.689331553054;62230.64385492972;15121.243937803285;15168.24158792056;15294;15304.923475382551;15373.615659608598;11684.357360345093;11785.88214117865;11787.233829801036;11807.291562506278;11836.644900652995;15512.767308490382;16503;16564.420245291356;16821.663566728595;16876.071816050237;3100.3024319527926;3221.3074189049466;6581.572197807127;6665.166854143232;6706.429953454011;847.427986109386;848.4315508609246;994.7264502261792;1008.0725732326277;1066.338869900651;24449.877750611133;29098.272543186165;29387.265318366783;29642.40715185684;29933;981.8379967305458;999.4602914426274;1019.7960407918446;1039.5841663334727;1049.396596956753;15232.847671523365;15285.923570382078;15375;15399.615009624626;15631.062136271632;636617;639619;645653;646120;646786;55170;55419;55719.44280557142;55966.72016639891;56072.15891761627;1209.9697507562205;1215.8419405477232;1232.8952039076778;1343.8723321284463;1361.4962463888426;28442.431151376444;28952.565711513922;29073.98241061553;29735.553966690084;29955;49413.25880111873;49769.502304976486;49784.751076244385;49920.750396247786;49981.750456238886;545484;555630.2218488882;581782.0910895418;584966;588897;26220.8688956554;26261.212163635122;26545.7345426551;27269.72730272672;27462.5880611787;43396.34905476483;43952.12095758131;44047.55952440434;44351;44661.776691116334;34610.307793844484;34942.301153977285;36526.634733652325;37536.624633753316;38545.6145438542;33598;36773;36830.26339473142;37471;37681.81159094297;36458.088547786516;37039.629603703615;37152.44271335878;37366.62633373631;37430.6256937438;27280;29748.363839989008;32332.515012275304;33225.66774332226;33522.832385837915;22130.89345532685;22650;24354;24739.752602474466;25035 15:45:19 INFO - PROCESS | 1655 | |2;jquery.html;296.3968324459725;370.90171104657264;380.34010990930733;382.20882772660605;399.48266994242454;15485.225738713061;17689.469315920527;19324.033798310098;19560;19613.607727845436;18618.81381186191;19339.516512087204;19492;19504.414867553995;19654.115564799584;5054.1155297822825;5269.6311258211945;5279.524842764151;5285.709285989269;5291.391489978663;5180.507851754077;5301.1783173608155;5587.776488940438;5656.773729050833;5676.091825307931;5555.972220138894;5620.831375058755;5623.465770751772;5625.915611265835;5626.099824028156;5338.679679219259;5348.438413966536;5379.677219366811;5388.110961691335;5398.757055932492;5220.843374698765;5233.4243233244315;5233.502817232356;5242.423333433319;5249.238860365225;21657.783422165892;23801.285961421185;24442.388940276636;24525.877370613034;24593.13924012669;1952.15521332341;2340.9063637454483;2402.68765060541;2485.8508489490687;2534.9479965814166;9692.806143877153;9848.556814943344;10080.94959525205;10198.286119971604;10207.540660670287;1489.448903905551;1671.1811212505906;1749.1778863934057;1873.531617095726;1878.370745800145;112373;116392;117566.82433175729;119614.60770784623;122710;11394;11663.708407289885;11665.533378664928;11793.941030294965;11870.821937670942;14286.142831430148;14430.062045966979;14430.855691443161;14436.061655992327;14459.27703614815;6981.79054628362;7602.049743782028;8059.5164290142775;8164;8193.672253109928;6360.363963603695;7300.488965772452;7947.880781788278;7969.800754981172;8114.43198976066;4381.496127945264;4553.726776393427;4558.908821823545;4635.513271106553;4647.581717645428;1738.9826101739075;1777.4934143768965;1906.428071578521;1909.5798924236647;1955.0908827395328;814.6944895664126;901.2924853989717;916.1434059154578;927.2535608834818;979.1628157925014;20153.79846201519;20184;20201.696974545393;20322.796772032387;20382;786.4573444323398;797.366093955307;863.9675587672671;888.6623083228461;934.5934518484345;9914.256430767722;10583;10607.310524815863;10678.1991350649;10727.409992450333;572728.2727172675;583208;583769;584265;589889;96095.50758401502;119046;120146.3992680031;122099.40055694694;124643;959.5250351076262;966.9274804389698;979.7501637082498;989.7525618595351;1020.73460900165;17756.3785267515;17892.373766918095;17960.371387001393;18585.72121418153;19482.92843893543;42811;47612.2858157121;48798.268025980346;50732;53478.73260633672;69042.30957690359;70394;73601;74110.25889741034;75738;7623.504472209289;7945.497168391938;8119.59402029908;8132.471389359673;8143.755687329152;7881.684732610632;11157.21899467062;12395.75208495843;12509.937450312691;12665.683357916127;9833.46312825748;10029.297949143782;10294.3823370598;10664.680059598224;11144.367953706604;9307.67423140214;10228.386296822215;10404.895951040393;10479.738006549896;10997.725056873322;9155.77110572241;10272.589096436059;11168.162387820783;11325.830112548483;11353.602623908124;6676.432503237279;7892.881606776018;8132.3900707448365;8169;8313.916860831314;9922.355046921926;10700.304480209074;10915;10930.797612262486;11028.172887033665 15:45:19 INFO - PROCESS | 1655 | |3;mootools.html;1458.8614081662238;1477.6010477171164;1659.9834001659988;1736.3315123677396;1760.1727188221532;7655.234476552342;7888.250616191467;8088.91911080889;8531.445456045352;8569.871451928218;8093;8395.244428001479;8409.957950210255;8413.284870785976;8429.494230346168;2297.6208925527308;2324.9186278480256;2333.801626861719;2338.1232037985756;2342.7774361435636;1921.1066853912928;1945.8443324534073;2324.174917904145;2352.6118190498546;2542.834715743471;1985.5433250352428;2250.3698964290024;2268.9546209075806;2284.7715228477186;2286.496970666459;1885.5663197464592;2270.92051778187;2340.707411573545;2341.1688850458013;2341.730700969393;2264.275431861805;2293.20884294918;2316.3861576682193;2319.4549280918973;2325.953480930372;7899;7941.36469082472;7970.362371010306;7974.760757177294;7998.680052797881;5115.335006449138;5288.127458969266;5292.682439053669;5293.523582877559;5301.946980530184;3238.5627940228005;3340.682635149657;3575.4636804479537;3595.1191957970204;3595.514605528246;1031.83490641497;1042.8957104289586;1053.531178625512;1075.6289080267295;1078.5955266774959;10841.186910981707;10869.293495922819;10887.401192934385;11007.284526505751;11019.614313498989;4492.9775351123035;4552.0668263006;4745.050989802054;4757.1209372298445;4763.285507173882;9226.07739226072;9384.06159384056;9384.202342800801;9393.201577865959;9398.483083430478;5181.326427564392;5186.455422180692;5196.428392876781;5200.245964335226;5213.218017297436;4246.426732391088;4263.488381394191;4712.387389639393;4753.572178503882;4756.191447453941;3726.6087060858217;3779.7732136071922;3839.5968423315708;3856.59505751892;3870.2646497165447;1258.1067442116016;1260.4517035089582;1263.911526193176;1271.8664540223328;1282.1089342906778;862.7282406042077;871.507598207011;883.7392969074139;898.6834287768451;916.2192745018835;4365.847195348148;5531.363893152258;5642.887142257132;5667.688277144714;5829;422.2146806939091;434.9325854492559;436.3585529271965;438.1303113320057;444.5265791931582;3754.568224654145;3784.640459156431;3865.4588357629955;3872.3223435898603;3884.281407939484;9916.702498925044;10136.391816491034;10274.280800344053;10279.486025698685;10303.072723454776;9492.382995105434;9787;10007.09936105754;10255.435951022766;10284.434356110485;7665.61671916402;7807.804804879924;7898.368130549542;7931.881021784562;7948.880766788503;5601.215829783752;5698.544116470755;5774.2782152230975;5784.595078344644;5839.649621022752;9402.247820174232;9404.435733855717;9427.198688111588;9443.102905223992;9453.43279403238;9088.63645454201;9397.154256116983;9406.294527910572;9531.428114313163;9552.57013434411;6410.519211059096;6602.240742314695;6900.689468974008;7050.964745176241;7221.31397517235;7639.885401718868;7923;7934.64294106755;7941.920580794118;7954.323882469824;6991.6853741580735;7102.325279098477;7145.571265724073;7182.3894968928225;7251.057362542836;6132.63204207749;6834.179898412222;6957.379695932383;7004.124484439445;7097.858042839218;6565.277819439762;6640.369164929324;6736.292689267556;6749.662516874235;6752.7298908045095;4773.307870358848;4773.618110551217;4811.927821082617;4848.127337079219;4852.1508735971765;4435.733855968652;4775.140474714584;4829.66192366531;4915.18899381598;4979.278004689372 15:45:19 INFO - PROCESS | 1655 | |4;prototype.html;260.94188067386744;291.4738896292193;300.5416739472305;302.321288706853;312.624850179784;4629.18989176894;5284.445133261005;5355.571554275649;5520.3841483828055;5719.656820590768;4274.931267183204;5222.477752224776;5374.339701847591;5473.370562385327;5498.3779784963435;3122.609673790776;3390.355832391838;3791.350762418343;3833.9424908626393;4023.6923000025035;3682.6317368263094;3760.3795373763296;3913.9021524461973;4075.612816782401;4108.58108779954;3443.7443474524225;3768.7361884668085;3910.081130934228;4121.670266378697;4138.255114079479;4117.608827161415;4123.340265557496;4149.792510374499;4160.625543701051;4179.498460184797;3189.3940151371166;3484.2683036562353;3587.6053634100226;3617.6201498842506;3636.4545318202217;4341.956580434186;4723.881902952419;4857.377016411134;5325.4940780625775;5452.536534394581;4713.622910167179;4726.3383126362;4738.265568836836;4774.952250477486;4804.183288840905;4764.166270902579;5768.192453056576;5785.871755007775;5821.476067153976;5875.591007686828;3766.6610005099396;3793.468914351993;3879.301725689375;3912.2957867583823;3937.4881265435597;5953.255843019622;6042.519582702223;6046.697665116727;6065.02959526478;6128.080787881809;3666.2667466506273;3974.4833171688074;3987.302222111119;3990.920181596352;3991.820368083443;3852.2680690668685;3889.4943657324366;3958.426028225891;4009.4787677601726;4026.174634199982;4679.953200467951;4683.601893839062;4690.390249267642;4699.882502937386;4737.8341758038305;5383.596230282745;5839.386864379264;5842.328132264759;5884.764609415575;5989.401059894063;1217.9464762979958;1222.712662524306;1223.2354778263584;1242.0125999830157;1286.4883761580106;758.3894964553588;833.8159813065462;846.280661437773;854;889.8042430665244;4628.166929952574;4804.897779819609;4962.826301079445;5154.793808247703;5209.843704688789;5151.742412879341;5604.85987850307;5639.577031722637;5681.403452637496;5713.685852253921;5129.538341549204;5165.612579056586;5170;5223.667964668985;5231.372235331708;2482.4290413205063;2601.1416232643182;2622.069165446277;2813.563897595876;3020.320427903748;4606.746628935373;5739.569532285095;5877.502885782952;5960.4635582797755;6064.575479716377;5490.039243132436;5597.636153650081;5929.940700592939;5974.2233509643465;6089.604175728564;5336.493033161843;5842.795502157404;6215.906761398583;6292.119103325538;6338.922383194776;5320.015797077553;5781.653100813796;6130.570860039755;6133.294671112701;6169;5252.894942101213;5965.940340596539;6002.219711437485;6022.337542870368;6026.638401695912;5773.364929857797;5797.710114494343;6292.741451709768;6350.269718982191;6390.52071094679;3699.9835089875546;3955.4660120883314;3969.841206351829;3976.6818654508147;3992.341263691458;6005.339412664691;6009.2188015557695;6035.969820150871;6044.637321760532;6056;5253.868653283545;5371;5393.43368946255;5647.519960803378;5885.411458854252;5747.9137812932;5904.675242861684;5908.670549126412;5928.547505861092;5970.0746384310505;4566.543345665407;5437.048516509516;5797.594168408171;5800.56495762811;5899.731557715112;4910.852674419773;5377.489138531833;5643.830685079454;5894.1728064300205;5906.616069955439;5497.47773961473;5600.915986260291;5716.685582293014;5759.337676167294;5782.848347650456 15:45:19 INFO - PROCESS | 1655 | |5;yui.html;566.9914951275734;641.4771960851905;692.4252870117799;706.5372181221298;775.5385545600369;14035.649108772273;14827.92586037071;14837.332320045596;14839.035462694917;14861.331240094194;13255;13374.866251337484;13389.397477113529;13394.531191408303;13397.062205645585;632.9841753956142;654.2835595023441;783.5925318834217;790.8141586727144;799.6281728996016;795.6976348987376;797.2904115337354;803.5138741061661;808.4179390838616;808.8544062068826;847.4872702015253;849.2908421468081;850.196564246787;851.3019324154196;851.5912362066211;842.4945032980224;848.1687945813138;848.6605357856845;849.1593322610632;853.2704537620307;620.2092332276338;624.6158612453354;625.4027403829332;626.8683576448951;628.2994461175801;14101.71796564063;14451;14497.275136243252;14544.345504452325;14623.268836558236;13573.637877706384;13668.93165534166;13769.931150344284;13842.792358114637;13926.025178237527;11037.724056898642;11262.936248375972;11282.774344513145;11321;11649.825252621218;3603.351396748584;3641.563012438498;3644.362236608583;3644.908877278063;3737.6449237322413;97287;98308.01691983132;99536;99715;99884;9178.816423671622;10533.104686101615;10538.894611053945;10697.625583104555;11009.449527523751;17800.643987120184;19965.60068798645;20417.387478375673;20760.273390431263;20791.8960405197;7366.265727625181;7518.736844210426;7606.429517786189;7664.21909108948;7743.225677432324;4090.1615168890307;4194.3708443733685;4196.097838964637;4278.165757677223;4322.22200003996;3322.1196382958537;3429.3998550253605;3433.6051354094097;3434.587849458081;3434.690877820958;589.6226415094343;631.4569470255576;631.7630888416844;632.6077831744255;634.1027446163627;602.5089552015094;612.3723183736705;627.4948666323562;651.2022772104134;653.8267359149829;8444.662213511514;8707.825843483095;8835.514046727492;8961.417507861968;9038.909610903807;496.76651973573087;538.4050624060435;568.2442351672266;644.8779124323643;651.3721446700512;3519.419295816213;4076.9796151019054;4311.525732169459;4330.415393921843;4355.6062060141085;110139;112971.87028129777;118327.2250916237;119332;121990.9004549769;80463.59768201121;87690.2309769018;99573;101573.42919568637;104667.42998855023;90327.09672903319;91027.54486227658;91163.54418227999;92218;93690;8422.789430264293;8470.364722645703;8513.361497887807;8623.396362254582;8697.21725044749;55552.72223638856;56456.717716411156;56869.71565142148;56887.431125688214;59300.70349648224;77235.22764772506;80926;81445.00492433683;82909.17090829015;84182.73725894239;11074.723131921766;11180.440977950908;11245.943770281096;11407.600733974272;11973.76052478963;11329.660110196708;11798.469068892093;11809.114316426474;12595.05537084704;13113.213207207598;12841.550545730855;12926.612201633967;13270.27013514266;13277.336133193108;13685.452581896612;11318.94340528292;12756.61730148097;13088.280144591758;13254.66863328386;13579.52471663487;12352.938235308766;12430.564930227398;12637.575685268253;12957.805632915326;13261;7881.448298619271;10568.260221784401;10572.735681608021;11069.833952490559;11307.717307067389;10431.217658675478;10914.617988370686;11160.944195278971;11207;11275.267107637977 15:45:19 INFO - PROCESS | 1655 | __end_tp_report 15:45:19 INFO - PROCESS | 1655 | __start_cc_report 15:45:19 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,380 15:45:19 INFO - PROCESS | 1655 | __end_cc_report 15:45:19 INFO - PROCESS | 1655 | __startTimestamp1479253519966__endTimestamp 15:45:19 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | ------- Summary: start ------- 15:45:19 INFO - PROCESS | 1655 | Number of tests: 6 15:45:19 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5757.99 Median:6034.94 stddev:672.79 (11.1%) stddev-sans-first:648.86 15:45:19 INFO - PROCESS | 1655 | Values: 3397.2 3404.9 3453.3 3454.9 3460.6 4157.4 5412.3 5661.8 5750.0 5791.6 4452.5 5494.0 5649.1 5749.5 5770.9 5205.1 5279.9 5320.9 5321.9 5361.0 5237.7 5334.0 5357.5 5388.5 5446.5 4314.6 5320.9 5339.2 5363.2 5387.6 4271.0 5169.4 5256.6 5280.2 5350.7 5156.3 5195.2 5222.4 5225.2 5301.2 5516.8 5723.8 5875.2 5890.3 5921.4 6154.8 6206.1 6223.6 6252.9 6268.5 6158.1 6173.4 6197.9 6250.1 6268.5 5898.8 5981.0 5985.3 6018.1 6023.8 6155.1 6304.8 6388.6 6403.6 6417.4 6126.9 6128.2 6129.9 6133.0 6228.3 6234.9 6239.5 6239.8 6261.4 6275.5 6107.9 6135.2 6145.0 6202.8 6212.3 6158.9 6208.5 6245.5 6273.7 6333.7 4923.6 4969.0 4976.9 4994.8 4997.1 4396.4 4420.9 4448.4 4466.3 4482.6 5905.2 5920.6 5987.8 5998.7 6037.9 6276.4 6286.7 6287.0 6295.2 6337.5 5965.1 6031.3 6032.5 6033.5 6035.0 5358.1 5395.9 5397.8 5452.7 5558.9 6292.4 6314.3 6343.2 6363.1 6475.7 6262.2 6276.1 6280.3 6287.8 6304.8 6387.7 6465.2 6482.6 6484.3 6497.7 6111.0 6119.0 6141.6 6144.0 6180.7 6208.0 6208.9 6214.6 6246.5 6250.7 5988.9 6072.8 6103.1 6117.9 6122.4 6038.4 6079.6 6093.5 6110.8 6130.5 5191.0 5465.3 5597.4 6017.4 6034.9 4938.8 6030.0 6102.4 6194.8 6201.3 5944.9 6010.4 6082.1 6100.7 6141.1 15:45:19 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:66482.97 Median:37683.91 stddev:146833.64 (389.6%) stddev-sans-first:147161.90 15:45:19 INFO - PROCESS | 1655 | Values: 471.6 489.7 507.8 523.8 524.9 35445.9 37367.6 37686.0 37765.6 37802.0 39723.2 40334.0 40388.6 40405.8 40483.8 9085.2 9264.6 9270.2 9302.2 9308.3 7461.9 9239.8 9243.3 9253.1 9282.9 9208.5 9237.7 9261.9 9277.2 9293.2 9202.1 9246.4 9264.8 9266.4 9285.4 9080.4 9126.1 9157.9 9161.0 9234.5 37632.0 37964.0 38066.0 38069.0 38214.2 3158.1 3161.9 3253.0 3619.1 3821.1 29508.6 29803.4 30109.2 30612.8 30655.0 2983.1 2989.7 3168.5 3189.3 3297.4 363290.0 363973.0 365441.0 453706.0 454377.5 54020.7 54397.2 54435.5 54458.0 54480.5 50815.5 61509.8 62002.7 62133.7 62230.6 15121.2 15168.2 15294.0 15304.9 15373.6 11684.4 11785.9 11787.2 11807.3 11836.6 15512.8 16503.0 16564.4 16821.7 16876.1 3100.3 3221.3 6581.6 6665.2 6706.4 847.4 848.4 994.7 1008.1 1066.3 24449.9 29098.3 29387.3 29642.4 29933.0 981.8 999.5 1019.8 1039.6 1049.4 15232.8 15285.9 15375.0 15399.6 15631.1 636617.0 639619.0 645653.0 646120.0 646786.0 55170.0 55419.0 55719.4 55966.7 56072.2 1210.0 1215.8 1232.9 1343.9 1361.5 28442.4 28952.6 29074.0 29735.6 29955.0 49413.3 49769.5 49784.8 49920.8 49981.8 545484.0 555630.2 581782.1 584966.0 588897.0 26220.9 26261.2 26545.7 27269.7 27462.6 43396.3 43952.1 44047.6 44351.0 44661.8 34610.3 34942.3 36526.6 37536.6 38545.6 33598.0 36773.0 36830.3 37471.0 37681.8 36458.1 37039.6 37152.4 37366.6 37430.6 27280.0 29748.4 32332.5 33225.7 33522.8 22130.9 22650.0 24354.0 24739.8 25035.0 15:45:19 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:33607.41 Median:390.85 stddev:97132.07 (24851.8%) stddev-sans-first:97372.35 15:45:19 INFO - PROCESS | 1655 | Values: 296.4 370.9 380.3 382.2 399.5 15485.2 17689.5 19324.0 19560.0 19613.6 18618.8 19339.5 19492.0 19504.4 19654.1 5054.1 5269.6 5279.5 5285.7 5291.4 5180.5 5301.2 5587.8 5656.8 5676.1 5556.0 5620.8 5623.5 5625.9 5626.1 5338.7 5348.4 5379.7 5388.1 5398.8 5220.8 5233.4 5233.5 5242.4 5249.2 21657.8 23801.3 24442.4 24525.9 24593.1 1952.2 2340.9 2402.7 2485.9 2534.9 9692.8 9848.6 10080.9 10198.3 10207.5 1489.4 1671.2 1749.2 1873.5 1878.4 112373.0 116392.0 117566.8 119614.6 122710.0 11394.0 11663.7 11665.5 11793.9 11870.8 14286.1 14430.1 14430.9 14436.1 14459.3 6981.8 7602.0 8059.5 8164.0 8193.7 6360.4 7300.5 7947.9 7969.8 8114.4 4381.5 4553.7 4558.9 4635.5 4647.6 1739.0 1777.5 1906.4 1909.6 1955.1 814.7 901.3 916.1 927.3 979.2 20153.8 20184.0 20201.7 20322.8 20382.0 786.5 797.4 864.0 888.7 934.6 9914.3 10583.0 10607.3 10678.2 10727.4 572728.3 583208.0 583769.0 584265.0 589889.0 96095.5 119046.0 120146.4 122099.4 124643.0 959.5 966.9 979.8 989.8 1020.7 17756.4 17892.4 17960.4 18585.7 19482.9 42811.0 47612.3 48798.3 50732.0 53478.7 69042.3 70394.0 73601.0 74110.3 75738.0 7623.5 7945.5 8119.6 8132.5 8143.8 7881.7 11157.2 12395.8 12509.9 12665.7 9833.5 10029.3 10294.4 10664.7 11144.4 9307.7 10228.4 10404.9 10479.7 10997.7 9155.8 10272.6 11168.2 11325.8 11353.6 6676.4 7892.9 8132.4 8169.0 8313.9 9922.4 10700.3 10915.0 10930.8 11028.2 15:45:19 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5416.27 Median:4883.67 stddev:3000.10 (61.4%) stddev-sans-first:2993.78 15:45:19 INFO - PROCESS | 1655 | Values: 1458.9 1477.6 1660.0 1736.3 1760.2 7655.2 7888.3 8088.9 8531.4 8569.9 8093.0 8395.2 8410.0 8413.3 8429.5 2297.6 2324.9 2333.8 2338.1 2342.8 1921.1 1945.8 2324.2 2352.6 2542.8 1985.5 2250.4 2269.0 2284.8 2286.5 1885.6 2270.9 2340.7 2341.2 2341.7 2264.3 2293.2 2316.4 2319.5 2326.0 7899.0 7941.4 7970.4 7974.8 7998.7 5115.3 5288.1 5292.7 5293.5 5301.9 3238.6 3340.7 3575.5 3595.1 3595.5 1031.8 1042.9 1053.5 1075.6 1078.6 10841.2 10869.3 10887.4 11007.3 11019.6 4493.0 4552.1 4745.1 4757.1 4763.3 9226.1 9384.1 9384.2 9393.2 9398.5 5181.3 5186.5 5196.4 5200.2 5213.2 4246.4 4263.5 4712.4 4753.6 4756.2 3726.6 3779.8 3839.6 3856.6 3870.3 1258.1 1260.5 1263.9 1271.9 1282.1 862.7 871.5 883.7 898.7 916.2 4365.8 5531.4 5642.9 5667.7 5829.0 422.2 434.9 436.4 438.1 444.5 3754.6 3784.6 3865.5 3872.3 3884.3 9916.7 10136.4 10274.3 10279.5 10303.1 9492.4 9787.0 10007.1 10255.4 10284.4 7665.6 7807.8 7898.4 7931.9 7948.9 5601.2 5698.5 5774.3 5784.6 5839.6 9402.2 9404.4 9427.2 9443.1 9453.4 9088.6 9397.2 9406.3 9531.4 9552.6 6410.5 6602.2 6900.7 7051.0 7221.3 7639.9 7923.0 7934.6 7941.9 7954.3 6991.7 7102.3 7145.6 7182.4 7251.1 6132.6 6834.2 6957.4 7004.1 7097.9 6565.3 6640.4 6736.3 6749.7 6752.7 4773.3 4773.6 4811.9 4848.1 4852.2 4435.7 4775.1 4829.7 4915.2 4979.3 15:45:19 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4646.83 Median:5242.13 stddev:1490.18 (28.4%) stddev-sans-first:1457.55 15:45:19 INFO - PROCESS | 1655 | Values: 260.9 291.5 300.5 302.3 312.6 4629.2 5284.4 5355.6 5520.4 5719.7 4274.9 5222.5 5374.3 5473.4 5498.4 3122.6 3390.4 3791.4 3833.9 4023.7 3682.6 3760.4 3913.9 4075.6 4108.6 3443.7 3768.7 3910.1 4121.7 4138.3 4117.6 4123.3 4149.8 4160.6 4179.5 3189.4 3484.3 3587.6 3617.6 3636.5 4342.0 4723.9 4857.4 5325.5 5452.5 4713.6 4726.3 4738.3 4775.0 4804.2 4764.2 5768.2 5785.9 5821.5 5875.6 3766.7 3793.5 3879.3 3912.3 3937.5 5953.3 6042.5 6046.7 6065.0 6128.1 3666.3 3974.5 3987.3 3990.9 3991.8 3852.3 3889.5 3958.4 4009.5 4026.2 4680.0 4683.6 4690.4 4699.9 4737.8 5383.6 5839.4 5842.3 5884.8 5989.4 1217.9 1222.7 1223.2 1242.0 1286.5 758.4 833.8 846.3 854.0 889.8 4628.2 4804.9 4962.8 5154.8 5209.8 5151.7 5604.9 5639.6 5681.4 5713.7 5129.5 5165.6 5170.0 5223.7 5231.4 2482.4 2601.1 2622.1 2813.6 3020.3 4606.7 5739.6 5877.5 5960.5 6064.6 5490.0 5597.6 5929.9 5974.2 6089.6 5336.5 5842.8 6215.9 6292.1 6338.9 5320.0 5781.7 6130.6 6133.3 6169.0 5252.9 5965.9 6002.2 6022.3 6026.6 5773.4 5797.7 6292.7 6350.3 6390.5 3700.0 3955.5 3969.8 3976.7 3992.3 6005.3 6009.2 6036.0 6044.6 6056.0 5253.9 5371.0 5393.4 5647.5 5885.4 5747.9 5904.7 5908.7 5928.5 5970.1 4566.5 5437.0 5797.6 5800.6 5899.7 4910.9 5377.5 5643.8 5894.2 5906.6 5497.5 5600.9 5716.7 5759.3 5782.8 15:45:19 INFO - PROCESS | 1655 | 15:45:19 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:21345.13 Median:4294.85 stddev:32170.17 (749.0%) stddev-sans-first:32222.59 15:45:19 INFO - PROCESS | 1655 | Values: 567.0 641.5 692.4 706.5 775.5 14035.6 14827.9 14837.3 14839.0 14861.3 13255.0 13374.9 13389.4 13394.5 13397.1 633.0 654.3 783.6 790.8 799.6 795.7 797.3 803.5 808.4 808.9 847.5 849.3 850.2 851.3 851.6 842.5 848.2 848.7 849.2 853.3 620.2 624.6 625.4 626.9 628.3 14101.7 14451.0 14497.3 14544.3 14623.3 13573.6 13668.9 13769.9 13842.8 13926.0 11037.7 11262.9 11282.8 11321.0 11649.8 3603.4 3641.6 3644.4 3644.9 3737.6 97287.0 98308.0 99536.0 99715.0 99884.0 9178.8 10533.1 10538.9 10697.6 11009.4 17800.6 19965.6 20417.4 20760.3 20791.9 7366.3 7518.7 7606.4 7664.2 7743.2 4090.2 4194.4 4196.1 4278.2 4322.2 3322.1 3429.4 3433.6 3434.6 3434.7 589.6 631.5 631.8 632.6 634.1 602.5 612.4 627.5 651.2 653.8 8444.7 8707.8 8835.5 8961.4 9038.9 496.8 538.4 568.2 644.9 651.4 3519.4 4077.0 4311.5 4330.4 4355.6 110139.0 112971.9 118327.2 119332.0 121990.9 80463.6 87690.2 99573.0 101573.4 104667.4 90327.1 91027.5 91163.5 92218.0 93690.0 8422.8 8470.4 8513.4 8623.4 8697.2 55552.7 56456.7 56869.7 56887.4 59300.7 77235.2 80926.0 81445.0 82909.2 84182.7 11074.7 11180.4 11245.9 11407.6 11973.8 11329.7 11798.5 11809.1 12595.1 13113.2 12841.6 12926.6 13270.3 13277.3 13685.5 11318.9 12756.6 13088.3 13254.7 13579.5 12352.9 12430.6 12637.6 12957.8 13261.0 7881.4 10568.3 10572.7 11069.8 11307.7 10431.2 10914.6 11160.9 11207.0 11275.3 15:45:19 INFO - PROCESS | 1655 | -------- Summary: end -------- 15:45:19 INFO - PROCESS | 1655 | 15:45:20 INFO - TEST-INFO | 1655: exit 0 15:45:20 INFO - TEST-OK | dromaeo_css | took 1117284ms 15:45:20 INFO - TEST-START | kraken 15:45:20 INFO - operating with platform_type : mac_ 15:45:20 INFO - Initialising browser for kraken test... 15:45:20 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpxvOE4X/profile http://localhost:49240/getInfo.html) 15:45:23 INFO - PROCESS | 1668 | 2016-11-15 15:45:23.453 plugin-container[1669:10180] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 15:45:23 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:23 INFO - PROCESS | 1668 | 2016-11-15 15:45:23.458 plugin-container[1669:10180] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 15:45:23 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:23 INFO - PROCESS | 1668 | 2016-11-15 15:45:23.458 plugin-container[1669:10180] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:23 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 15:45:23 INFO - PROCESS | 1668 | colorDepth:24 15:45:23 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 15:45:23 INFO - PROCESS | 1668 | __metrics 15:45:23 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3174: TypeError: this.tabs is undefined 15:45:24 INFO - TEST-INFO | 1668: exit 0 15:45:24 INFO - Browser initialized. 15:45:24 INFO - Running cycle 1/1 for kraken test... 15:45:24 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpxvOE4X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:26 INFO - PROCESS | 1670 | 2016-11-15 15:45:26.783 plugin-container[1671:10293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 15:45:26 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:26 INFO - PROCESS | 1670 | 2016-11-15 15:45:26.784 plugin-container[1671:10293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 15:45:26 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:26 INFO - PROCESS | 1670 | 2016-11-15 15:45:26.784 plugin-container[1671:10293] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:36 INFO - PROCESS | 1670 | RSS: Main: 243675136 15:45:36 INFO - PROCESS | 1670 | 15:45:39 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 15:45:40 INFO - PROCESS | 1670 | RSS: Main: 254586880 15:45:40 INFO - PROCESS | 1670 | 15:45:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 15:45:43 INFO - PROCESS | 1670 | RSS: Main: 262111232 15:45:43 INFO - PROCESS | 1670 | 15:45:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 15:45:47 INFO - PROCESS | 1670 | RSS: Main: 263401472 15:45:47 INFO - PROCESS | 1670 | 15:45:49 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 15:45:50 INFO - PROCESS | 1670 | RSS: Main: 264216576 15:45:50 INFO - PROCESS | 1670 | 15:45:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:45:53 INFO - PROCESS | 1670 | RSS: Main: 265179136 15:45:53 INFO - PROCESS | 1670 | 15:45:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 15:45:56 INFO - PROCESS | 1670 | RSS: Main: 265326592 15:45:56 INFO - PROCESS | 1670 | 15:45:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 15:46:00 INFO - PROCESS | 1670 | RSS: Main: 265527296 15:46:00 INFO - PROCESS | 1670 | 15:46:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 15:46:03 INFO - PROCESS | 1670 | RSS: Main: 265805824 15:46:03 INFO - PROCESS | 1670 | 15:46:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:46:06 INFO - PROCESS | 1670 | RSS: Main: 265482240 15:46:06 INFO - PROCESS | 1670 | 15:46:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:46:08 INFO - PROCESS | 1670 | RSS: Main: 265469952 15:46:08 INFO - PROCESS | 1670 | 15:46:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:46:11 INFO - PROCESS | 1670 | RSS: Main: 265519104 15:46:11 INFO - PROCESS | 1670 | 15:46:14 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:46:15 INFO - PROCESS | 1670 | RSS: Main: 265928704 15:46:15 INFO - PROCESS | 1670 | 15:46:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:46:18 INFO - PROCESS | 1670 | RSS: Main: 265674752 15:46:18 INFO - PROCESS | 1670 | 15:46:20 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 15:46:21 INFO - PROCESS | 1670 | RSS: Main: 267026432 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | __start_tp_report 15:46:21 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 15:46:21 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 15:46:21 INFO - PROCESS | 1670 | |i|pagename|runs| 15:46:21 INFO - PROCESS | 1670 | |0;ai-astar;105;117;120;117;117;118;117;118;117;117 15:46:21 INFO - PROCESS | 1670 | |1;audio-beat-detection;149;133;142;164;146;144;102;145;136;142 15:46:21 INFO - PROCESS | 1670 | |2;audio-dft;208;234;196;197;199;200;195;198;200;192 15:46:21 INFO - PROCESS | 1670 | |3;audio-fft;81;67;83;74;77;53;64;80;104;87 15:46:21 INFO - PROCESS | 1670 | |4;audio-oscillator;95;69;88;74;70;72;87;68;72;87 15:46:21 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;89;92;89;81;133;132;90;86;89;104 15:46:21 INFO - PROCESS | 1670 | |6;imaging-darkroom;118;171;169;101;103;100;100;98;99;105 15:46:21 INFO - PROCESS | 1670 | |7;imaging-desaturate;132;143;87;87;88;87;91;92;89;88 15:46:21 INFO - PROCESS | 1670 | |8;json-parse-financial;48;88;46;51;60;75;85;89;79;59 15:46:21 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;69;74;71;84;67;97;59;68;86;101 15:46:21 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;89;86;107;88;88;86;87;87;90;87 15:46:21 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;153;173;159;147;150;184;170;174;198;143 15:46:21 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;177;178;176;165;177;179;177;177;175;179 15:46:21 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;68;69;70;68;72;68;63;83;92;88 15:46:21 INFO - PROCESS | 1670 | __end_tp_report 15:46:21 INFO - PROCESS | 1670 | __start_cc_report 15:46:21 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,994 15:46:21 INFO - PROCESS | 1670 | __end_cc_report 15:46:21 INFO - PROCESS | 1670 | __startTimestamp1479253581021__endTimestamp 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | ------- Summary: start ------- 15:46:21 INFO - PROCESS | 1670 | Number of tests: 14 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:116.30 Median:117.00 stddev:4.08 (3.5%) stddev-sans-first:1.01 15:46:21 INFO - PROCESS | 1670 | Values: 105.0 117.0 120.0 117.0 117.0 118.0 117.0 118.0 117.0 117.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:144.50 stddev:15.81 (10.9%) stddev-sans-first:16.45 15:46:21 INFO - PROCESS | 1670 | Values: 149.0 133.0 142.0 164.0 146.0 144.0 102.0 145.0 136.0 142.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:201.90 Median:199.50 stddev:12.03 (6.0%) stddev-sans-first:12.56 15:46:21 INFO - PROCESS | 1670 | Values: 208.0 234.0 196.0 197.0 199.0 200.0 195.0 198.0 200.0 192.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:77.00 Median:78.50 stddev:13.92 (17.7%) stddev-sans-first:14.69 15:46:21 INFO - PROCESS | 1670 | Values: 81.0 67.0 83.0 74.0 77.0 53.0 64.0 80.0 104.0 87.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:80.50 stddev:9.91 (12.3%) stddev-sans-first:8.44 15:46:21 INFO - PROCESS | 1670 | Values: 95.0 69.0 88.0 74.0 70.0 72.0 87.0 68.0 72.0 87.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:89.00 stddev:18.83 (21.2%) stddev-sans-first:19.65 15:46:21 INFO - PROCESS | 1670 | Values: 89.0 92.0 89.0 81.0 133.0 132.0 90.0 86.0 89.0 104.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:116.40 Median:143.50 stddev:28.82 (20.1%) stddev-sans-first:30.56 15:46:21 INFO - PROCESS | 1670 | Values: 118.0 171.0 169.0 101.0 103.0 100.0 100.0 98.0 99.0 105.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:98.40 Median:88.00 stddev:20.84 (23.7%) stddev-sans-first:18.21 15:46:21 INFO - PROCESS | 1670 | Values: 132.0 143.0 87.0 87.0 88.0 87.0 91.0 92.0 89.0 88.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:68.00 Median:77.00 stddev:17.06 (22.1%) stddev-sans-first:16.48 15:46:21 INFO - PROCESS | 1670 | Values: 48.0 88.0 46.0 51.0 60.0 75.0 85.0 89.0 79.0 59.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:77.60 Median:72.50 stddev:13.81 (19.0%) stddev-sans-first:14.29 15:46:21 INFO - PROCESS | 1670 | Values: 69.0 74.0 71.0 84.0 67.0 97.0 59.0 68.0 86.0 101.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:87.50 stddev:6.28 (7.2%) stddev-sans-first:6.65 15:46:21 INFO - PROCESS | 1670 | Values: 89.0 86.0 107.0 88.0 88.0 86.0 87.0 87.0 90.0 87.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:165.10 Median:171.50 stddev:17.74 (10.3%) stddev-sans-first:18.27 15:46:21 INFO - PROCESS | 1670 | Values: 153.0 173.0 159.0 147.0 150.0 184.0 170.0 174.0 198.0 143.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.00 Median:177.00 stddev:4.06 (2.3%) stddev-sans-first:4.28 15:46:21 INFO - PROCESS | 1670 | Values: 177.0 178.0 176.0 165.0 177.0 179.0 177.0 177.0 175.0 179.0 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:71.00 stddev:9.86 (13.9%) stddev-sans-first:10.21 15:46:21 INFO - PROCESS | 1670 | Values: 68.0 69.0 70.0 68.0 72.0 68.0 63.0 83.0 92.0 88.0 15:46:21 INFO - PROCESS | 1670 | -------- Summary: end -------- 15:46:21 INFO - PROCESS | 1670 | 15:46:21 INFO - TEST-INFO | 1670: exit 0 15:46:21 INFO - TEST-OK | kraken | took 61201ms 15:46:21 INFO - SUITE-END | took 1178s 15:46:22 INFO - Completed test suite (00:19:38) 15:46:22 INFO - javascript benchmark 15:46:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7086.843534529858, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3397.1676939149897, 3404.9148771280716, 3453.2575496268328, 3454.9309013819725, 3460.567429071366, 4157.3971774092815, 5412.269343638608, 5661.801836935708, 5749.971250143754, 5791.594588378815, 4452.532484089164, 5494.011078005958, 5649.067903795879, 5749.511291540213, 5770.855728606787, 5205.089109405858, 5279.894402111955, 5320.9467905320835, 5321.946780532203, 5361.035013697533, 5237.685738855681, 5334.0, 5357.517823395913, 5388.515033646971, 5446.537044351235, 4314.590113939171, 5320.9467905320835, 5339.172428273624, 5363.222332761748, 5387.622866399353, 4270.957290427087, 5169.405518365361, 5256.579473642099, 5280.1815718563685, 5350.705711185883, 5156.3296771419855, 5195.246689230078, 5222.399424066243, 5225.242339860699, 5301.151815709468, 5516.779328826842, 5723.799667011676, 5875.236219291465, 5890.322612899528, 5921.437463440966, 6154.784582539634, 6206.131141640174, 6223.595466294676, 6252.937470625327, 6268.4671802896355, 6158.107074474221, 6173.351798061229, 6197.938020619781, 6250.093736408241, 6268.467180289681, 5898.8230353089475, 5981.0, 5985.25184351956, 6018.067199584028, 6023.759049637965, 6155.138280640714, 6304.810855674337, 6388.616682999035, 6403.583767055126, 6417.358264173638, 6126.877462450726, 6128.228138398591, 6129.908988904127, 6133.0, 6228.28374736902, 6234.937650623527, 6239.532035097386, 6239.750409983641, 6261.405166509175, 6275.497960163176, 6107.908381374283, 6135.2326666699655, 6145.033589251397, 6202.751889924442, 6212.316645169028, 6158.860610787019, 6208.50331973441, 6245.469135123565, 6273.7176827042895, 6333.746650133943, 4923.606111511071, 4969.006198760262, 4976.90046199074, 4994.825181118644, 4997.100521906092, 4396.362527433568, 4420.911581768347, 4448.399466072104, 4466.285394336891, 4482.574155455227, 5905.232319798484, 5920.585559010828, 5987.820365389045, 5998.7300571473515, 6037.909431358533, 6276.435120839146, 6286.748530058838, 6287.0, 6295.150154729145, 6337.461315788118, 5965.075413310944, 6031.336552979256, 6032.457078862835, 6033.493920623954, 6035.004224302941, 5358.142697168434, 5395.946040539623, 5397.78408863641, 5452.700101494456, 5558.944410555923, 6292.402221789014, 6314.305426403092, 6343.238811342669, 6363.077353783583, 6475.708593113415, 6262.248530176225, 6276.121343012073, 6280.309165991828, 6287.811365659038, 6304.779332723333, 6387.712552935036, 6465.191851018622, 6482.57863238888, 6484.254310754135, 6497.707603157964, 6111.04482360895, 6119.015143218386, 6141.570090093829, 6143.969280153749, 6180.6600636965395, 6207.96896015517, 6208.937910620836, 6214.5960512566535, 6246.531510136849, 6250.749970001149, 5988.910166347596, 6072.757089716361, 6103.11504831788, 6117.938820611737, 6122.448979591858, 6038.396160383926, 6079.635221886524, 6093.482054025458, 6110.816675499742, 6130.540209484396, 5191.0, 5465.262189604511, 5597.384287728265, 6017.428344307284, 6034.939650603438, 4938.777755001105, 6030.0, 6102.389761023862, 6194.752209911553, 6201.2868520118955, 5944.851378715567, 6010.398960103955, 6082.148499210202, 6100.702285529835, 6141.1402403664415], "value": 5716.353244540125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.6344832754615, 489.71107046842354, 507.8146476536065, 523.8323736404351, 524.9055170069388, 35445.936621901354, 37367.62632373675, 37686.0, 37765.62234377655, 37802.0, 39723.205535889334, 40334.0, 40388.596114038926, 40405.797971010106, 40483.79758101213, 9085.227755640779, 9264.583093760762, 9270.21203197729, 9302.162805347518, 9308.301877359192, 7461.8507629847645, 9239.769005774842, 9243.26053915685, 9253.074692530761, 9282.907170928305, 9208.539573021322, 9237.722868313962, 9261.907380926206, 9277.211437027827, 9293.21007714345, 9202.079792020777, 9246.445213287225, 9264.841894763122, 9266.351355405124, 9285.350025498217, 9080.40977336471, 9126.13301736334, 9157.908420915772, 9161.0, 9234.538273086318, 37632.0, 37964.0, 38066.0, 38069.0, 38214.23571528582, 3158.0683698309053, 3161.873525058995, 3252.9512057319157, 3619.1314084619767, 3821.1211421372964, 29508.557371639443, 29803.40393192124, 30109.247268818453, 30612.846935765403, 30655.0, 2983.1199796059996, 2989.671136175019, 3168.5405616185526, 3189.346184032268, 3297.3570153820015, 363290.0, 363973.0, 365441.0, 453706.0, 454377.4562254368, 54020.72989635027, 54397.1840422394, 54435.45564544304, 54458.0, 54480.45519544754, 50815.491845081815, 61509.769804604555, 62002.68998655068, 62133.689331553054, 62230.64385492972, 15121.243937803285, 15168.24158792056, 15294.0, 15304.923475382551, 15373.615659608598, 11684.357360345093, 11785.88214117865, 11787.233829801036, 11807.291562506278, 11836.644900652995, 15512.767308490382, 16503.0, 16564.420245291356, 16821.663566728595, 16876.071816050237, 3100.3024319527926, 3221.3074189049466, 6581.572197807127, 6665.166854143232, 6706.429953454011, 847.427986109386, 848.4315508609246, 994.7264502261792, 1008.0725732326277, 1066.338869900651, 24449.877750611133, 29098.272543186165, 29387.265318366783, 29642.40715185684, 29933.0, 981.8379967305458, 999.4602914426274, 1019.7960407918446, 1039.5841663334727, 1049.396596956753, 15232.847671523365, 15285.923570382078, 15375.0, 15399.615009624626, 15631.062136271632, 636617.0, 639619.0, 645653.0, 646120.0, 646786.0, 55170.0, 55419.0, 55719.44280557142, 55966.72016639891, 56072.15891761627, 1209.9697507562205, 1215.8419405477232, 1232.8952039076778, 1343.8723321284463, 1361.4962463888426, 28442.431151376444, 28952.565711513922, 29073.98241061553, 29735.553966690084, 29955.0, 49413.25880111873, 49769.502304976486, 49784.751076244385, 49920.750396247786, 49981.750456238886, 545484.0, 555630.2218488882, 581782.0910895418, 584966.0, 588897.0, 26220.8688956554, 26261.212163635122, 26545.7345426551, 27269.72730272672, 27462.5880611787, 43396.34905476483, 43952.12095758131, 44047.55952440434, 44351.0, 44661.776691116334, 34610.307793844484, 34942.301153977285, 36526.634733652325, 37536.624633753316, 38545.6145438542, 33598.0, 36773.0, 36830.26339473142, 37471.0, 37681.81159094297, 36458.088547786516, 37039.629603703615, 37152.44271335878, 37366.62633373631, 37430.6256937438, 27280.0, 29748.363839989008, 32332.515012275304, 33225.66774332226, 33522.832385837915, 22130.89345532685, 22650.0, 24354.0, 24739.752602474466, 25035.0], "value": 18863.08350066278, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.3968324459725, 370.90171104657264, 380.34010990930733, 382.20882772660605, 399.48266994242454, 15485.225738713061, 17689.469315920527, 19324.033798310098, 19560.0, 19613.607727845436, 18618.81381186191, 19339.516512087204, 19492.0, 19504.414867553995, 19654.115564799584, 5054.1155297822825, 5269.6311258211945, 5279.524842764151, 5285.709285989269, 5291.391489978663, 5180.507851754077, 5301.1783173608155, 5587.776488940438, 5656.773729050833, 5676.091825307931, 5555.972220138894, 5620.831375058755, 5623.465770751772, 5625.915611265835, 5626.099824028156, 5338.679679219259, 5348.438413966536, 5379.677219366811, 5388.110961691335, 5398.757055932492, 5220.843374698765, 5233.4243233244315, 5233.502817232356, 5242.423333433319, 5249.238860365225, 21657.783422165892, 23801.285961421185, 24442.388940276636, 24525.877370613034, 24593.13924012669, 1952.15521332341, 2340.9063637454483, 2402.68765060541, 2485.8508489490687, 2534.9479965814166, 9692.806143877153, 9848.556814943344, 10080.94959525205, 10198.286119971604, 10207.540660670287, 1489.448903905551, 1671.1811212505906, 1749.1778863934057, 1873.531617095726, 1878.370745800145, 112373.0, 116392.0, 117566.82433175729, 119614.60770784623, 122710.0, 11394.0, 11663.708407289885, 11665.533378664928, 11793.941030294965, 11870.821937670942, 14286.142831430148, 14430.062045966979, 14430.855691443161, 14436.061655992327, 14459.27703614815, 6981.79054628362, 7602.049743782028, 8059.5164290142775, 8164.0, 8193.672253109928, 6360.363963603695, 7300.488965772452, 7947.880781788278, 7969.800754981172, 8114.43198976066, 4381.496127945264, 4553.726776393427, 4558.908821823545, 4635.513271106553, 4647.581717645428, 1738.9826101739075, 1777.4934143768965, 1906.428071578521, 1909.5798924236647, 1955.0908827395328, 814.6944895664126, 901.2924853989717, 916.1434059154578, 927.2535608834818, 979.1628157925014, 20153.79846201519, 20184.0, 20201.696974545393, 20322.796772032387, 20382.0, 786.4573444323398, 797.366093955307, 863.9675587672671, 888.6623083228461, 934.5934518484345, 9914.256430767722, 10583.0, 10607.310524815863, 10678.1991350649, 10727.409992450333, 572728.2727172675, 583208.0, 583769.0, 584265.0, 589889.0, 96095.50758401502, 119046.0, 120146.3992680031, 122099.40055694694, 124643.0, 959.5250351076262, 966.9274804389698, 979.7501637082498, 989.7525618595351, 1020.73460900165, 17756.3785267515, 17892.373766918095, 17960.371387001393, 18585.72121418153, 19482.92843893543, 42811.0, 47612.2858157121, 48798.268025980346, 50732.0, 53478.73260633672, 69042.30957690359, 70394.0, 73601.0, 74110.25889741034, 75738.0, 7623.504472209289, 7945.497168391938, 8119.59402029908, 8132.471389359673, 8143.755687329152, 7881.684732610632, 11157.21899467062, 12395.75208495843, 12509.937450312691, 12665.683357916127, 9833.46312825748, 10029.297949143782, 10294.3823370598, 10664.680059598224, 11144.367953706604, 9307.67423140214, 10228.386296822215, 10404.895951040393, 10479.738006549896, 10997.725056873322, 9155.77110572241, 10272.589096436059, 11168.162387820783, 11325.830112548483, 11353.602623908124, 6676.432503237279, 7892.881606776018, 8132.3900707448365, 8169.0, 8313.916860831314, 9922.355046921926, 10700.304480209074, 10915.0, 10930.797612262486, 11028.172887033665], "value": 9108.333814396612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.8614081662238, 1477.6010477171164, 1659.9834001659988, 1736.3315123677396, 1760.1727188221532, 7655.234476552342, 7888.250616191467, 8088.91911080889, 8531.445456045352, 8569.871451928218, 8093.0, 8395.244428001479, 8409.957950210255, 8413.284870785976, 8429.494230346168, 2297.6208925527308, 2324.9186278480256, 2333.801626861719, 2338.1232037985756, 2342.7774361435636, 1921.1066853912928, 1945.8443324534073, 2324.174917904145, 2352.6118190498546, 2542.834715743471, 1985.5433250352428, 2250.3698964290024, 2268.9546209075806, 2284.7715228477186, 2286.496970666459, 1885.5663197464592, 2270.92051778187, 2340.707411573545, 2341.1688850458013, 2341.730700969393, 2264.275431861805, 2293.20884294918, 2316.3861576682193, 2319.4549280918973, 2325.953480930372, 7899.0, 7941.36469082472, 7970.362371010306, 7974.760757177294, 7998.680052797881, 5115.335006449138, 5288.127458969266, 5292.682439053669, 5293.523582877559, 5301.946980530184, 3238.5627940228005, 3340.682635149657, 3575.4636804479537, 3595.1191957970204, 3595.514605528246, 1031.83490641497, 1042.8957104289586, 1053.531178625512, 1075.6289080267295, 1078.5955266774959, 10841.186910981707, 10869.293495922819, 10887.401192934385, 11007.284526505751, 11019.614313498989, 4492.9775351123035, 4552.0668263006, 4745.050989802054, 4757.1209372298445, 4763.285507173882, 9226.07739226072, 9384.06159384056, 9384.202342800801, 9393.201577865959, 9398.483083430478, 5181.326427564392, 5186.455422180692, 5196.428392876781, 5200.245964335226, 5213.218017297436, 4246.426732391088, 4263.488381394191, 4712.387389639393, 4753.572178503882, 4756.191447453941, 3726.6087060858217, 3779.7732136071922, 3839.5968423315708, 3856.59505751892, 3870.2646497165447, 1258.1067442116016, 1260.4517035089582, 1263.911526193176, 1271.8664540223328, 1282.1089342906778, 862.7282406042077, 871.507598207011, 883.7392969074139, 898.6834287768451, 916.2192745018835, 4365.847195348148, 5531.363893152258, 5642.887142257132, 5667.688277144714, 5829.0, 422.2146806939091, 434.9325854492559, 436.3585529271965, 438.1303113320057, 444.5265791931582, 3754.568224654145, 3784.640459156431, 3865.4588357629955, 3872.3223435898603, 3884.281407939484, 9916.702498925044, 10136.391816491034, 10274.280800344053, 10279.486025698685, 10303.072723454776, 9492.382995105434, 9787.0, 10007.09936105754, 10255.435951022766, 10284.434356110485, 7665.61671916402, 7807.804804879924, 7898.368130549542, 7931.881021784562, 7948.880766788503, 5601.215829783752, 5698.544116470755, 5774.2782152230975, 5784.595078344644, 5839.649621022752, 9402.247820174232, 9404.435733855717, 9427.198688111588, 9443.102905223992, 9453.43279403238, 9088.63645454201, 9397.154256116983, 9406.294527910572, 9531.428114313163, 9552.57013434411, 6410.519211059096, 6602.240742314695, 6900.689468974008, 7050.964745176241, 7221.31397517235, 7639.885401718868, 7923.0, 7934.64294106755, 7941.920580794118, 7954.323882469824, 6991.6853741580735, 7102.325279098477, 7145.571265724073, 7182.3894968928225, 7251.057362542836, 6132.63204207749, 6834.179898412222, 6957.379695932383, 7004.124484439445, 7097.858042839218, 6565.277819439762, 6640.369164929324, 6736.292689267556, 6749.662516874235, 6752.7298908045095, 4773.307870358848, 4773.618110551217, 4811.927821082617, 4848.127337079219, 4852.1508735971765, 4435.733855968652, 4775.140474714584, 4829.66192366531, 4915.18899381598, 4979.278004689372], "value": 4312.5348149454085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.94188067386744, 291.4738896292193, 300.5416739472305, 302.321288706853, 312.624850179784, 4629.18989176894, 5284.445133261005, 5355.571554275649, 5520.3841483828055, 5719.656820590768, 4274.931267183204, 5222.477752224776, 5374.339701847591, 5473.370562385327, 5498.3779784963435, 3122.609673790776, 3390.355832391838, 3791.350762418343, 3833.9424908626393, 4023.6923000025035, 3682.6317368263094, 3760.3795373763296, 3913.9021524461973, 4075.612816782401, 4108.58108779954, 3443.7443474524225, 3768.7361884668085, 3910.081130934228, 4121.670266378697, 4138.255114079479, 4117.608827161415, 4123.340265557496, 4149.792510374499, 4160.625543701051, 4179.498460184797, 3189.3940151371166, 3484.2683036562353, 3587.6053634100226, 3617.6201498842506, 3636.4545318202217, 4341.956580434186, 4723.881902952419, 4857.377016411134, 5325.4940780625775, 5452.536534394581, 4713.622910167179, 4726.3383126362, 4738.265568836836, 4774.952250477486, 4804.183288840905, 4764.166270902579, 5768.192453056576, 5785.871755007775, 5821.476067153976, 5875.591007686828, 3766.6610005099396, 3793.468914351993, 3879.301725689375, 3912.2957867583823, 3937.4881265435597, 5953.255843019622, 6042.519582702223, 6046.697665116727, 6065.02959526478, 6128.080787881809, 3666.2667466506273, 3974.4833171688074, 3987.302222111119, 3990.920181596352, 3991.820368083443, 3852.2680690668685, 3889.4943657324366, 3958.426028225891, 4009.4787677601726, 4026.174634199982, 4679.953200467951, 4683.601893839062, 4690.390249267642, 4699.882502937386, 4737.8341758038305, 5383.596230282745, 5839.386864379264, 5842.328132264759, 5884.764609415575, 5989.401059894063, 1217.9464762979958, 1222.712662524306, 1223.2354778263584, 1242.0125999830157, 1286.4883761580106, 758.3894964553588, 833.8159813065462, 846.280661437773, 854.0, 889.8042430665244, 4628.166929952574, 4804.897779819609, 4962.826301079445, 5154.793808247703, 5209.843704688789, 5151.742412879341, 5604.85987850307, 5639.577031722637, 5681.403452637496, 5713.685852253921, 5129.538341549204, 5165.612579056586, 5170.0, 5223.667964668985, 5231.372235331708, 2482.4290413205063, 2601.1416232643182, 2622.069165446277, 2813.563897595876, 3020.320427903748, 4606.746628935373, 5739.569532285095, 5877.502885782952, 5960.4635582797755, 6064.575479716377, 5490.039243132436, 5597.636153650081, 5929.940700592939, 5974.2233509643465, 6089.604175728564, 5336.493033161843, 5842.795502157404, 6215.906761398583, 6292.119103325538, 6338.922383194776, 5320.015797077553, 5781.653100813796, 6130.570860039755, 6133.294671112701, 6169.0, 5252.894942101213, 5965.940340596539, 6002.219711437485, 6022.337542870368, 6026.638401695912, 5773.364929857797, 5797.710114494343, 6292.741451709768, 6350.269718982191, 6390.52071094679, 3699.9835089875546, 3955.4660120883314, 3969.841206351829, 3976.6818654508147, 3992.341263691458, 6005.339412664691, 6009.2188015557695, 6035.969820150871, 6044.637321760532, 6056.0, 5253.868653283545, 5371.0, 5393.43368946255, 5647.519960803378, 5885.411458854252, 5747.9137812932, 5904.675242861684, 5908.670549126412, 5928.547505861092, 5970.0746384310505, 4566.543345665407, 5437.048516509516, 5797.594168408171, 5800.56495762811, 5899.731557715112, 4910.852674419773, 5377.489138531833, 5643.830685079454, 5894.1728064300205, 5906.616069955439, 5497.47773961473, 5600.915986260291, 5716.685582293014, 5759.337676167294, 5782.848347650456], "value": 4154.644436432664, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.9914951275734, 641.4771960851905, 692.4252870117799, 706.5372181221298, 775.5385545600369, 14035.649108772273, 14827.92586037071, 14837.332320045596, 14839.035462694917, 14861.331240094194, 13255.0, 13374.866251337484, 13389.397477113529, 13394.531191408303, 13397.062205645585, 632.9841753956142, 654.2835595023441, 783.5925318834217, 790.8141586727144, 799.6281728996016, 795.6976348987376, 797.2904115337354, 803.5138741061661, 808.4179390838616, 808.8544062068826, 847.4872702015253, 849.2908421468081, 850.196564246787, 851.3019324154196, 851.5912362066211, 842.4945032980224, 848.1687945813138, 848.6605357856845, 849.1593322610632, 853.2704537620307, 620.2092332276338, 624.6158612453354, 625.4027403829332, 626.8683576448951, 628.2994461175801, 14101.71796564063, 14451.0, 14497.275136243252, 14544.345504452325, 14623.268836558236, 13573.637877706384, 13668.93165534166, 13769.931150344284, 13842.792358114637, 13926.025178237527, 11037.724056898642, 11262.936248375972, 11282.774344513145, 11321.0, 11649.825252621218, 3603.351396748584, 3641.563012438498, 3644.362236608583, 3644.908877278063, 3737.6449237322413, 97287.0, 98308.01691983132, 99536.0, 99715.0, 99884.0, 9178.816423671622, 10533.104686101615, 10538.894611053945, 10697.625583104555, 11009.449527523751, 17800.643987120184, 19965.60068798645, 20417.387478375673, 20760.273390431263, 20791.8960405197, 7366.265727625181, 7518.736844210426, 7606.429517786189, 7664.21909108948, 7743.225677432324, 4090.1615168890307, 4194.3708443733685, 4196.097838964637, 4278.165757677223, 4322.22200003996, 3322.1196382958537, 3429.3998550253605, 3433.6051354094097, 3434.587849458081, 3434.690877820958, 589.6226415094343, 631.4569470255576, 631.7630888416844, 632.6077831744255, 634.1027446163627, 602.5089552015094, 612.3723183736705, 627.4948666323562, 651.2022772104134, 653.8267359149829, 8444.662213511514, 8707.825843483095, 8835.514046727492, 8961.417507861968, 9038.909610903807, 496.76651973573087, 538.4050624060435, 568.2442351672266, 644.8779124323643, 651.3721446700512, 3519.419295816213, 4076.9796151019054, 4311.525732169459, 4330.415393921843, 4355.6062060141085, 110139.0, 112971.87028129777, 118327.2250916237, 119332.0, 121990.9004549769, 80463.59768201121, 87690.2309769018, 99573.0, 101573.42919568637, 104667.42998855023, 90327.09672903319, 91027.54486227658, 91163.54418227999, 92218.0, 93690.0, 8422.789430264293, 8470.364722645703, 8513.361497887807, 8623.396362254582, 8697.21725044749, 55552.72223638856, 56456.717716411156, 56869.71565142148, 56887.431125688214, 59300.70349648224, 77235.22764772506, 80926.0, 81445.00492433683, 82909.17090829015, 84182.73725894239, 11074.723131921766, 11180.440977950908, 11245.943770281096, 11407.600733974272, 11973.76052478963, 11329.660110196708, 11798.469068892093, 11809.114316426474, 12595.05537084704, 13113.213207207598, 12841.550545730855, 12926.612201633967, 13270.27013514266, 13277.336133193108, 13685.452581896612, 11318.94340528292, 12756.61730148097, 13088.280144591758, 13254.66863328386, 13579.52471663487, 12352.938235308766, 12430.564930227398, 12637.575685268253, 12957.805632915326, 13261.0, 7881.448298619271, 10568.260221784401, 10572.735681608021, 11069.833952490559, 11307.717307067389, 10431.217658675478, 10914.617988370686, 11160.944195278971, 11207.0, 11275.267107637977], "value": 7198.389806890288, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1577.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 120.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 117.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 133.0, 142.0, 164.0, 146.0, 144.0, 102.0, 145.0, 136.0, 142.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 234.0, 196.0, 197.0, 199.0, 200.0, 195.0, 198.0, 200.0, 192.0], "value": 201.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 67.0, 83.0, 74.0, 77.0, 53.0, 64.0, 80.0, 104.0, 87.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 69.0, 88.0, 74.0, 70.0, 72.0, 87.0, 68.0, 72.0, 87.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 89.0, 81.0, 133.0, 132.0, 90.0, 86.0, 89.0, 104.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 171.0, 169.0, 101.0, 103.0, 100.0, 100.0, 98.0, 99.0, 105.0], "value": 116.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 143.0, 87.0, 87.0, 88.0, 87.0, 91.0, 92.0, 89.0, 88.0], "value": 98.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 88.0, 46.0, 51.0, 60.0, 75.0, 85.0, 89.0, 79.0, 59.0], "value": 68.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 71.0, 84.0, 67.0, 97.0, 59.0, 68.0, 86.0, 101.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 107.0, 88.0, 88.0, 86.0, 87.0, 87.0, 90.0, 87.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 173.0, 159.0, 147.0, 150.0, 184.0, 170.0, 174.0, 198.0, 143.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 176.0, 165.0, 177.0, 179.0, 177.0, 177.0, 175.0, 179.0], "value": 176.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 68.0, 72.0, 68.0, 63.0, 83.0, 92.0, 88.0], "value": 74.1, "unit": "score"}]}]} 15:46:22 INFO - Return code: 0 15:46:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:46:22 ERROR - # TBPL SUCCESS # 15:46:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:46:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:46:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:46:22 INFO - 2016-11-15 15:46:22.321 screenresolution[1675:10683] starting screenresolution argv=screenresolution get 15:46:22 INFO - 2016-11-15 15:46:22.324 screenresolution[1675:10683] Display 0: 1600x1200x32@60 15:46:22 INFO - 2016-11-15 15:46:22.330 screenresolution[1676:10686] starting screenresolution argv=screenresolution list 15:46:22 INFO - Available Modes on Display 0 15:46:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:46:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:46:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:46:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:46:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:46:22 INFO - Intel Iris: 15:46:22 INFO - Chipset Model: Intel Iris 15:46:22 INFO - Type: GPU 15:46:22 INFO - Bus: Built-In 15:46:22 INFO - VRAM (Dynamic, Max): 1536 MB 15:46:22 INFO - Vendor: Intel (0x8086) 15:46:22 INFO - Device ID: 0x0a2e 15:46:22 INFO - Revision ID: 0x0009 15:46:22 INFO - Displays: 15:46:22 INFO - Display: 15:46:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:46:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:46:22 INFO - Display Serial Number: 1600x1200 60 15:46:22 INFO - Main Display: Yes 15:46:22 INFO - Mirror: Off 15:46:22 INFO - Online: Yes 15:46:22 INFO - Rotation: Supported 15:46:22 INFO - Return code: 0 15:46:22 INFO - Running post-action listener: _resource_record_post_action 15:46:22 INFO - [mozharness: 2016-11-15 23:46:22.566055Z] Finished run-tests step (success) 15:46:22 INFO - Running post-run listener: _resource_record_post_run 15:46:22 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 39553536; Write bytes: 703566848; Read time: 384; Write time: 1892 15:46:22 INFO - TinderboxPrint: CPU usage
54.6% 15:46:22 INFO - TinderboxPrint: I/O read bytes / time
39,553,536 / 384 15:46:22 INFO - TinderboxPrint: I/O write bytes / time
703,566,848 / 1,892 15:46:22 INFO - TinderboxPrint: CPU idle
2,204.7 (45.4%) 15:46:22 INFO - TinderboxPrint: CPU system
978.0 (20.1%) 15:46:22 INFO - TinderboxPrint: CPU user
1,671.0 (34.4%) 15:46:22 INFO - TinderboxPrint: Swap in / out
437,673,984 / 0 15:46:22 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 383087104; Write bytes: 379788800; Read time: 29530; Write time: 723 15:46:22 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 38623744; Write bytes: 299210240; Read time: 371; Write time: 1117 15:46:23 INFO - Running post-run listener: _upload_blobber_files 15:46:23 INFO - Blob upload gear active. 15:46:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:46:23 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 15:46:23 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:46:23 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:46:23 INFO - (blobuploader) - INFO - Open directory for files ... 15:46:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:46:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:46:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:24 INFO - (blobuploader) - INFO - Done attempting. 15:46:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:46:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:46:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:25 INFO - (blobuploader) - INFO - Done attempting. 15:46:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:46:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:46:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:27 INFO - (blobuploader) - INFO - Done attempting. 15:46:27 INFO - (blobuploader) - INFO - Iteration through files over. 15:46:27 INFO - Return code: 0 15:46:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:46:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:46:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d3e18c8fb5e62ef58b7ed12f049d6a11bab44f5a7e4562255c2934f66a7644953cef40cb53179ca32f2da68a748f1c9010fc2c976cd06b5e47d73c4649832b8e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f064036e3f73b080f6c0b671b6a5c23a3072d38e2a331b527251f080d6f0b6605bd43edc1a6448d32b7b19973f4c40174bf7bd8705ddbcfad4e6e793da32810e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/af1017c6e5a9c73fc8500a26383bd19fb2b0939c6fd93f91f1c1a8171b570a471548c4c786876db98e90fd12b05281238a9c61bd18982f9c9b75b05d119e2458"} 15:46:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:46:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:46:27 INFO - Contents: 15:46:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d3e18c8fb5e62ef58b7ed12f049d6a11bab44f5a7e4562255c2934f66a7644953cef40cb53179ca32f2da68a748f1c9010fc2c976cd06b5e47d73c4649832b8e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f064036e3f73b080f6c0b671b6a5c23a3072d38e2a331b527251f080d6f0b6605bd43edc1a6448d32b7b19973f4c40174bf7bd8705ddbcfad4e6e793da32810e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/af1017c6e5a9c73fc8500a26383bd19fb2b0939c6fd93f91f1c1a8171b570a471548c4c786876db98e90fd12b05281238a9c61bd18982f9c9b75b05d119e2458"} 15:46:27 INFO - Running post-run listener: copy_logs_to_upload_dir 15:46:27 INFO - Copying logs to upload dir... 15:46:27 INFO - mkdir: /builds/slave/test/build/upload/logs 15:46:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.411355 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-15 15:46:27.437760) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 15:46:27.442414) ========= 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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/Larch/sha512/d3e18c8fb5e62ef58b7ed12f049d6a11bab44f5a7e4562255c2934f66a7644953cef40cb53179ca32f2da68a748f1c9010fc2c976cd06b5e47d73c4649832b8e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f064036e3f73b080f6c0b671b6a5c23a3072d38e2a331b527251f080d6f0b6605bd43edc1a6448d32b7b19973f4c40174bf7bd8705ddbcfad4e6e793da32810e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/af1017c6e5a9c73fc8500a26383bd19fb2b0939c6fd93f91f1c1a8171b570a471548c4c786876db98e90fd12b05281238a9c61bd18982f9c9b75b05d119e2458"} build_url:https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010741 build_url: 'https://queue.taskcluster.net/v1/task/dvdu9aQ6R0uWGZZ32z5uqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d3e18c8fb5e62ef58b7ed12f049d6a11bab44f5a7e4562255c2934f66a7644953cef40cb53179ca32f2da68a748f1c9010fc2c976cd06b5e47d73c4649832b8e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f064036e3f73b080f6c0b671b6a5c23a3072d38e2a331b527251f080d6f0b6605bd43edc1a6448d32b7b19973f4c40174bf7bd8705ddbcfad4e6e793da32810e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/af1017c6e5a9c73fc8500a26383bd19fb2b0939c6fd93f91f1c1a8171b570a471548c4c786876db98e90fd12b05281238a9c61bd18982f9c9b75b05d119e2458"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 15:46:27.474788) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 15:46:27.475159) ========= 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.Hp5QsmLk4Z/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.RQ0MEedHJm/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.004993 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 15:46:27.526494) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 15:46:27.526838) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 15:46:27.527351) ========= ========= Total master_lag: 0.15 =========