builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0090 starttime: 1484959696.07 results: success (0) buildid: 20170120144225 builduid: c1215c97759d498b8e1479f8270be8af revision: ccec56819e76733f28ae7f046e4602824ddbed10 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:16.068830) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:16.069753) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:16.070418) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:16.099742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:16.100042) ========= 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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-20 16:48:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 67.1M=0s 2017-01-20 16:48:16 (67.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118433 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:16.242872) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:16.243233) ========= 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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.027798 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:16.296246) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-20 16:48:16.297038) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ccec56819e76733f28ae7f046e4602824ddbed10 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ccec56819e76733f28ae7f046e4602824ddbed10 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WyPd9Pggqp/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.SLjrQh5O5U/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-20 16:48:16,400 truncating revision to first 12 chars 2017-01-20 16:48:16,400 Setting DEBUG logging. 2017-01-20 16:48:16,400 attempt 1/10 2017-01-20 16:48:16,400 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ccec56819e76?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-20 16:48:18,536 unpacking tar archive at: mozilla-aurora-ccec56819e76/testing/mozharness/ program finished with exit code 0 elapsedTime=2.408793 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-20 16:48:18.731293) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:18.731697) ========= script_repo_revision: ccec56819e76733f28ae7f046e4602824ddbed10 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:18.732172) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:18.732498) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 16:48:18.753471) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-20 16:48:18.753797) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WyPd9Pggqp/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.SLjrQh5O5U/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:48:18 INFO - MultiFileLogger online at 20170120 16:48:18 in /builds/slave/test 16:48:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:48:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:48:18 INFO - {'append_to_log': False, 16:48:18 INFO - 'base_work_dir': '/builds/slave/test', 16:48:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:48:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:48:18 INFO - 'branch': 'Mozilla-Aurora', 16:48:18 INFO - 'buildbot_json_path': 'buildprops.json', 16:48:18 INFO - 'config_files': ('talos/mac_config.py',), 16:48:18 INFO - 'default_actions': ('clobber', 16:48:18 INFO - 'read-buildbot-config', 16:48:18 INFO - 'download-and-extract', 16:48:18 INFO - 'populate-webroot', 16:48:18 INFO - 'create-virtualenv', 16:48:18 INFO - 'install', 16:48:18 INFO - 'run-tests'), 16:48:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:48:18 INFO - 'download_minidump_stackwalk': True, 16:48:18 INFO - 'download_symbols': 'ondemand', 16:48:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:48:18 INFO - 'tooltool.py': '/tools/tooltool.py', 16:48:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:48:18 INFO - '/tools/misc-python/virtualenv.py')}, 16:48:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:48:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:48:18 INFO - 'installer_path': 'installer.exe', 16:48:18 INFO - 'log_level': 'info', 16:48:18 INFO - 'log_name': 'talos', 16:48:18 INFO - 'log_to_console': True, 16:48:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:48:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:48:18 INFO - 'opt_config_files': (), 16:48:18 INFO - 'pip_index': False, 16:48:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:48:18 INFO - 'cmd': ('bash', 16:48:18 INFO - '-c', 16:48:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:48:18 INFO - 'enabled': True, 16:48:18 INFO - 'halt_on_failure': False, 16:48:18 INFO - 'name': 'check_screen_resolution'},), 16:48:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:48:18 INFO - 'cmd': ('bash', 16:48:18 INFO - '-c', 16:48:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:48:18 INFO - 'enabled': True, 16:48:18 INFO - 'halt_on_failure': False, 16:48:18 INFO - 'name': 'check_screen_resolution'},), 16:48:18 INFO - 'run_cmd_checks_enabled': True, 16:48:18 INFO - 'sps_profile': False, 16:48:18 INFO - 'sps_profile_interval': 0, 16:48:18 INFO - 'suite': 'dromaeojs-e10s', 16:48:18 INFO - 'system_bits': '32', 16:48:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:48:18 INFO - 'title': 't-yosemite-r7-0090', 16:48:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:48:18 INFO - 'use_talos_json': True, 16:48:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:48:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:48:18 INFO - 'work_dir': 'build'} 16:48:18 INFO - [mozharness: 2017-01-21 00:48:18.972188Z] Running clobber step. 16:48:18 INFO - Running pre-action listener: _resource_record_pre_action 16:48:18 INFO - Running main action method: clobber 16:48:18 INFO - rmtree: /builds/slave/test/build 16:48:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:48:21 INFO - Running post-action listener: _resource_record_post_action 16:48:21 INFO - [mozharness: 2017-01-21 00:48:21.430043Z] Finished clobber step (success) 16:48:21 INFO - [mozharness: 2017-01-21 00:48:21.430185Z] Running read-buildbot-config step. 16:48:21 INFO - Running pre-action listener: _resource_record_pre_action 16:48:21 INFO - Running main action method: read_buildbot_config 16:48:21 INFO - Using buildbot properties: 16:48:21 INFO - { 16:48:21 INFO - "project": "", 16:48:21 INFO - "product": "firefox", 16:48:21 INFO - "script_repo_revision": "production", 16:48:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 16:48:21 INFO - "repository": "", 16:48:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 16:48:21 INFO - "buildid": "20170120144225", 16:48:21 INFO - "pgo_build": "False", 16:48:21 INFO - "basedir": "/builds/slave/test", 16:48:21 INFO - "buildnumber": 35, 16:48:21 INFO - "slavename": "t-yosemite-r7-0090", 16:48:21 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:48:21 INFO - "platform": "yosemite_r7", 16:48:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:48:21 INFO - "branch": "mozilla-aurora", 16:48:21 INFO - "repo_path": "releases/mozilla-aurora", 16:48:21 INFO - "revision": "ccec56819e76733f28ae7f046e4602824ddbed10", 16:48:21 INFO - "stage_platform": "macosx64", 16:48:21 INFO - "builduid": "c1215c97759d498b8e1479f8270be8af", 16:48:21 INFO - "slavebuilddir": "test" 16:48:21 INFO - } 16:48:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 16:48:21 INFO - Running post-action listener: _resource_record_post_action 16:48:21 INFO - [mozharness: 2017-01-21 00:48:21.433594Z] Finished read-buildbot-config step (success) 16:48:21 INFO - [mozharness: 2017-01-21 00:48:21.433728Z] Running download-and-extract step. 16:48:21 INFO - Running pre-action listener: _resource_record_pre_action 16:48:21 INFO - Running main action method: download_and_extract 16:48:21 INFO - mkdir: /builds/slave/test/build/tests 16:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:21 INFO - trying https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 16:48:21 INFO - Downloading https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 16:48:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 16:48:22 INFO - Downloaded 1199 bytes. 16:48:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 16:48:22 INFO - Using the following test package requirements: 16:48:22 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:48:22 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:48:22 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 16:48:22 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:48:22 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 16:48:22 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:48:22 INFO - u'jsshell-mac.zip'], 16:48:22 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:48:22 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 16:48:22 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:48:22 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:48:22 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 16:48:22 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:48:22 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 16:48:22 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:48:22 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 16:48:22 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:48:22 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 16:48:22 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:48:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:48:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:48:22 INFO - Fetch https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:48:22 INFO - Http code: 200 16:48:22 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 16:48:22 INFO - x-amz-cf-id: JczUUY2zDIH9rDt5r9E41J2DIwKN_BBDvxJMcyL6NhBsCPAjBLynlQ== 16:48:22 INFO - x-amz-version-id: Bc6ElE57GF_PPPqIU5NhnU8Ftt2oCn.m 16:48:22 INFO - Expected file size: 16276206 16:48:22 INFO - Obtained file size: 16276206 16:48:29 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 16:48:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:48:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:48:29 INFO - Fetch https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:48:30 INFO - Http code: 200 16:48:30 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 16:48:30 INFO - x-amz-cf-id: wg9nYUyqrtgXgHWjILI8p3TmpZIKqXkksGLldopHu788Jrb2871lJQ== 16:48:30 INFO - x-amz-version-id: Bc6ElE57GF_PPPqIU5NhnU8Ftt2oCn.m 16:48:30 INFO - Expected file size: 16276206 16:48:30 INFO - Obtained file size: 16276206 16:48:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 16:48:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:48:36 INFO - Fetch https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 16:48:37 INFO - Http code: 200 16:48:37 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 16:48:37 INFO - x-amz-cf-id: Sh1nfKygQXE2sZiBfbns0V7cGCkVOqzxVJEJ7TQwREVwN3rAfkF6Qg== 16:48:37 INFO - x-amz-version-id: 0oDhBiQChCKDTl52Vq7ILLEZn1rWkckV 16:48:37 INFO - Expected file size: 14402992 16:48:37 INFO - Obtained file size: 14402992 16:48: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')]} 16:48:38 INFO - trying https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:48:38 INFO - Downloading https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 16:48:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 16:48:41 INFO - Downloaded 109968401 bytes. 16:48:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:48:41 INFO - mkdir: /builds/slave/test/properties 16:48:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:48:41 INFO - Writing to file /builds/slave/test/properties/build_url 16:48:41 INFO - Contents: 16:48:41 INFO - build_url:https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:48:41 INFO - Running post-action listener: _resource_record_post_action 16:48:41 INFO - Running post-action listener: set_extra_try_arguments 16:48:41 INFO - [mozharness: 2017-01-21 00:48:41.723891Z] Finished download-and-extract step (success) 16:48:41 INFO - [mozharness: 2017-01-21 00:48:41.724022Z] Running populate-webroot step. 16:48:41 INFO - Running pre-action listener: _resource_record_pre_action 16:48:41 INFO - Running main action method: populate_webroot 16:48:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:48:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:48:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:48:41 INFO - '--tppagecycles', 16:48:41 INFO - '7'], 16:48:41 INFO - 'tests': ['tsvgm']}}, 16:48:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:48:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:48:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:48:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:48:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:48:41 INFO - 'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:48:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:48:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:48:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:48:41 INFO - 'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['damp', 'tps']}, 16:48:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:48:41 INFO - 'tests': ['damp', 'tps']}, 16:48:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['dromaeo_dom']}, 16:48:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:48:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['basic_compositor_video']}, 16:48:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:48:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['a11yr', 16:48:41 INFO - 'ts_paint', 16:48:41 INFO - 'tpaint', 16:48:41 INFO - 'sessionrestore', 16:48:41 INFO - 'sessionrestore_no_auto_restore', 16:48:41 INFO - 'tabpaint']}, 16:48:41 INFO - 'other-e10s': {'tests': ['a11yr', 16:48:41 INFO - 'ts_paint', 16:48:41 INFO - 'tpaint', 16:48:41 INFO - 'sessionrestore', 16:48:41 INFO - 'sessionrestore_no_auto_restore', 16:48:41 INFO - 'tabpaint']}, 16:48:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:48:41 INFO - 'ts_paint', 16:48:41 INFO - 'tpaint', 16:48:41 INFO - 'sessionrestore', 16:48:41 INFO - 'sessionrestore_no_auto_restore', 16:48:41 INFO - 'tabpaint']}, 16:48:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:48:41 INFO - 'ts_paint', 16:48:41 INFO - 'tpaint', 16:48:41 INFO - 'sessionrestore', 16:48:41 INFO - 'sessionrestore_no_auto_restore', 16:48:41 INFO - 'tabpaint']}, 16:48:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['a11yr', 16:48:41 INFO - 'ts_paint', 16:48:41 INFO - 'tpaint', 16:48:41 INFO - 'sessionrestore', 16:48:41 INFO - 'sessionrestore_no_auto_restore', 16:48:41 INFO - 'tabpaint']}, 16:48:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['a11yr', 16:48:41 INFO - 'ts_paint', 16:48:41 INFO - 'tpaint', 16:48:41 INFO - 'sessionrestore', 16:48:41 INFO - 'sessionrestore_no_auto_restore', 16:48:41 INFO - 'tabpaint']}, 16:48:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['tsvgx', 16:48:41 INFO - 'tsvgr_opacity', 16:48:41 INFO - 'tart', 16:48:41 INFO - 'tscrollx', 16:48:41 INFO - 'cart']}, 16:48:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:48:41 INFO - 'tsvgr_opacity', 16:48:41 INFO - 'tart', 16:48:41 INFO - 'tscrollx', 16:48:41 INFO - 'cart']}, 16:48:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:48:41 INFO - 'talos_options': ['--disable-e10s'], 16:48:41 INFO - 'tests': ['tp5o']}, 16:48:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:48:41 INFO - 'tests': ['tp5o']}, 16:48:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:48:41 INFO - 'talos_options': ['--disable-e10s', 16:48:41 INFO - '--xperf_path', 16:48:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:48:41 INFO - 'tests': ['tp5n']}, 16:48:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:48:41 INFO - 'talos_options': ['--xperf_path', 16:48:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:48:41 INFO - 'tests': ['tp5n']}}, 16:48:41 INFO - 'talos.zip': {'path': '', 16:48:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:48:41 INFO - Running post-action listener: _resource_record_post_action 16:48:41 INFO - [mozharness: 2017-01-21 00:48:41.743102Z] Finished populate-webroot step (success) 16:48:41 INFO - [mozharness: 2017-01-21 00:48:41.743242Z] Running create-virtualenv step. 16:48:41 INFO - Running pre-action listener: _resource_record_pre_action 16:48:41 INFO - Running main action method: create_virtualenv 16:48:41 INFO - Creating virtualenv /builds/slave/test/build/venv 16:48:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:48:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:48:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:48:41 INFO - Using real prefix '/tools/python27' 16:48:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:48:42 INFO - Installing distribute.............................................................................................................................................................................................done. 16:48:45 INFO - Installing pip.................done. 16:48:45 INFO - Return code: 0 16:48:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:48:45 INFO - Reading from file tmpfile_stdout 16:48:45 INFO - Output received: 16:48:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:48:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:45 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:48:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:48:45 INFO - 'HOME': '/Users/cltbld', 16:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:48:45 INFO - 'LOGNAME': 'cltbld', 16:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:45 INFO - 'MOZ_NO_REMOTE': '1', 16:48:45 INFO - 'NO_EM_RESTART': '1', 16:48:45 INFO - 'PAGER': '/bin/cat', 16:48:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:45 INFO - 'PWD': '/builds/slave/test', 16:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:48:45 INFO - 'SHELL': '/bin/bash', 16:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:48:45 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:48:45 INFO - 'USER': 'cltbld', 16:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:45 INFO - 'XPC_FLAGS': '0x0', 16:48:45 INFO - 'XPC_SERVICE_NAME': '0', 16:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:48:45 INFO - Cleaning up... 16:48:45 INFO - Return code: 0 16:48:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:45 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:48:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:48:45 INFO - 'HOME': '/Users/cltbld', 16:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:48:45 INFO - 'LOGNAME': 'cltbld', 16:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:45 INFO - 'MOZ_NO_REMOTE': '1', 16:48:45 INFO - 'NO_EM_RESTART': '1', 16:48:45 INFO - 'PAGER': '/bin/cat', 16:48:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:45 INFO - 'PWD': '/builds/slave/test', 16:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:48:45 INFO - 'SHELL': '/bin/bash', 16:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:48:45 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:48:45 INFO - 'USER': 'cltbld', 16:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:45 INFO - 'XPC_FLAGS': '0x0', 16:48:45 INFO - 'XPC_SERVICE_NAME': '0', 16:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:46 INFO - Downloading/unpacking psutil>=3.1.1 16:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:48:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:48:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:48:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:48:49 INFO - Installing collected packages: psutil 16:48:49 INFO - Running setup.py install for psutil 16:48:49 INFO - building 'psutil._psutil_osx' extension 16:48:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:48:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:48:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:48:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:48:50 INFO - building 'psutil._psutil_posix' extension 16:48:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:48:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:48:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:48:50 INFO - ^ 16:48:50 INFO - 1 warning generated. 16:48:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:48:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:48:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:48:50 INFO - Successfully installed psutil 16:48:50 INFO - Cleaning up... 16:48:50 INFO - Return code: 0 16:48:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:48: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')]} 16:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48: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')]} 16:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:48: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 mozsystemmonitor==0.3 16:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:48:50 INFO - 'HOME': '/Users/cltbld', 16:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:48:50 INFO - 'LOGNAME': 'cltbld', 16:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:50 INFO - 'MOZ_NO_REMOTE': '1', 16:48:50 INFO - 'NO_EM_RESTART': '1', 16:48:50 INFO - 'PAGER': '/bin/cat', 16:48:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:50 INFO - 'PWD': '/builds/slave/test', 16:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:48:50 INFO - 'SHELL': '/bin/bash', 16:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:48:50 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:48:50 INFO - 'USER': 'cltbld', 16:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:50 INFO - 'XPC_FLAGS': '0x0', 16:48:50 INFO - 'XPC_SERVICE_NAME': '0', 16:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:48: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 16:48: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 16:48: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 16:48: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 16:48: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 16:48: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 16:48:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:48:53 INFO - Installing collected packages: mozsystemmonitor 16:48:53 INFO - Running setup.py install for mozsystemmonitor 16:48:54 INFO - Successfully installed mozsystemmonitor 16:48:54 INFO - Cleaning up... 16:48:54 INFO - Return code: 0 16:48:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:54 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:48:54 INFO - 'HOME': '/Users/cltbld', 16:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:48:54 INFO - 'LOGNAME': 'cltbld', 16:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:54 INFO - 'MOZ_NO_REMOTE': '1', 16:48:54 INFO - 'NO_EM_RESTART': '1', 16:48:54 INFO - 'PAGER': '/bin/cat', 16:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:54 INFO - 'PWD': '/builds/slave/test', 16:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:48:54 INFO - 'SHELL': '/bin/bash', 16:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:48:54 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:48:54 INFO - 'USER': 'cltbld', 16:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:54 INFO - 'XPC_FLAGS': '0x0', 16:48:54 INFO - 'XPC_SERVICE_NAME': '0', 16:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:54 INFO - Downloading/unpacking jsonschema==2.5.1 16:48: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 16:48: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 16:48: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 16:48: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 16:48: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 16:48: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 16:48:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:48:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:48:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:57 INFO - Downloading functools32-3.2.3-2.tar.gz 16:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:48:57 INFO - warning: no files found matching '*.txt' 16:48:57 INFO - no previously-included directories found matching 'build' 16:48:57 INFO - no previously-included directories found matching 'dist' 16:48:57 INFO - no previously-included directories found matching '.git*' 16:48:57 INFO - Installing collected packages: jsonschema, functools32 16:48:57 INFO - Running setup.py install for functools32 16:48:57 INFO - warning: no files found matching '*.txt' 16:48:57 INFO - no previously-included directories found matching 'build' 16:48:57 INFO - no previously-included directories found matching 'dist' 16:48:57 INFO - no previously-included directories found matching '.git*' 16:48:57 INFO - Successfully installed jsonschema functools32 16:48:57 INFO - Cleaning up... 16:48:57 INFO - Return code: 0 16:48:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:57 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:48:57 INFO - 'HOME': '/Users/cltbld', 16:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:48:57 INFO - 'LOGNAME': 'cltbld', 16:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:57 INFO - 'MOZ_NO_REMOTE': '1', 16:48:57 INFO - 'NO_EM_RESTART': '1', 16:48:57 INFO - 'PAGER': '/bin/cat', 16:48:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:57 INFO - 'PWD': '/builds/slave/test', 16:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:48:57 INFO - 'SHELL': '/bin/bash', 16:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:48:57 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:48:57 INFO - 'USER': 'cltbld', 16:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:57 INFO - 'XPC_FLAGS': '0x0', 16:48:57 INFO - 'XPC_SERVICE_NAME': '0', 16:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:48:57 INFO - Cleaning up... 16:48:57 INFO - Return code: 0 16:48:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:48:57 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:48:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:48:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:48:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:48:58 INFO - 'HOME': '/Users/cltbld', 16:48:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:48:58 INFO - 'LOGNAME': 'cltbld', 16:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:58 INFO - 'MOZ_NO_REMOTE': '1', 16:48:58 INFO - 'NO_EM_RESTART': '1', 16:48:58 INFO - 'PAGER': '/bin/cat', 16:48:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:58 INFO - 'PWD': '/builds/slave/test', 16:48:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:48:58 INFO - 'SHELL': '/bin/bash', 16:48:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:48:58 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:48:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:48:58 INFO - 'USER': 'cltbld', 16:48:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:48:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:58 INFO - 'XPC_FLAGS': '0x0', 16:48:58 INFO - 'XPC_SERVICE_NAME': '0', 16:48:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:58 INFO - Downloading/unpacking blobuploader==1.2.4 16:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:48:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:00 INFO - Downloading blobuploader-1.2.4.tar.gz 16:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:49:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:49:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:02 INFO - Downloading docopt-0.6.1.tar.gz 16:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:49:02 INFO - Installing collected packages: blobuploader, requests, docopt 16:49:02 INFO - Running setup.py install for blobuploader 16:49:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:49:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:49:02 INFO - Running setup.py install for requests 16:49:02 INFO - Running setup.py install for docopt 16:49:03 INFO - Successfully installed blobuploader requests docopt 16:49:03 INFO - Cleaning up... 16:49:03 INFO - Return code: 0 16:49:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:03 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:03 INFO - 'HOME': '/Users/cltbld', 16:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:03 INFO - 'LOGNAME': 'cltbld', 16:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:03 INFO - 'MOZ_NO_REMOTE': '1', 16:49:03 INFO - 'NO_EM_RESTART': '1', 16:49:03 INFO - 'PAGER': '/bin/cat', 16:49:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:03 INFO - 'PWD': '/builds/slave/test', 16:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:03 INFO - 'SHELL': '/bin/bash', 16:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:49:03 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:03 INFO - 'USER': 'cltbld', 16:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:03 INFO - 'XPC_FLAGS': '0x0', 16:49:03 INFO - 'XPC_SERVICE_NAME': '0', 16:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:49:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-uxNBT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:49:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-HM534e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:49:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-vzFMYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:49:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-e5zpcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:49:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-bgOHhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:49:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-X8WHJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-nv2ahX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-7QF5rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Ybfvnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-nEDBXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-fUEh1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-FGF2lR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-MRrfRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-zy0mnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-P2mFMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:49:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-KoHT9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:49:05 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-BgFdlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:49:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:49:05 INFO - Running setup.py install for manifestparser 16:49:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:49:05 INFO - Running setup.py install for mozcrash 16:49:05 INFO - Running setup.py install for mozdebug 16:49:05 INFO - Running setup.py install for mozdevice 16:49:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:49:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:49:05 INFO - Running setup.py install for mozfile 16:49:05 INFO - Running setup.py install for mozhttpd 16:49:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:49:06 INFO - Running setup.py install for mozinfo 16:49:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:49:06 INFO - Running setup.py install for mozInstall 16:49:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:49:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:49:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:49:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:49:06 INFO - Running setup.py install for mozleak 16:49:06 INFO - Running setup.py install for mozlog 16:49:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:49:06 INFO - Running setup.py install for moznetwork 16:49:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:49:06 INFO - Running setup.py install for mozprocess 16:49:06 INFO - Running setup.py install for mozprofile 16:49:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:49:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:49:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:49:07 INFO - Running setup.py install for mozrunner 16:49:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:49:07 INFO - Running setup.py install for mozscreenshot 16:49:07 INFO - Running setup.py install for moztest 16:49:07 INFO - Running setup.py install for mozversion 16:49:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:49:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:49:07 INFO - Cleaning up... 16:49:07 INFO - Return code: 0 16:49:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:49: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')]} 16:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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')]} 16:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:07 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:49:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:49:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:07 INFO - 'HOME': '/Users/cltbld', 16:49:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:07 INFO - 'LOGNAME': 'cltbld', 16:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:07 INFO - 'MOZ_NO_REMOTE': '1', 16:49:07 INFO - 'NO_EM_RESTART': '1', 16:49:07 INFO - 'PAGER': '/bin/cat', 16:49:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:07 INFO - 'PWD': '/builds/slave/test', 16:49:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:07 INFO - 'SHELL': '/bin/bash', 16:49:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:49:07 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:49:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:07 INFO - 'USER': 'cltbld', 16:49:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:07 INFO - 'XPC_FLAGS': '0x0', 16:49:07 INFO - 'XPC_SERVICE_NAME': '0', 16:49:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-2imNll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-FVwZKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-JClTvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-WXeWC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:49:08 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)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-65InCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-P8CzBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-g3KRh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-SYVGI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-WYW3Hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:49:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-CiWDOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:49:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-wF6RgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:49:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-OgJ1Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:49:09 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)) 16:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:49:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-8K6PPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:49:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-puK_Z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-i78AZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:49:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-4NWJtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:49:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-auoDqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:49:09 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)) 16:49:09 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)) 16:49:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:12 INFO - Downloading blessings-1.6.tar.gz 16:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:49:12 INFO - Installing collected packages: blessings 16:49:12 INFO - Running setup.py install for blessings 16:49:12 INFO - Successfully installed blessings 16:49:12 INFO - Cleaning up... 16:49:12 INFO - Return code: 0 16:49:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:49:13 INFO - Reading from file tmpfile_stdout 16:49:13 INFO - Current package versions: 16:49:13 INFO - blessings == 1.6 16:49:13 INFO - blobuploader == 1.2.4 16:49:13 INFO - docopt == 0.6.1 16:49:13 INFO - functools32 == 3.2.3-2 16:49:13 INFO - jsonschema == 2.5.1 16:49:13 INFO - manifestparser == 1.1 16:49:13 INFO - mozInstall == 1.12 16:49:13 INFO - mozcrash == 1.0 16:49:13 INFO - mozdebug == 0.1 16:49:13 INFO - mozdevice == 0.48 16:49:13 INFO - mozfile == 1.2 16:49:13 INFO - mozhttpd == 0.7 16:49:13 INFO - mozinfo == 0.9 16:49:13 INFO - mozleak == 0.1 16:49:13 INFO - mozlog == 3.4 16:49:13 INFO - moznetwork == 0.27 16:49:13 INFO - mozprocess == 0.23 16:49:13 INFO - mozprofile == 0.28 16:49:13 INFO - mozrunner == 6.13 16:49:13 INFO - mozscreenshot == 0.1 16:49:13 INFO - mozsystemmonitor == 0.3 16:49:13 INFO - moztest == 0.8 16:49:13 INFO - mozversion == 1.4 16:49:13 INFO - psutil == 3.1.1 16:49:13 INFO - requests == 1.2.3 16:49:13 INFO - wsgiref == 0.1.2 16:49:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:49: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')]} 16:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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')]} 16:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:13 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:49:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:49:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:13 INFO - 'HOME': '/Users/cltbld', 16:49:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:13 INFO - 'LOGNAME': 'cltbld', 16:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:13 INFO - 'MOZ_NO_REMOTE': '1', 16:49:13 INFO - 'NO_EM_RESTART': '1', 16:49:13 INFO - 'PAGER': '/bin/cat', 16:49:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:13 INFO - 'PWD': '/builds/slave/test', 16:49:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:13 INFO - 'SHELL': '/bin/bash', 16:49:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:49:13 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:49:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:13 INFO - 'USER': 'cltbld', 16:49:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:13 INFO - 'XPC_FLAGS': '0x0', 16:49:13 INFO - 'XPC_SERVICE_NAME': '0', 16:49:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:49:14 INFO - Cleaning up... 16:49:14 INFO - Return code: 0 16:49:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:14 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 0x1089c9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10893f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc635d0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, '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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:14 INFO - 'HOME': '/Users/cltbld', 16:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:14 INFO - 'LOGNAME': 'cltbld', 16:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:14 INFO - 'MOZ_NO_REMOTE': '1', 16:49:14 INFO - 'NO_EM_RESTART': '1', 16:49:14 INFO - 'PAGER': '/bin/cat', 16:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:14 INFO - 'PWD': '/builds/slave/test', 16:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:14 INFO - 'SHELL': '/bin/bash', 16:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:49:14 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:14 INFO - 'USER': 'cltbld', 16:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:14 INFO - 'XPC_FLAGS': '0x0', 16:49:14 INFO - 'XPC_SERVICE_NAME': '0', 16:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:49:14 INFO - Cleaning up... 16:49:14 INFO - Return code: 0 16:49:14 INFO - Running post-action listener: _resource_record_post_action 16:49:14 INFO - Running post-action listener: _start_resource_monitoring 16:49:14 INFO - Starting resource monitoring. 16:49:14 INFO - [mozharness: 2017-01-21 00:49:14.809372Z] Finished create-virtualenv step (success) 16:49:14 INFO - [mozharness: 2017-01-21 00:49:14.810331Z] Running install step. 16:49:14 INFO - Running pre-action listener: _resource_record_pre_action 16:49:14 INFO - Running main action method: install 16:49:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:49:15 INFO - Reading from file tmpfile_stdout 16:49:15 INFO - Detecting whether we're running mozinstall >=1.0... 16:49:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:49:15 INFO - Reading from file tmpfile_stdout 16:49:15 INFO - Output received: 16:49:15 INFO - Usage: mozinstall [options] installer 16:49:15 INFO - Options: 16:49:15 INFO - -h, --help show this help message and exit 16:49:15 INFO - -d DEST, --destination=DEST 16:49:15 INFO - Directory to install application into. [default: 16:49:15 INFO - "/builds/slave/test"] 16:49:15 INFO - --app=APP Application being installed. [default: firefox] 16:49:15 INFO - mkdir: /builds/slave/test/build/application 16:49:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 16:49:47 INFO - Reading from file tmpfile_stdout 16:49:47 INFO - Output received: 16:49:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:49:47 INFO - Running post-action listener: _resource_record_post_action 16:49:47 INFO - [mozharness: 2017-01-21 00:49:47.049756Z] Finished install step (success) 16:49:47 INFO - [mozharness: 2017-01-21 00:49:47.049902Z] Running run-tests step. 16:49:47 INFO - Running pre-action listener: _resource_record_pre_action 16:49:47 INFO - Running main action method: run_tests 16:49:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:49:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:49:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:49:47 INFO - 2017-01-20 16:49:47.064 screenresolution[1621:7424] starting screenresolution argv=screenresolution get 16:49:47 INFO - 2017-01-20 16:49:47.067 screenresolution[1621:7424] Display 0: 1600x1200x32@60 16:49:47 INFO - 2017-01-20 16:49:47.073 screenresolution[1622:7426] starting screenresolution argv=screenresolution list 16:49:47 INFO - Available Modes on Display 0 16:49:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:49:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:49:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:49:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:49:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:49:47 INFO - Intel Iris: 16:49:47 INFO - Chipset Model: Intel Iris 16:49:47 INFO - Type: GPU 16:49:47 INFO - Bus: Built-In 16:49:47 INFO - VRAM (Dynamic, Max): 1536 MB 16:49:47 INFO - Vendor: Intel (0x8086) 16:49:47 INFO - Device ID: 0x0a2e 16:49:47 INFO - Revision ID: 0x0009 16:49:47 INFO - Displays: 16:49:47 INFO - Display: 16:49:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:49:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:49:47 INFO - Display Serial Number: 1600x1200 60 16:49:47 INFO - Main Display: Yes 16:49:47 INFO - Mirror: Off 16:49:47 INFO - Online: Yes 16:49:47 INFO - Rotation: Supported 16:49:47 INFO - Return code: 0 16:49:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:49:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:49:47 INFO - Python 2.7.3 16:49:47 INFO - Return code: 0 16:49:47 INFO - grabbing minidump binary from tooltool 16:49: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')]} 16:49:47 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 0x1087ddd30>, '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 0x108643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087d5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:49:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:49:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:49:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:49:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:49:47 INFO - Return code: 0 16:49:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:49:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:49:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:49:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:49:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:49:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:49:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:49:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WyPd9Pggqp/Render', 16:49:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:47 INFO - 'HOME': '/Users/cltbld', 16:49:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:47 INFO - 'LOGNAME': 'cltbld', 16:49:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:49:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:47 INFO - 'MOZ_NO_REMOTE': '1', 16:49:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:49:47 INFO - 'NO_EM_RESTART': '1', 16:49:47 INFO - 'PAGER': '/bin/cat', 16:49:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:47 INFO - 'PWD': '/builds/slave/test', 16:49:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:49:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:47 INFO - 'SHELL': '/bin/bash', 16:49:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SLjrQh5O5U/Listeners', 16:49:47 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:49:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:47 INFO - 'USER': 'cltbld', 16:49:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:47 INFO - 'XPC_FLAGS': '0x0', 16:49:47 INFO - 'XPC_SERVICE_NAME': '0', 16:49:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:49:47 INFO - mozversion application_buildid: 20170120144225 16:49:47 INFO - mozversion application_changeset: ccec56819e76733f28ae7f046e4602824ddbed10 16:49:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:49:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:49:47 INFO - mozversion application_name: Firefox 16:49:47 INFO - mozversion application_remotingname: firefox-dev 16:49:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:49:47 INFO - mozversion application_vendor: Mozilla 16:49:47 INFO - mozversion application_version: 52.0a2 16:49:47 INFO - mozversion platform_buildid: 20170120144225 16:49:47 INFO - mozversion platform_changeset: ccec56819e76733f28ae7f046e4602824ddbed10 16:49:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:49:47 INFO - mozversion platform_version: 52.0a2 16:49:47 INFO - using testdate: 1484959787 16:49:47 INFO - actual date: 1484959787 16:49:47 INFO - starting webserver on 'localhost:49235' 16:49:47 INFO - SUITE-START | Running 2 tests 16:49:47 INFO - TEST-START | dromaeo_css 16:49:47 INFO - operating with platform_type : mac_ 16:49:47 INFO - Initialising browser for dromaeo_css test... 16:49:47 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpIQ9QIy/profile http://localhost:49235/getInfo.html) 16:49:50 INFO - PROCESS | 1632 | 2017-01-20 16:49:50.826 plugin-container[1634:7608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a37, name = 'com.apple.tsm.portname' 16:49:50 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:49:50 INFO - PROCESS | 1632 | 2017-01-20 16:49:50.829 plugin-container[1634:7608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 16:49:50 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:49:50 INFO - PROCESS | 1632 | 2017-01-20 16:49:50.829 plugin-container[1634:7608] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:49:50 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 16:49:50 INFO - PROCESS | 1632 | colorDepth:24 16:49:50 INFO - PROCESS | 1632 | Browser inner width/height: 1024/663 16:49:50 INFO - PROCESS | 1632 | __metrics 16:49:50 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 16:49:51 INFO - TEST-INFO | 1632: exit 0 16:49:51 INFO - Browser initialized. 16:49:51 INFO - Running cycle 1/1 for dromaeo_css test... 16:49:51 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpIQ9QIy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:49:54 INFO - PROCESS | 1635 | 2017-01-20 16:49:54.598 plugin-container[1636:7741] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 16:49:54 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:49:54 INFO - PROCESS | 1635 | 2017-01-20 16:49:54.599 plugin-container[1636:7741] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x864f, name = 'com.apple.CFPasteboardClient' 16:49:54 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:49:54 INFO - PROCESS | 1635 | 2017-01-20 16:49:54.599 plugin-container[1636:7741] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:50:04 INFO - PROCESS | 1635 | RSS: Main: 242618368 16:50:04 INFO - PROCESS | 1635 | 16:53:07 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 16:53:08 INFO - PROCESS | 1635 | RSS: Main: 245424128 16:53:08 INFO - PROCESS | 1635 | 16:56:10 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 16:56:11 INFO - PROCESS | 1635 | RSS: Main: 254832640 16:56:11 INFO - PROCESS | 1635 | 16:59:13 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 16:59:14 INFO - PROCESS | 1635 | RSS: Main: 257634304 16:59:14 INFO - PROCESS | 1635 | 17:02:16 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 17:02:16 INFO - PROCESS | 1635 | RSS: Main: 256888832 17:02:16 INFO - PROCESS | 1635 | 17:05:19 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 17:05:20 INFO - PROCESS | 1635 | RSS: Main: 261468160 17:05:20 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 17:08:22 INFO - PROCESS | 1635 | RSS: Main: 263004160 17:08:22 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | __start_tp_report 17:08:22 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 17:08:22 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 17:08:22 INFO - PROCESS | 1635 | |i|pagename|runs| 17:08:22 INFO - PROCESS | 1635 | |0;dojo.html;3277.1970867137547;3343.6322004579506;3353.396388650043;3366.5960084789813;3370.4101782188127;5600.439956004398;5602.60781745278;5650.547956163502;5677.9716101419435;5732.369439361667;5719.656820590768;5751.309842818868;5763.5389168866495;5771.653700777946;5775.364709881909;5133.537981581648;5168.974155129238;5193.298904647862;5214.739263036852;5268.420473747885;5266.289050978107;5277.12927366984;5287.471252874701;5298.09932311508;5320.866978325554;5260.132078207111;5271.051210782058;5272.446393128724;5276.261323414725;5280.392754833186;4479.48485924118;5031.943291908704;5058.721770302632;5099.4390617032095;5139.563137133348;5044.6973181608855;5154.123798954187;5157.561607263349;5160.122779127557;5211.452797456288;4833.7583120844165;5591.896497443917;5666.490015898589;5684.976704193285;5723.227364305807;5270.288511051036;5442.673439593597;6042.6978651067275;6047.516198704093;6062.939370606326;6108.6759897029115;6110.71064601923;6119.959572021022;6141.600795948294;6142.713648198237;5707.857303567403;5729.8567535811935;5740.0815869460685;5820.35976042635;5903.763849446017;6191.628502289877;6200.379962003809;6210.254769427697;6232.376762323822;6241.9375806241815;5857.15071314657;6086;6093.573449858556;6142.416470435302;6147.784827531015;6021.458068773832;6105.581053426346;6109.647596799064;6125.632272693312;6133.84665383369;6111.1967345965195;6121.57491608479;6134.283518479771;6162.075688646739;6194.0708893665405;4588.541145885385;5605.7197140142825;5886.735096920649;5982.551308651869;6203.16257305258;4806.2310030394965;4829.348038014893;4857.149998750205;4908.901821963541;4920.3111564381015;4356.825726970886;4356.9128617428105;4366.912661746747;4379.627731642846;4382.408374869416;5129.794808207705;5527.281453411031;5737.9384813809575;5842.824715258549;5849.385814489502;6099.9390006099375;6125.235451818339;6126.264848218242;6144.218176728698;6240.7503699852405;5632.943670563324;5652.378238393773;5691.658500489983;5823.155642431909;5840.2991641003355;5369.48989845964;5376.301080859464;5389.730513474311;5416;5432.945670543244;6129.083728908156;6190.164327815687;6277.780277690259;6282.4974002079725;6290.968545157337;6127.953177891068;6128.503123582109;6129.908988904127;6130.478909292582;6236.532260080603;6238.033104868752;6255.40573645503;6281.842953926189;6284.717187726472;6291.276503202191;6134.2332208473945;6144.907826382697;6145.324014358328;6147.44672979434;6148.784792532419;5082.974585127051;5554.305711786027;6114.388610881841;6130.52043589939;6193.102000210034;6049.213602231682;6085.208922840003;6091.451769340781;6101.664408457577;6125.448709616156;5821.854453638693;5996.430339117776;6070.150178974859;6070.575059745952;6073.967425537581;5548.861278468071;5639.943600564106;5646.774129034792;5768.855778605568;5825.155352473952;5851.941480585139;5856.795012174553;5876.206712093813;5884.764609415575;5903.793367232126;5717.056685646821;5717.22817419643;5728.656280623176;5736.713164341682;5760.884782304414 17:08:22 INFO - PROCESS | 1635 | |1;ext.html;465.7159132928915;489.7208591103072;528.3976267055557;533.4159095790109;533.6077982682727;36536.63463365366;37198;37524.81237593808;38827.223455530875;39876.202475950464;39523.20953580927;39718;39721.20557588847;39817.60182398175;39979.00052498689;9166.312526560501;9326.114019168168;9363.204127649158;9372.531373431335;9398.483083430408;9209.079092090804;9245.676401325922;9271.536423178848;9280.257579393667;9298.86051709225;9253.907460925371;9271.397359171666;9308.767280817967;9316.953415232914;9318.72043838686;9215.95392023039;9242.214411775007;9257.722268331961;9285.767855803591;9288.21050210733;9231.953840230823;9296;9302.906970930271;9313.720588382292;9314.208292295163;38309.23381532354;38423;38487.807560962014;38667.22665546701;38828;3180.3321302526497;3256.234784825564;3330.9500357494658;3475.061733331986;3674.4488326750934;28660;28953.841846326333;29096.854515727497;29265.85367073172;29469.557956630666;2832.2069820450306;3044.34546572488;3155.5108958111527;3208.69517395847;3290.7038366546885;504508;511078;511162;511589;513108;54772.178417322975;55084.44915550874;55088.898222035335;55182.17226741602;55190;48016.27975580369;56939.14591281134;56970;57509;57808.132878006865;15422.768658470131;15454.38182472711;15483.690326193413;15645.765313520305;15762.369505219664;10492.213084018576;12270.631881043582;12305.44625491855;12331.136820422664;12382.071344649188;12959.935200323938;13848.723025539633;14522.273886305642;14580.125192488273;14670.413183472756;3162.256869635634;3262.869485220612;5851;5920.940790592125;5970.49250813677;837.5267973594899;911.5624500239921;938.4697645830221;952.6903756279237;955.8040601676639;26564.601530977052;28832;29931.551026734618;30362.54456183159;30572.77708891664;1004.7036124343336;1060.2896059639907;1121.5569849909402;1160.1530882455854;1162.4536467860091;14988.62528436776;15220;15253.237338133049;15459;15511.612209694847;709699;715123;716002;719515;719695;57352.13971790426;57798.7110064447;57950.55123621859;58018;58096;1343.147101590497;1361.271719630013;1370;1370.9177449353072;1372.28641106624;25910.481790364465;27824.44351113007;28181.71818281791;28993.42013159767;29359.55960660549;46920.530794691615;47041.764791175825;47383.76308118437;47567.524324757695;47649.28526072181;642539;643201;644991;647362;650019.7499012474;24473.898674560045;25262.242132736046;25506.617400739375;25724.742752572234;26375.076872310146;36906.14252579415;40388;43111.72375493346;44066.457673981255;44515.109697805216;37880.621193788815;38274.80862595669;38642;38723.41914871336;39310.21379572335;35639.82180089083;35856.28287434184;36623.334142824206;36649;36688.44967325439;35825.28349433049;35876.461853071705;36168.638313616524;36429.724959626285;36460.27079458449;31418.685813141576;33874.3225135491;33897.322053559285;33972.83013584916;34044.31911361808;23879.761202387755;24752.876235618707;25015.87492062528;25108.199265737483;25200.621990669788 17:08:22 INFO - PROCESS | 1635 | |2;jquery.html;332.3021654525497;376.9283836071148;380.4692454026634;386.9455733127227;403.36872794077266;19140.90429547852;19303.32438364656;19661.60676786467;19745.605087898235;19947.002649867485;11262.887371126286;11719.472623731932;11900.940495297511;11917.344546049964;11919.40402979852;5072.974635126829;5349.144136938091;5380.085385484457;5449.700266485342;5465.207544906006;3923.8626648067325;4126.0510082681;5387.757550910218;5429.619926605139;6370.649614271213;6552.410283074522;6685.164354455693;6697.3637504437;6703.664816759167;6706.26231114577;6043.486303664194;6106.114613381079;6108.3280839107665;6118.112873633343;6206.6586337751405;4953.257011448276;5259.8422047338645;5915.7633694652595;5943.821685349403;5950.256217972754;12825.102242843004;12983.610491685264;13069.15050521713;13111.082224244306;13320.933395333059;2323.1520495019195;2406.554787364326;2673.3049407154085;2809.3959798643186;2878.8848446062134;9477.952610236973;9836.76634324799;9938.055884690943;10018.749531261705;10420.166386689047;1755.824417558234;1878.2956391353243;1911.8661693681447;1918.2039453626728;1939.4084804134734;120350;121044;125082.3745881274;126316;128247.7175228245;11125.276857004268;11131.666050018512;11746.353950532803;11889.643310700692;11934.22427542207;14584;14646.414143434144;14655.780163297559;14672;14697.77953330701;8185.79535511617;8392.790180245544;8426.620802063922;8566.229039386486;8579.571021448903;8203.466774659628;8211.794705132419;8238.423310368216;8302.418830681794;8365.749027529184;4472.195004899149;4541.704789188692;4555;4604.58558729716;4672.74299913508;1576.7556028815552;1671.189473105543;1683.3098429643787;1899.268781519125;1963.7250784890127;895.8790563273875;924.8289066522715;955.2739917662487;958.3866325551653;980.5146452506056;19664.213431462867;20398.592028159354;20966.895165524074;21075.473113172295;21222.363329100153;928.5914197752967;940.9435433873989;956.1824639932896;959.3668179001826;992.0773680477225;9070.455772653662;10185.847212291821;11107.944460277648;11146.331220126818;11161.553537858395;603785;606945;613874;616946;626431;103363;124476.37761811134;139285.30357348148;139444;146979;855.4738835616175;893.7229458867772;1011.0849681038671;1012.8632634594383;1014.4167103915278;18948;19238;19316.93756843387;19411.2235510578;19553.804461955766;54920;55229.723851380484;55363;56037.59906002219;57656.71171644283;82179.7673034917;82234;82675.5866220665;83324.58337708273;85847;7838.764837054898;8027.2775450209765;11174.709184617297;11777.646670599896;11780.116491263361;8421.284190843602;8531.274841638531;12166.817497737718;12497.875021250034;12932.09475336755;7702.807429814299;7812.218778122142;7849.097353804157;7916.208379162038;8021.197880211932;7549.886751698619;7692.307692307719;7874.409419293462;7886.763397098096;8028.638711258124;7812.429863192666;7876.960615196887;7923.247291507298;8049.919500804917;8150.9184908150155;11844.763104737685;11978;12044.156909016285;12064.45709943037;12267.509299627915;9987.751531058617;11065.280756750786;11206.43967801623;11438.885611143782;11464.197506174487 17:08:22 INFO - PROCESS | 1635 | |3;mootools.html;1236.925784452933;1452.9854701452985;1578.8579027887488;1755.2803350626245;1761.8766686331946;7507;7785.493942893722;8053.033635963678;8551.187637174466;8567.91432085679;7056.400205982498;7790.3767698584115;7919.802004949879;8398.244158025776;8430.409871309012;2169.915042478761;2275.772422757719;2320.9651855222187;2339.2397470821966;2358.292512246328;2190.5290362572046;2330.312557795446;2352.1179557665873;2370.7747763962398;2389.1399096325395;2131.424515380854;2327.3716096653893;2341.94145146371;2347.3310106619615;2350.0717216699395;2295.2884605772265;2308.5036717105904;2326.674265602817;2327.476317828492;2335.6730057791838;2316.953660926772;2334.3113781434517;2335.1826860598826;2343.320437073247;2351.8824058797163;8421.368397370221;8476.78808029798;8487.787805304855;8496.532690702008;8519.659213631447;5065.569426598743;5221.608379371524;5222.0861349264105;5227.268182454422;5232.215167724834;3200.3279311344645;3277.213468767479;3375.1899544109483;3596.065023093988;3604.2250916053176;1021.8927012663712;1034.3811934322837;1079.433297518801;1079.7570546626946;1093.278436232083;11042.613508527164;11060.336379817236;11144.275622084539;11144.554217831195;11159.051480624157;4259.849840543098;4323.654107671378;4641.350210970467;4724.055188962221;4755.904881902412;8559.529225892637;9280.953595232115;9529.714108576754;9530.761730956643;9545.856812147824;5228.529432351107;5230.267762513251;5233.659812112201;5237.450067742832;5240.816571419982;4260.0840819223395;4276.2302785498905;4753.1444340019125;4798.1123492153365;4808.543188397097;3295.35740530599;3835.328817456934;3921.9019524512114;3945.447637330776;3947.388154836005;1267.309316422552;1274.1399555300136;1275.1775105057186;1284.8715128487263;1297.1633296523878;794.7377365469497;898.2095755734912;905.651324240159;914.4741773480275;929.809389075238;5649.37856835756;5656.377798442168;5735.483806457439;5897.852553686193;5919.911201331984;905.6649039855296;921.4102974096584;934.3739694404765;953.137410643355;965.7151140413595;3702.8148592570265;3752.155764952918;3817.4464702617965;3872.283627528916;3878.0692633767694;9987.500624968723;10336.0697537222;10352.948235258926;10359.63741269052;10393.272470926962;10158.339707918962;10226.795464090825;10235.385876847413;10281.023302786223;10290.382577045251;7503.699852005859;7895.368370530344;7905.960470197612;7922.881156782768;8039.959800200962;5820.76716931318;5825.3883342248455;5891.469767720926;5952.255968003999;5998.790042348497;8779.517126558101;8884.333674974532;9335.113164249386;9580.95209523948;9593.760155996157;9204.125608067223;9334.159925606456;9511;9694.17599504022;9707.349750542284;6011.789587364421;6503.772367967098;7224.8916266255;7265.564066156047;7383.2985866342615;7156.964215178891;8065.274125328514;8127.2279133482225;8137.6744930204495;8140.185981401813;7124.895641175626;7302.342789149002;7319;7350.77947661571;7368.778936631909;7014.333638304353;7110.786676399716;7126.608036558039;7147.82130446743;7252.601106938958;5995.9700201500455;6646.335366463315;6799.6600169990315;6809.421199198124;6826.692798823966;4837.588804951619;4844.297576851266;4884.023195360871;4943.110240156806;4968.453470118357;4848.9757551212015;4896.2655601660035;4922.384701912261;4935.679180853233;5040.36995375578 17:08:22 INFO - PROCESS | 1635 | |4;prototype.html;280.01853503470335;291.9328554432482;308.03431243053035;311.4005539336776;311.8534288884225;4729.858104256878;5175.171972484394;5404.2974413326265;5498.697571633558;5515.821250606008;4334.696571240007;4364.978175109128;5027.0699920514635;5274.709890955995;5423.484768946953;4123.11353059093;4129.855455059073;4146.751194928314;4150.966026646952;4158.230727315441;4002.2395744808537;4141.722052870924;4142.627163555279;4171.353440216772;4190.287651099306;3755.305268525318;4073.8981525461954;4080.531008836831;4100.753954762724;4116.670666346685;3417.7436692248057;4079.2861249281405;4107;4125.092479654471;4125.711200215986;3268.3953468608383;3400.829958502065;3411.5906091269208;3458.5503884495106;3641.708663306929;4669.287742499151;4881.656065595079;5240.528352448298;5274.314339135926;5378.489043540857;4612.95387046132;4669.237377874549;4686.414198225222;4695.783388378972;4708.788104535304;5647.98336299466;5697.6011679182475;5708;5732.283464566929;5746.965546201682;3407.7561689983227;3840.135969406889;3854.4796452478836;3908.882733517999;3924.9018774530578;5650.51970582501;5675.744591493392;5871.295444546682;6020;6098.72555734993;3441.6042155151977;3493.8078405687424;3807.904802379963;3913.241164978683;3933.4689816874356;3895.7662540247675;3907.47249121365;3944.3491823849317;3969.4442778011103;3977.7812220327583;3759.5300587426573;3781.7352785305316;4227.7040607157205;4582.95417045832;4602.976985115053;4852.417709874838;5740.368559458456;5771.394003629643;5784.855378615484;5806.767729290866;1075.1506310015156;1107.4739498738036;1219.5182902753363;1277.9552715655093;1287.922724636525;840.9159084091542;868.5049521772529;868.700298397052;874.8950125984923;875.9868601970976;4712.929306060412;4787.976060119677;4843.951560484421;5149.613778966593;5188.818391356284;4888.633352498577;5152.227165925066;5334.733263336818;5475.808346707846;5529.225908372831;4106.548279689232;5087.303729023623;5092.745362731848;5104.744762761847;5115;2843.5545430929383;2920.6495220573665;2961.8667159977854;2977.895773647955;3004.564338170953;4938.851834444972;5689.046112951459;5701.868318513371;5705.914411283834;5764.798232061858;4898.706077635282;4996.550310472075;5771.192033115367;6061.302950160788;6107.389261073857;5334.012952746474;5605.411431799684;6078.3921607838865;6097;6125.356837531995;5366.785328586813;5668.716564171693;5940.405959404198;5990.495560097218;6052.848678783065;4947.183714687037;4977.278294647328;5153.2270159476375;5892.939270931273;5954.880902381841;5395.487428694268;5928.318243402064;5936.28764548257;6086.6348019119;6092.969535152296;3496.901858884649;3812.275667623143;3934.075492259374;3974.1058261892;3979.4030895364767;4365.585269399402;5911.349751527243;5912.556558258062;5929.347771745191;5936.762529498772;5534.900455744796;5710;5728.627639203605;5751.568632352507;5766.5675074368755;5696.1455781633085;5696.772129114789;5736.569757268305;5755.251817263729;5785.805291163872;5468.48049435303;5545.528630066489;5555.472230138131;5593.216949626974;5650.248422988887;4724.763761811827;4726.5273472653835;5265.604614777164;5279.973600131974;5745.454181852718;4558.065596552765;5615.578831587565;5631.8592035199445;5648.858778530405;5653.349864765604 17:08:22 INFO - PROCESS | 1635 | |5;yui.html;628.2931701835436;695.0790202981049;717.7559629725895;743.0266351080083;753.5704648350436;13801.51694690686;14679.486217982372;14689.265536723162;14695.485658001971;14772.556823295292;13275.800862987064;13307.667308317297;13377.66555836102;13427.462901483954;13449.32753362333;633.5027003802016;655.1744801550034;785.9371250299962;800.0639252075072;803.2650125135523;802.6227672993713;813.9918600813975;814.3403842887251;815.1725998111921;816.8897198878135;864.2351518905782;865.4936861935776;867.1501928110456;867.4621734524603;868.5266529741275;853.7652145659962;859.1494420523699;863.7624653220383;863.8617821148586;864.2869632553169;641.9197600299963;643.1446176585131;643.6234802640461;643.6845945486677;644.8968165093562;12132.636020919299;13740;14030.508932187324;14051.578452646334;14056.437742490289;13444.865551344557;13581.728365432637;13584.184948903097;13734.587962361145;13763.086225420324;10970.122390208764;11038.282511636718;11172.776544469065;11235.04502117321;11279.887201128047;2941.9852900735573;3233.094733474631;3279.2785587170783;3727.683146932514;3769.076576238839;62382.064269035545;80805.19194808035;81417.18582814155;83680;87180.6922896157;8860.601272942604;10518.684439466828;10555;10655.467226638637;10931.125509959184;18662.813371866378;19220.654554181074;20531.38405847827;20770.1691932321;20785.480362991046;7662.73180438682;7674.616269186518;7724;7725.381969442544;7854.175311592315;3849.40334248192;4128.772917489507;4159.230542349675;4236.2374772540625;4359.411479450234;3199.3601279744144;3267.967320326766;3301.735861131104;3303.0751389610946;3304.4052070627035;603.0923460192382;610.9022556391045;620.1937481274325;631.6052467207995;632.4876849751624;536.9436209198057;578.6181120460476;585.92382990211;599.3886236039216;603.7826382502295;7331.450141239428;7996.280334769783;8688.66129700734;8747.606357713918;8808.867866982;552.6656372894341;554.9611527193139;602.0908428273339;608.6256951974592;611.4160976267653;4018.3972404139613;4085.20338533989;4165.4584903962295;4171.290880550314;4239.1521695660995;119955.40022299833;120875;122563;123037;123136;102244.48877755564;102606.48696756469;106865.4656726727;108511;108829;81072;94482;95193;95581.52209238909;96151;8130.715424960098;8166.959165204374;8174.7956301092945;8185.4270201085355;8398.958005209935;42345.576544234165;50951;51995.48004519907;52685.7365713169;53464;68187.65906170437;69147.61704765775;70611.9408208867;72739;74212;10160.745981350525;10270;10318.48407579633;10341.74145646365;10630.946845265724;11485.368304743319;11533.769324613628;11587;11737.471813768572;11815.70460738454;10490.213234007328;10776.245662803529;10845.132389408986;11044.723881903017;11489.770204596029;11070.501827417624;11078.169137314895;11133.721656958642;11154;11180.217384782987;10487.318324308895;10663.146948243966;10709.732256693645;10796.298240614335;10866.456677166268;8905.243054340453;9359;9501.619935202514;9520.90479095228;9611.086946740048;8990.415622984485;9324.766880828034;9350.25197984146;9372.625094996396;9459.290553208675 17:08:22 INFO - PROCESS | 1635 | __end_tp_report 17:08:22 INFO - PROCESS | 1635 | __start_cc_report 17:08:22 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,368 17:08:22 INFO - PROCESS | 1635 | __end_cc_report 17:08:22 INFO - PROCESS | 1635 | __startTimestamp1484960902944__endTimestamp 17:08:22 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | ------- Summary: start ------- 17:08:22 INFO - PROCESS | 1635 | Number of tests: 6 17:08:22 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5639.38 Median:5771.65 stddev:627.24 (10.9%) stddev-sans-first:601.17 17:08:22 INFO - PROCESS | 1635 | Values: 3277.2 3343.6 3353.4 3366.6 3370.4 5600.4 5602.6 5650.5 5678.0 5732.4 5719.7 5751.3 5763.5 5771.7 5775.4 5133.5 5169.0 5193.3 5214.7 5268.4 5266.3 5277.1 5287.5 5298.1 5320.9 5260.1 5271.1 5272.4 5276.3 5280.4 4479.5 5031.9 5058.7 5099.4 5139.6 5044.7 5154.1 5157.6 5160.1 5211.5 4833.8 5591.9 5666.5 5685.0 5723.2 5270.3 5442.7 6042.7 6047.5 6062.9 6108.7 6110.7 6120.0 6141.6 6142.7 5707.9 5729.9 5740.1 5820.4 5903.8 6191.6 6200.4 6210.3 6232.4 6241.9 5857.2 6086.0 6093.6 6142.4 6147.8 6021.5 6105.6 6109.6 6125.6 6133.8 6111.2 6121.6 6134.3 6162.1 6194.1 4588.5 5605.7 5886.7 5982.6 6203.2 4806.2 4829.3 4857.1 4908.9 4920.3 4356.8 4356.9 4366.9 4379.6 4382.4 5129.8 5527.3 5737.9 5842.8 5849.4 6099.9 6125.2 6126.3 6144.2 6240.8 5632.9 5652.4 5691.7 5823.2 5840.3 5369.5 5376.3 5389.7 5416.0 5432.9 6129.1 6190.2 6277.8 6282.5 6291.0 6128.0 6128.5 6129.9 6130.5 6236.5 6238.0 6255.4 6281.8 6284.7 6291.3 6134.2 6144.9 6145.3 6147.4 6148.8 5083.0 5554.3 6114.4 6130.5 6193.1 6049.2 6085.2 6091.5 6101.7 6125.4 5821.9 5996.4 6070.2 6070.6 6074.0 5548.9 5639.9 5646.8 5768.9 5825.2 5851.9 5856.8 5876.2 5884.8 5903.8 5717.1 5717.2 5728.7 5736.7 5760.9 17:08:22 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:73481.64 Median:38564.90 stddev:169108.24 (438.5%) stddev-sans-first:169493.77 17:08:22 INFO - PROCESS | 1635 | Values: 465.7 489.7 528.4 533.4 533.6 36536.6 37198.0 37524.8 38827.2 39876.2 39523.2 39718.0 39721.2 39817.6 39979.0 9166.3 9326.1 9363.2 9372.5 9398.5 9209.1 9245.7 9271.5 9280.3 9298.9 9253.9 9271.4 9308.8 9317.0 9318.7 9216.0 9242.2 9257.7 9285.8 9288.2 9232.0 9296.0 9302.9 9313.7 9314.2 38309.2 38423.0 38487.8 38667.2 38828.0 3180.3 3256.2 3331.0 3475.1 3674.4 28660.0 28953.8 29096.9 29265.9 29469.6 2832.2 3044.3 3155.5 3208.7 3290.7 504508.0 511078.0 511162.0 511589.0 513108.0 54772.2 55084.4 55088.9 55182.2 55190.0 48016.3 56939.1 56970.0 57509.0 57808.1 15422.8 15454.4 15483.7 15645.8 15762.4 10492.2 12270.6 12305.4 12331.1 12382.1 12959.9 13848.7 14522.3 14580.1 14670.4 3162.3 3262.9 5851.0 5920.9 5970.5 837.5 911.6 938.5 952.7 955.8 26564.6 28832.0 29931.6 30362.5 30572.8 1004.7 1060.3 1121.6 1160.2 1162.5 14988.6 15220.0 15253.2 15459.0 15511.6 709699.0 715123.0 716002.0 719515.0 719695.0 57352.1 57798.7 57950.6 58018.0 58096.0 1343.1 1361.3 1370.0 1370.9 1372.3 25910.5 27824.4 28181.7 28993.4 29359.6 46920.5 47041.8 47383.8 47567.5 47649.3 642539.0 643201.0 644991.0 647362.0 650019.7 24473.9 25262.2 25506.6 25724.7 26375.1 36906.1 40388.0 43111.7 44066.5 44515.1 37880.6 38274.8 38642.0 38723.4 39310.2 35639.8 35856.3 36623.3 36649.0 36688.4 35825.3 35876.5 36168.6 36429.7 36460.3 31418.7 33874.3 33897.3 33972.8 34044.3 23879.8 24752.9 25015.9 25108.2 25200.6 17:08:22 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:35163.54 Median:4299.12 stddev:102747.40 (2390.0%) stddev-sans-first:103002.34 17:08:22 INFO - PROCESS | 1635 | Values: 332.3 376.9 380.5 386.9 403.4 19140.9 19303.3 19661.6 19745.6 19947.0 11262.9 11719.5 11900.9 11917.3 11919.4 5073.0 5349.1 5380.1 5449.7 5465.2 3923.9 4126.1 5387.8 5429.6 6370.6 6552.4 6685.2 6697.4 6703.7 6706.3 6043.5 6106.1 6108.3 6118.1 6206.7 4953.3 5259.8 5915.8 5943.8 5950.3 12825.1 12983.6 13069.2 13111.1 13320.9 2323.2 2406.6 2673.3 2809.4 2878.9 9478.0 9836.8 9938.1 10018.7 10420.2 1755.8 1878.3 1911.9 1918.2 1939.4 120350.0 121044.0 125082.4 126316.0 128247.7 11125.3 11131.7 11746.4 11889.6 11934.2 14584.0 14646.4 14655.8 14672.0 14697.8 8185.8 8392.8 8426.6 8566.2 8579.6 8203.5 8211.8 8238.4 8302.4 8365.7 4472.2 4541.7 4555.0 4604.6 4672.7 1576.8 1671.2 1683.3 1899.3 1963.7 895.9 924.8 955.3 958.4 980.5 19664.2 20398.6 20966.9 21075.5 21222.4 928.6 940.9 956.2 959.4 992.1 9070.5 10185.8 11107.9 11146.3 11161.6 603785.0 606945.0 613874.0 616946.0 626431.0 103363.0 124476.4 139285.3 139444.0 146979.0 855.5 893.7 1011.1 1012.9 1014.4 18948.0 19238.0 19316.9 19411.2 19553.8 54920.0 55229.7 55363.0 56037.6 57656.7 82179.8 82234.0 82675.6 83324.6 85847.0 7838.8 8027.3 11174.7 11777.6 11780.1 8421.3 8531.3 12166.8 12497.9 12932.1 7702.8 7812.2 7849.1 7916.2 8021.2 7549.9 7692.3 7874.4 7886.8 8028.6 7812.4 7877.0 7923.2 8049.9 8150.9 11844.8 11978.0 12044.2 12064.5 12267.5 9987.8 11065.3 11206.4 11438.9 11464.2 17:08:22 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5477.24 Median:5221.85 stddev:3005.87 (57.6%) stddev-sans-first:2997.41 17:08:22 INFO - PROCESS | 1635 | Values: 1236.9 1453.0 1578.9 1755.3 1761.9 7507.0 7785.5 8053.0 8551.2 8567.9 7056.4 7790.4 7919.8 8398.2 8430.4 2169.9 2275.8 2321.0 2339.2 2358.3 2190.5 2330.3 2352.1 2370.8 2389.1 2131.4 2327.4 2341.9 2347.3 2350.1 2295.3 2308.5 2326.7 2327.5 2335.7 2317.0 2334.3 2335.2 2343.3 2351.9 8421.4 8476.8 8487.8 8496.5 8519.7 5065.6 5221.6 5222.1 5227.3 5232.2 3200.3 3277.2 3375.2 3596.1 3604.2 1021.9 1034.4 1079.4 1079.8 1093.3 11042.6 11060.3 11144.3 11144.6 11159.1 4259.8 4323.7 4641.4 4724.1 4755.9 8559.5 9281.0 9529.7 9530.8 9545.9 5228.5 5230.3 5233.7 5237.5 5240.8 4260.1 4276.2 4753.1 4798.1 4808.5 3295.4 3835.3 3921.9 3945.4 3947.4 1267.3 1274.1 1275.2 1284.9 1297.2 794.7 898.2 905.7 914.5 929.8 5649.4 5656.4 5735.5 5897.9 5919.9 905.7 921.4 934.4 953.1 965.7 3702.8 3752.2 3817.4 3872.3 3878.1 9987.5 10336.1 10352.9 10359.6 10393.3 10158.3 10226.8 10235.4 10281.0 10290.4 7503.7 7895.4 7906.0 7922.9 8040.0 5820.8 5825.4 5891.5 5952.3 5998.8 8779.5 8884.3 9335.1 9581.0 9593.8 9204.1 9334.2 9511.0 9694.2 9707.3 6011.8 6503.8 7224.9 7265.6 7383.3 7157.0 8065.3 8127.2 8137.7 8140.2 7124.9 7302.3 7319.0 7350.8 7368.8 7014.3 7110.8 7126.6 7147.8 7252.6 5996.0 6646.3 6799.7 6809.4 6826.7 4837.6 4844.3 4884.0 4943.1 4968.5 4849.0 4896.3 4922.4 4935.7 5040.4 17:08:22 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4552.69 Median:5057.19 stddev:1421.31 (28.1%) stddev-sans-first:1388.65 17:08:22 INFO - PROCESS | 1635 | Values: 280.0 291.9 308.0 311.4 311.9 4729.9 5175.2 5404.3 5498.7 5515.8 4334.7 4365.0 5027.1 5274.7 5423.5 4123.1 4129.9 4146.8 4151.0 4158.2 4002.2 4141.7 4142.6 4171.4 4190.3 3755.3 4073.9 4080.5 4100.8 4116.7 3417.7 4079.3 4107.0 4125.1 4125.7 3268.4 3400.8 3411.6 3458.6 3641.7 4669.3 4881.7 5240.5 5274.3 5378.5 4613.0 4669.2 4686.4 4695.8 4708.8 5648.0 5697.6 5708.0 5732.3 5747.0 3407.8 3840.1 3854.5 3908.9 3924.9 5650.5 5675.7 5871.3 6020.0 6098.7 3441.6 3493.8 3807.9 3913.2 3933.5 3895.8 3907.5 3944.3 3969.4 3977.8 3759.5 3781.7 4227.7 4583.0 4603.0 4852.4 5740.4 5771.4 5784.9 5806.8 1075.2 1107.5 1219.5 1278.0 1287.9 840.9 868.5 868.7 874.9 876.0 4712.9 4788.0 4844.0 5149.6 5188.8 4888.6 5152.2 5334.7 5475.8 5529.2 4106.5 5087.3 5092.7 5104.7 5115.0 2843.6 2920.6 2961.9 2977.9 3004.6 4938.9 5689.0 5701.9 5705.9 5764.8 4898.7 4996.6 5771.2 6061.3 6107.4 5334.0 5605.4 6078.4 6097.0 6125.4 5366.8 5668.7 5940.4 5990.5 6052.8 4947.2 4977.3 5153.2 5892.9 5954.9 5395.5 5928.3 5936.3 6086.6 6093.0 3496.9 3812.3 3934.1 3974.1 3979.4 4365.6 5911.3 5912.6 5929.3 5936.8 5534.9 5710.0 5728.6 5751.6 5766.6 5696.1 5696.8 5736.6 5755.3 5785.8 5468.5 5545.5 5555.5 5593.2 5650.2 4724.8 4726.5 5265.6 5280.0 5745.5 4558.1 5615.6 5631.9 5648.9 5653.3 17:08:22 INFO - PROCESS | 1635 | 17:08:22 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20449.06 Median:51473.24 stddev:31687.72 (61.6%) stddev-sans-first:31741.67 17:08:22 INFO - PROCESS | 1635 | Values: 628.3 695.1 717.8 743.0 753.6 13801.5 14679.5 14689.3 14695.5 14772.6 13275.8 13307.7 13377.7 13427.5 13449.3 633.5 655.2 785.9 800.1 803.3 802.6 814.0 814.3 815.2 816.9 864.2 865.5 867.2 867.5 868.5 853.8 859.1 863.8 863.9 864.3 641.9 643.1 643.6 643.7 644.9 12132.6 13740.0 14030.5 14051.6 14056.4 13444.9 13581.7 13584.2 13734.6 13763.1 10970.1 11038.3 11172.8 11235.0 11279.9 2942.0 3233.1 3279.3 3727.7 3769.1 62382.1 80805.2 81417.2 83680.0 87180.7 8860.6 10518.7 10555.0 10655.5 10931.1 18662.8 19220.7 20531.4 20770.2 20785.5 7662.7 7674.6 7724.0 7725.4 7854.2 3849.4 4128.8 4159.2 4236.2 4359.4 3199.4 3268.0 3301.7 3303.1 3304.4 603.1 610.9 620.2 631.6 632.5 536.9 578.6 585.9 599.4 603.8 7331.5 7996.3 8688.7 8747.6 8808.9 552.7 555.0 602.1 608.6 611.4 4018.4 4085.2 4165.5 4171.3 4239.2 119955.4 120875.0 122563.0 123037.0 123136.0 102244.5 102606.5 106865.5 108511.0 108829.0 81072.0 94482.0 95193.0 95581.5 96151.0 8130.7 8167.0 8174.8 8185.4 8399.0 42345.6 50951.0 51995.5 52685.7 53464.0 68187.7 69147.6 70611.9 72739.0 74212.0 10160.7 10270.0 10318.5 10341.7 10630.9 11485.4 11533.8 11587.0 11737.5 11815.7 10490.2 10776.2 10845.1 11044.7 11489.8 11070.5 11078.2 11133.7 11154.0 11180.2 10487.3 10663.1 10709.7 10796.3 10866.5 8905.2 9359.0 9501.6 9520.9 9611.1 8990.4 9324.8 9350.3 9372.6 9459.3 17:08:22 INFO - PROCESS | 1635 | -------- Summary: end -------- 17:08:22 INFO - PROCESS | 1635 | 17:08:23 INFO - TEST-INFO | 1635: exit 0 17:08:23 INFO - TEST-OK | dromaeo_css | took 1115952ms 17:08:23 INFO - TEST-START | kraken 17:08:23 INFO - operating with platform_type : mac_ 17:08:23 INFO - Initialising browser for kraken test... 17:08:23 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpTvpik8/profile http://localhost:49235/getInfo.html) 17:08:26 INFO - PROCESS | 1647 | 2017-01-20 17:08:26.751 plugin-container[1648:10098] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 17:08:26 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:08:26 INFO - PROCESS | 1647 | 2017-01-20 17:08:26.754 plugin-container[1648:10098] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 17:08:26 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:08:26 INFO - PROCESS | 1647 | 2017-01-20 17:08:26.754 plugin-container[1648:10098] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:08:26 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 17:08:26 INFO - PROCESS | 1647 | colorDepth:24 17:08:26 INFO - PROCESS | 1647 | Browser inner width/height: 1024/663 17:08:26 INFO - PROCESS | 1647 | __metrics 17:08:26 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 17:08:27 INFO - TEST-INFO | 1647: exit 0 17:08:27 INFO - Browser initialized. 17:08:27 INFO - Running cycle 1/1 for kraken test... 17:08:27 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpTvpik8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:08:30 INFO - PROCESS | 1649 | 2017-01-20 17:08:30.603 plugin-container[1650:10223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 17:08:30 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:08:30 INFO - PROCESS | 1649 | 2017-01-20 17:08:30.604 plugin-container[1650:10223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 17:08:30 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:08:30 INFO - PROCESS | 1649 | 2017-01-20 17:08:30.604 plugin-container[1650:10223] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:08:40 INFO - PROCESS | 1649 | RSS: Main: 238895104 17:08:40 INFO - PROCESS | 1649 | 17:08:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 17:08:43 INFO - PROCESS | 1649 | RSS: Main: 248930304 17:08:43 INFO - PROCESS | 1649 | 17:08:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 17:08:46 INFO - PROCESS | 1649 | RSS: Main: 257015808 17:08:46 INFO - PROCESS | 1649 | 17:08:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 17:08:50 INFO - PROCESS | 1649 | RSS: Main: 257957888 17:08:50 INFO - PROCESS | 1649 | 17:08:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 17:08:53 INFO - PROCESS | 1649 | RSS: Main: 257822720 17:08:53 INFO - PROCESS | 1649 | 17:08:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:08:55 INFO - PROCESS | 1649 | RSS: Main: 257830912 17:08:55 INFO - PROCESS | 1649 | 17:08:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 17:08:59 INFO - PROCESS | 1649 | RSS: Main: 261623808 17:08:59 INFO - PROCESS | 1649 | 17:09:02 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 17:09:03 INFO - PROCESS | 1649 | RSS: Main: 259067904 17:09:03 INFO - PROCESS | 1649 | 17:09:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 17:09:07 INFO - PROCESS | 1649 | RSS: Main: 260489216 17:09:07 INFO - PROCESS | 1649 | 17:09:08 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:09:09 INFO - PROCESS | 1649 | RSS: Main: 259919872 17:09:09 INFO - PROCESS | 1649 | 17:09:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:09:12 INFO - PROCESS | 1649 | RSS: Main: 260034560 17:09:12 INFO - PROCESS | 1649 | 17:09:14 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:09:14 INFO - PROCESS | 1649 | RSS: Main: 259960832 17:09:14 INFO - PROCESS | 1649 | 17:09:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:09:18 INFO - PROCESS | 1649 | RSS: Main: 260018176 17:09:18 INFO - PROCESS | 1649 | 17:09:21 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:09:22 INFO - PROCESS | 1649 | RSS: Main: 260411392 17:09:22 INFO - PROCESS | 1649 | 17:09:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 17:09:24 INFO - PROCESS | 1649 | RSS: Main: 262139904 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | __start_tp_report 17:09:24 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 17:09:24 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 17:09:24 INFO - PROCESS | 1649 | |i|pagename|runs| 17:09:24 INFO - PROCESS | 1649 | |0;ai-astar;108;127;118;119;120;119;119;118;121;120 17:09:24 INFO - PROCESS | 1649 | |1;audio-beat-detection;144;113;111;145;123;114;108;99;120;150 17:09:24 INFO - PROCESS | 1649 | |2;audio-dft;198;200;195;205;198;193;230;266;261;192 17:09:24 INFO - PROCESS | 1649 | |3;audio-fft;78;57;59;82;63;53;60;60;78;59 17:09:24 INFO - PROCESS | 1649 | |4;audio-oscillator;89;70;89;73;70;70;86;70;72;88 17:09:24 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;123;125;91;88;90;75;130;121;94;90 17:09:24 INFO - PROCESS | 1649 | |6;imaging-darkroom;165;119;165;165;101;140;130;114;164;162 17:09:24 INFO - PROCESS | 1649 | |7;imaging-desaturate;91;92;129;87;77;140;142;93;89;91 17:09:24 INFO - PROCESS | 1649 | |8;json-parse-financial;57;57;57;59;60;61;60;58;60;59 17:09:24 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;75;91;103;107;88;69;70;72;72;73 17:09:24 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;84;87;85;82;84;83;87;88;85;82 17:09:24 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;142;140;139;138;143;147;142;160;258;160 17:09:24 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;168;186;207;216;150;216;210;167;167;203 17:09:24 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;70;67;68;68;69;67;69;68;66;69 17:09:24 INFO - PROCESS | 1649 | __end_tp_report 17:09:24 INFO - PROCESS | 1649 | __start_cc_report 17:09:24 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,983 17:09:24 INFO - PROCESS | 1649 | __end_cc_report 17:09:24 INFO - PROCESS | 1649 | __startTimestamp1484960964213__endTimestamp 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | ------- Summary: start ------- 17:09:24 INFO - PROCESS | 1649 | Number of tests: 14 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:118.90 Median:119.50 stddev:4.63 (3.9%) stddev-sans-first:2.76 17:09:24 INFO - PROCESS | 1649 | Values: 108.0 127.0 118.0 119.0 120.0 119.0 119.0 118.0 121.0 120.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:133.50 stddev:17.60 (13.2%) stddev-sans-first:16.90 17:09:24 INFO - PROCESS | 1649 | Values: 144.0 113.0 111.0 145.0 123.0 114.0 108.0 99.0 120.0 150.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:213.80 Median:202.50 stddev:28.37 (14.0%) stddev-sans-first:29.51 17:09:24 INFO - PROCESS | 1649 | Values: 198.0 200.0 195.0 205.0 198.0 193.0 230.0 266.0 261.0 192.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:64.90 Median:61.50 stddev:10.33 (16.8%) stddev-sans-first:9.81 17:09:24 INFO - PROCESS | 1649 | Values: 78.0 57.0 59.0 82.0 63.0 53.0 60.0 60.0 78.0 59.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.50 stddev:8.96 (11.3%) stddev-sans-first:8.52 17:09:24 INFO - PROCESS | 1649 | Values: 89.0 70.0 89.0 73.0 70.0 70.0 86.0 70.0 72.0 88.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:89.00 stddev:19.74 (22.2%) stddev-sans-first:19.53 17:09:24 INFO - PROCESS | 1649 | Values: 123.0 125.0 91.0 88.0 90.0 75.0 130.0 121.0 94.0 90.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:142.50 Median:163.00 stddev:24.97 (15.3%) stddev-sans-first:25.12 17:09:24 INFO - PROCESS | 1649 | Values: 165.0 119.0 165.0 165.0 101.0 140.0 130.0 114.0 164.0 162.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:90.00 stddev:24.04 (26.7%) stddev-sans-first:25.10 17:09:24 INFO - PROCESS | 1649 | Values: 91.0 92.0 129.0 87.0 77.0 140.0 142.0 93.0 89.0 91.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.50 stddev:1.48 (2.5%) stddev-sans-first:1.41 17:09:24 INFO - PROCESS | 1649 | Values: 57.0 57.0 57.0 59.0 60.0 61.0 60.0 58.0 60.0 59.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:72.50 stddev:14.24 (19.6%) stddev-sans-first:14.88 17:09:24 INFO - PROCESS | 1649 | Values: 75.0 91.0 103.0 107.0 88.0 69.0 70.0 72.0 72.0 73.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.00 stddev:2.11 (2.5%) stddev-sans-first:2.22 17:09:24 INFO - PROCESS | 1649 | Values: 84.0 87.0 85.0 82.0 84.0 83.0 87.0 88.0 85.0 82.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:156.90 Median:145.00 stddev:36.42 (25.1%) stddev-sans-first:38.23 17:09:24 INFO - PROCESS | 1649 | Values: 142.0 140.0 139.0 138.0 143.0 147.0 142.0 160.0 258.0 160.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:205.00 stddev:24.40 (11.9%) stddev-sans-first:24.67 17:09:24 INFO - PROCESS | 1649 | Values: 168.0 186.0 207.0 216.0 150.0 216.0 210.0 167.0 167.0 203.0 17:09:24 INFO - PROCESS | 1649 | 17:09:24 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:1.20 (1.7%) stddev-sans-first:1.05 17:09:24 INFO - PROCESS | 1649 | Values: 70.0 67.0 68.0 68.0 69.0 67.0 69.0 68.0 66.0 69.0 17:09:24 INFO - PROCESS | 1649 | -------- Summary: end -------- 17:09:24 INFO - PROCESS | 1649 | 17:09:25 INFO - TEST-INFO | 1649: exit 0 17:09:25 INFO - TEST-OK | kraken | took 61619ms 17:09:25 INFO - SUITE-END | took 1177s 17:09:25 INFO - Completed test suite (00:19:37) 17:09:25 INFO - javascript benchmark 17:09:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7047.288290142602, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3277.1970867137547, 3343.6322004579506, 3353.396388650043, 3366.5960084789813, 3370.4101782188127, 5600.439956004398, 5602.60781745278, 5650.547956163502, 5677.9716101419435, 5732.369439361667, 5719.656820590768, 5751.309842818868, 5763.5389168866495, 5771.653700777946, 5775.364709881909, 5133.537981581648, 5168.974155129238, 5193.298904647862, 5214.739263036852, 5268.420473747885, 5266.289050978107, 5277.12927366984, 5287.471252874701, 5298.09932311508, 5320.866978325554, 5260.132078207111, 5271.051210782058, 5272.446393128724, 5276.261323414725, 5280.392754833186, 4479.48485924118, 5031.943291908704, 5058.721770302632, 5099.4390617032095, 5139.563137133348, 5044.6973181608855, 5154.123798954187, 5157.561607263349, 5160.122779127557, 5211.452797456288, 4833.7583120844165, 5591.896497443917, 5666.490015898589, 5684.976704193285, 5723.227364305807, 5270.288511051036, 5442.673439593597, 6042.6978651067275, 6047.516198704093, 6062.939370606326, 6108.6759897029115, 6110.71064601923, 6119.959572021022, 6141.600795948294, 6142.713648198237, 5707.857303567403, 5729.8567535811935, 5740.0815869460685, 5820.35976042635, 5903.763849446017, 6191.628502289877, 6200.379962003809, 6210.254769427697, 6232.376762323822, 6241.9375806241815, 5857.15071314657, 6086.0, 6093.573449858556, 6142.416470435302, 6147.784827531015, 6021.458068773832, 6105.581053426346, 6109.647596799064, 6125.632272693312, 6133.84665383369, 6111.1967345965195, 6121.57491608479, 6134.283518479771, 6162.075688646739, 6194.0708893665405, 4588.541145885385, 5605.7197140142825, 5886.735096920649, 5982.551308651869, 6203.16257305258, 4806.2310030394965, 4829.348038014893, 4857.149998750205, 4908.901821963541, 4920.3111564381015, 4356.825726970886, 4356.9128617428105, 4366.912661746747, 4379.627731642846, 4382.408374869416, 5129.794808207705, 5527.281453411031, 5737.9384813809575, 5842.824715258549, 5849.385814489502, 6099.9390006099375, 6125.235451818339, 6126.264848218242, 6144.218176728698, 6240.7503699852405, 5632.943670563324, 5652.378238393773, 5691.658500489983, 5823.155642431909, 5840.2991641003355, 5369.48989845964, 5376.301080859464, 5389.730513474311, 5416.0, 5432.945670543244, 6129.083728908156, 6190.164327815687, 6277.780277690259, 6282.4974002079725, 6290.968545157337, 6127.953177891068, 6128.503123582109, 6129.908988904127, 6130.478909292582, 6236.532260080603, 6238.033104868752, 6255.40573645503, 6281.842953926189, 6284.717187726472, 6291.276503202191, 6134.2332208473945, 6144.907826382697, 6145.324014358328, 6147.44672979434, 6148.784792532419, 5082.974585127051, 5554.305711786027, 6114.388610881841, 6130.52043589939, 6193.102000210034, 6049.213602231682, 6085.208922840003, 6091.451769340781, 6101.664408457577, 6125.448709616156, 5821.854453638693, 5996.430339117776, 6070.150178974859, 6070.575059745952, 6073.967425537581, 5548.861278468071, 5639.943600564106, 5646.774129034792, 5768.855778605568, 5825.155352473952, 5851.941480585139, 5856.795012174553, 5876.206712093813, 5884.764609415575, 5903.793367232126, 5717.056685646821, 5717.22817419643, 5728.656280623176, 5736.713164341682, 5760.884782304414], "value": 5601.08568243037, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.7159132928915, 489.7208591103072, 528.3976267055557, 533.4159095790109, 533.6077982682727, 36536.63463365366, 37198.0, 37524.81237593808, 38827.223455530875, 39876.202475950464, 39523.20953580927, 39718.0, 39721.20557588847, 39817.60182398175, 39979.00052498689, 9166.312526560501, 9326.114019168168, 9363.204127649158, 9372.531373431335, 9398.483083430408, 9209.079092090804, 9245.676401325922, 9271.536423178848, 9280.257579393667, 9298.86051709225, 9253.907460925371, 9271.397359171666, 9308.767280817967, 9316.953415232914, 9318.72043838686, 9215.95392023039, 9242.214411775007, 9257.722268331961, 9285.767855803591, 9288.21050210733, 9231.953840230823, 9296.0, 9302.906970930271, 9313.720588382292, 9314.208292295163, 38309.23381532354, 38423.0, 38487.807560962014, 38667.22665546701, 38828.0, 3180.3321302526497, 3256.234784825564, 3330.9500357494658, 3475.061733331986, 3674.4488326750934, 28660.0, 28953.841846326333, 29096.854515727497, 29265.85367073172, 29469.557956630666, 2832.2069820450306, 3044.34546572488, 3155.5108958111527, 3208.69517395847, 3290.7038366546885, 504508.0, 511078.0, 511162.0, 511589.0, 513108.0, 54772.178417322975, 55084.44915550874, 55088.898222035335, 55182.17226741602, 55190.0, 48016.27975580369, 56939.14591281134, 56970.0, 57509.0, 57808.132878006865, 15422.768658470131, 15454.38182472711, 15483.690326193413, 15645.765313520305, 15762.369505219664, 10492.213084018576, 12270.631881043582, 12305.44625491855, 12331.136820422664, 12382.071344649188, 12959.935200323938, 13848.723025539633, 14522.273886305642, 14580.125192488273, 14670.413183472756, 3162.256869635634, 3262.869485220612, 5851.0, 5920.940790592125, 5970.49250813677, 837.5267973594899, 911.5624500239921, 938.4697645830221, 952.6903756279237, 955.8040601676639, 26564.601530977052, 28832.0, 29931.551026734618, 30362.54456183159, 30572.77708891664, 1004.7036124343336, 1060.2896059639907, 1121.5569849909402, 1160.1530882455854, 1162.4536467860091, 14988.62528436776, 15220.0, 15253.237338133049, 15459.0, 15511.612209694847, 709699.0, 715123.0, 716002.0, 719515.0, 719695.0, 57352.13971790426, 57798.7110064447, 57950.55123621859, 58018.0, 58096.0, 1343.147101590497, 1361.271719630013, 1370.0, 1370.9177449353072, 1372.28641106624, 25910.481790364465, 27824.44351113007, 28181.71818281791, 28993.42013159767, 29359.55960660549, 46920.530794691615, 47041.764791175825, 47383.76308118437, 47567.524324757695, 47649.28526072181, 642539.0, 643201.0, 644991.0, 647362.0, 650019.7499012474, 24473.898674560045, 25262.242132736046, 25506.617400739375, 25724.742752572234, 26375.076872310146, 36906.14252579415, 40388.0, 43111.72375493346, 44066.457673981255, 44515.109697805216, 37880.621193788815, 38274.80862595669, 38642.0, 38723.41914871336, 39310.21379572335, 35639.82180089083, 35856.28287434184, 36623.334142824206, 36649.0, 36688.44967325439, 35825.28349433049, 35876.461853071705, 36168.638313616524, 36429.724959626285, 36460.27079458449, 31418.685813141576, 33874.3225135491, 33897.322053559285, 33972.83013584916, 34044.31911361808, 23879.761202387755, 24752.876235618707, 25015.87492062528, 25108.199265737483, 25200.621990669788], "value": 19061.896529773145, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.3021654525497, 376.9283836071148, 380.4692454026634, 386.9455733127227, 403.36872794077266, 19140.90429547852, 19303.32438364656, 19661.60676786467, 19745.605087898235, 19947.002649867485, 11262.887371126286, 11719.472623731932, 11900.940495297511, 11917.344546049964, 11919.40402979852, 5072.974635126829, 5349.144136938091, 5380.085385484457, 5449.700266485342, 5465.207544906006, 3923.8626648067325, 4126.0510082681, 5387.757550910218, 5429.619926605139, 6370.649614271213, 6552.410283074522, 6685.164354455693, 6697.3637504437, 6703.664816759167, 6706.26231114577, 6043.486303664194, 6106.114613381079, 6108.3280839107665, 6118.112873633343, 6206.6586337751405, 4953.257011448276, 5259.8422047338645, 5915.7633694652595, 5943.821685349403, 5950.256217972754, 12825.102242843004, 12983.610491685264, 13069.15050521713, 13111.082224244306, 13320.933395333059, 2323.1520495019195, 2406.554787364326, 2673.3049407154085, 2809.3959798643186, 2878.8848446062134, 9477.952610236973, 9836.76634324799, 9938.055884690943, 10018.749531261705, 10420.166386689047, 1755.824417558234, 1878.2956391353243, 1911.8661693681447, 1918.2039453626728, 1939.4084804134734, 120350.0, 121044.0, 125082.3745881274, 126316.0, 128247.7175228245, 11125.276857004268, 11131.666050018512, 11746.353950532803, 11889.643310700692, 11934.22427542207, 14584.0, 14646.414143434144, 14655.780163297559, 14672.0, 14697.77953330701, 8185.79535511617, 8392.790180245544, 8426.620802063922, 8566.229039386486, 8579.571021448903, 8203.466774659628, 8211.794705132419, 8238.423310368216, 8302.418830681794, 8365.749027529184, 4472.195004899149, 4541.704789188692, 4555.0, 4604.58558729716, 4672.74299913508, 1576.7556028815552, 1671.189473105543, 1683.3098429643787, 1899.268781519125, 1963.7250784890127, 895.8790563273875, 924.8289066522715, 955.2739917662487, 958.3866325551653, 980.5146452506056, 19664.213431462867, 20398.592028159354, 20966.895165524074, 21075.473113172295, 21222.363329100153, 928.5914197752967, 940.9435433873989, 956.1824639932896, 959.3668179001826, 992.0773680477225, 9070.455772653662, 10185.847212291821, 11107.944460277648, 11146.331220126818, 11161.553537858395, 603785.0, 606945.0, 613874.0, 616946.0, 626431.0, 103363.0, 124476.37761811134, 139285.30357348148, 139444.0, 146979.0, 855.4738835616175, 893.7229458867772, 1011.0849681038671, 1012.8632634594383, 1014.4167103915278, 18948.0, 19238.0, 19316.93756843387, 19411.2235510578, 19553.804461955766, 54920.0, 55229.723851380484, 55363.0, 56037.59906002219, 57656.71171644283, 82179.7673034917, 82234.0, 82675.5866220665, 83324.58337708273, 85847.0, 7838.764837054898, 8027.2775450209765, 11174.709184617297, 11777.646670599896, 11780.116491263361, 8421.284190843602, 8531.274841638531, 12166.817497737718, 12497.875021250034, 12932.09475336755, 7702.807429814299, 7812.218778122142, 7849.097353804157, 7916.208379162038, 8021.197880211932, 7549.886751698619, 7692.307692307719, 7874.409419293462, 7886.763397098096, 8028.638711258124, 7812.429863192666, 7876.960615196887, 7923.247291507298, 8049.919500804917, 8150.9184908150155, 11844.763104737685, 11978.0, 12044.156909016285, 12064.45709943037, 12267.509299627915, 9987.751531058617, 11065.280756750786, 11206.43967801623, 11438.885611143782, 11464.197506174487], "value": 9138.735805252887, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.925784452933, 1452.9854701452985, 1578.8579027887488, 1755.2803350626245, 1761.8766686331946, 7507.0, 7785.493942893722, 8053.033635963678, 8551.187637174466, 8567.91432085679, 7056.400205982498, 7790.3767698584115, 7919.802004949879, 8398.244158025776, 8430.409871309012, 2169.915042478761, 2275.772422757719, 2320.9651855222187, 2339.2397470821966, 2358.292512246328, 2190.5290362572046, 2330.312557795446, 2352.1179557665873, 2370.7747763962398, 2389.1399096325395, 2131.424515380854, 2327.3716096653893, 2341.94145146371, 2347.3310106619615, 2350.0717216699395, 2295.2884605772265, 2308.5036717105904, 2326.674265602817, 2327.476317828492, 2335.6730057791838, 2316.953660926772, 2334.3113781434517, 2335.1826860598826, 2343.320437073247, 2351.8824058797163, 8421.368397370221, 8476.78808029798, 8487.787805304855, 8496.532690702008, 8519.659213631447, 5065.569426598743, 5221.608379371524, 5222.0861349264105, 5227.268182454422, 5232.215167724834, 3200.3279311344645, 3277.213468767479, 3375.1899544109483, 3596.065023093988, 3604.2250916053176, 1021.8927012663712, 1034.3811934322837, 1079.433297518801, 1079.7570546626946, 1093.278436232083, 11042.613508527164, 11060.336379817236, 11144.275622084539, 11144.554217831195, 11159.051480624157, 4259.849840543098, 4323.654107671378, 4641.350210970467, 4724.055188962221, 4755.904881902412, 8559.529225892637, 9280.953595232115, 9529.714108576754, 9530.761730956643, 9545.856812147824, 5228.529432351107, 5230.267762513251, 5233.659812112201, 5237.450067742832, 5240.816571419982, 4260.0840819223395, 4276.2302785498905, 4753.1444340019125, 4798.1123492153365, 4808.543188397097, 3295.35740530599, 3835.328817456934, 3921.9019524512114, 3945.447637330776, 3947.388154836005, 1267.309316422552, 1274.1399555300136, 1275.1775105057186, 1284.8715128487263, 1297.1633296523878, 794.7377365469497, 898.2095755734912, 905.651324240159, 914.4741773480275, 929.809389075238, 5649.37856835756, 5656.377798442168, 5735.483806457439, 5897.852553686193, 5919.911201331984, 905.6649039855296, 921.4102974096584, 934.3739694404765, 953.137410643355, 965.7151140413595, 3702.8148592570265, 3752.155764952918, 3817.4464702617965, 3872.283627528916, 3878.0692633767694, 9987.500624968723, 10336.0697537222, 10352.948235258926, 10359.63741269052, 10393.272470926962, 10158.339707918962, 10226.795464090825, 10235.385876847413, 10281.023302786223, 10290.382577045251, 7503.699852005859, 7895.368370530344, 7905.960470197612, 7922.881156782768, 8039.959800200962, 5820.76716931318, 5825.3883342248455, 5891.469767720926, 5952.255968003999, 5998.790042348497, 8779.517126558101, 8884.333674974532, 9335.113164249386, 9580.95209523948, 9593.760155996157, 9204.125608067223, 9334.159925606456, 9511.0, 9694.17599504022, 9707.349750542284, 6011.789587364421, 6503.772367967098, 7224.8916266255, 7265.564066156047, 7383.2985866342615, 7156.964215178891, 8065.274125328514, 8127.2279133482225, 8137.6744930204495, 8140.185981401813, 7124.895641175626, 7302.342789149002, 7319.0, 7350.77947661571, 7368.778936631909, 7014.333638304353, 7110.786676399716, 7126.608036558039, 7147.82130446743, 7252.601106938958, 5995.9700201500455, 6646.335366463315, 6799.6600169990315, 6809.421199198124, 6826.692798823966, 4837.588804951619, 4844.297576851266, 4884.023195360871, 4943.110240156806, 4968.453470118357, 4848.9757551212015, 4896.2655601660035, 4922.384701912261, 4935.679180853233, 5040.36995375578], "value": 4441.172313166345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.01853503470335, 291.9328554432482, 308.03431243053035, 311.4005539336776, 311.8534288884225, 4729.858104256878, 5175.171972484394, 5404.2974413326265, 5498.697571633558, 5515.821250606008, 4334.696571240007, 4364.978175109128, 5027.0699920514635, 5274.709890955995, 5423.484768946953, 4123.11353059093, 4129.855455059073, 4146.751194928314, 4150.966026646952, 4158.230727315441, 4002.2395744808537, 4141.722052870924, 4142.627163555279, 4171.353440216772, 4190.287651099306, 3755.305268525318, 4073.8981525461954, 4080.531008836831, 4100.753954762724, 4116.670666346685, 3417.7436692248057, 4079.2861249281405, 4107.0, 4125.092479654471, 4125.711200215986, 3268.3953468608383, 3400.829958502065, 3411.5906091269208, 3458.5503884495106, 3641.708663306929, 4669.287742499151, 4881.656065595079, 5240.528352448298, 5274.314339135926, 5378.489043540857, 4612.95387046132, 4669.237377874549, 4686.414198225222, 4695.783388378972, 4708.788104535304, 5647.98336299466, 5697.6011679182475, 5708.0, 5732.283464566929, 5746.965546201682, 3407.7561689983227, 3840.135969406889, 3854.4796452478836, 3908.882733517999, 3924.9018774530578, 5650.51970582501, 5675.744591493392, 5871.295444546682, 6020.0, 6098.72555734993, 3441.6042155151977, 3493.8078405687424, 3807.904802379963, 3913.241164978683, 3933.4689816874356, 3895.7662540247675, 3907.47249121365, 3944.3491823849317, 3969.4442778011103, 3977.7812220327583, 3759.5300587426573, 3781.7352785305316, 4227.7040607157205, 4582.95417045832, 4602.976985115053, 4852.417709874838, 5740.368559458456, 5771.394003629643, 5784.855378615484, 5806.767729290866, 1075.1506310015156, 1107.4739498738036, 1219.5182902753363, 1277.9552715655093, 1287.922724636525, 840.9159084091542, 868.5049521772529, 868.700298397052, 874.8950125984923, 875.9868601970976, 4712.929306060412, 4787.976060119677, 4843.951560484421, 5149.613778966593, 5188.818391356284, 4888.633352498577, 5152.227165925066, 5334.733263336818, 5475.808346707846, 5529.225908372831, 4106.548279689232, 5087.303729023623, 5092.745362731848, 5104.744762761847, 5115.0, 2843.5545430929383, 2920.6495220573665, 2961.8667159977854, 2977.895773647955, 3004.564338170953, 4938.851834444972, 5689.046112951459, 5701.868318513371, 5705.914411283834, 5764.798232061858, 4898.706077635282, 4996.550310472075, 5771.192033115367, 6061.302950160788, 6107.389261073857, 5334.012952746474, 5605.411431799684, 6078.3921607838865, 6097.0, 6125.356837531995, 5366.785328586813, 5668.716564171693, 5940.405959404198, 5990.495560097218, 6052.848678783065, 4947.183714687037, 4977.278294647328, 5153.2270159476375, 5892.939270931273, 5954.880902381841, 5395.487428694268, 5928.318243402064, 5936.28764548257, 6086.6348019119, 6092.969535152296, 3496.901858884649, 3812.275667623143, 3934.075492259374, 3974.1058261892, 3979.4030895364767, 4365.585269399402, 5911.349751527243, 5912.556558258062, 5929.347771745191, 5936.762529498772, 5534.900455744796, 5710.0, 5728.627639203605, 5751.568632352507, 5766.5675074368755, 5696.1455781633085, 5696.772129114789, 5736.569757268305, 5755.251817263729, 5785.805291163872, 5468.48049435303, 5545.528630066489, 5555.472230138131, 5593.216949626974, 5650.248422988887, 4724.763761811827, 4726.5273472653835, 5265.604614777164, 5279.973600131974, 5745.454181852718, 4558.065596552765, 5615.578831587565, 5631.8592035199445, 5648.858778530405, 5653.349864765604], "value": 4091.4390466798864, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.2931701835436, 695.0790202981049, 717.7559629725895, 743.0266351080083, 753.5704648350436, 13801.51694690686, 14679.486217982372, 14689.265536723162, 14695.485658001971, 14772.556823295292, 13275.800862987064, 13307.667308317297, 13377.66555836102, 13427.462901483954, 13449.32753362333, 633.5027003802016, 655.1744801550034, 785.9371250299962, 800.0639252075072, 803.2650125135523, 802.6227672993713, 813.9918600813975, 814.3403842887251, 815.1725998111921, 816.8897198878135, 864.2351518905782, 865.4936861935776, 867.1501928110456, 867.4621734524603, 868.5266529741275, 853.7652145659962, 859.1494420523699, 863.7624653220383, 863.8617821148586, 864.2869632553169, 641.9197600299963, 643.1446176585131, 643.6234802640461, 643.6845945486677, 644.8968165093562, 12132.636020919299, 13740.0, 14030.508932187324, 14051.578452646334, 14056.437742490289, 13444.865551344557, 13581.728365432637, 13584.184948903097, 13734.587962361145, 13763.086225420324, 10970.122390208764, 11038.282511636718, 11172.776544469065, 11235.04502117321, 11279.887201128047, 2941.9852900735573, 3233.094733474631, 3279.2785587170783, 3727.683146932514, 3769.076576238839, 62382.064269035545, 80805.19194808035, 81417.18582814155, 83680.0, 87180.6922896157, 8860.601272942604, 10518.684439466828, 10555.0, 10655.467226638637, 10931.125509959184, 18662.813371866378, 19220.654554181074, 20531.38405847827, 20770.1691932321, 20785.480362991046, 7662.73180438682, 7674.616269186518, 7724.0, 7725.381969442544, 7854.175311592315, 3849.40334248192, 4128.772917489507, 4159.230542349675, 4236.2374772540625, 4359.411479450234, 3199.3601279744144, 3267.967320326766, 3301.735861131104, 3303.0751389610946, 3304.4052070627035, 603.0923460192382, 610.9022556391045, 620.1937481274325, 631.6052467207995, 632.4876849751624, 536.9436209198057, 578.6181120460476, 585.92382990211, 599.3886236039216, 603.7826382502295, 7331.450141239428, 7996.280334769783, 8688.66129700734, 8747.606357713918, 8808.867866982, 552.6656372894341, 554.9611527193139, 602.0908428273339, 608.6256951974592, 611.4160976267653, 4018.3972404139613, 4085.20338533989, 4165.4584903962295, 4171.290880550314, 4239.1521695660995, 119955.40022299833, 120875.0, 122563.0, 123037.0, 123136.0, 102244.48877755564, 102606.48696756469, 106865.4656726727, 108511.0, 108829.0, 81072.0, 94482.0, 95193.0, 95581.52209238909, 96151.0, 8130.715424960098, 8166.959165204374, 8174.7956301092945, 8185.4270201085355, 8398.958005209935, 42345.576544234165, 50951.0, 51995.48004519907, 52685.7365713169, 53464.0, 68187.65906170437, 69147.61704765775, 70611.9408208867, 72739.0, 74212.0, 10160.745981350525, 10270.0, 10318.48407579633, 10341.74145646365, 10630.946845265724, 11485.368304743319, 11533.769324613628, 11587.0, 11737.471813768572, 11815.70460738454, 10490.213234007328, 10776.245662803529, 10845.132389408986, 11044.723881903017, 11489.770204596029, 11070.501827417624, 11078.169137314895, 11133.721656958642, 11154.0, 11180.217384782987, 10487.318324308895, 10663.146948243966, 10709.732256693645, 10796.298240614335, 10866.456677166268, 8905.243054340453, 9359.0, 9501.619935202514, 9520.90479095228, 9611.086946740048, 8990.415622984485, 9324.766880828034, 9350.25197984146, 9372.625094996396, 9459.290553208675], "value": 6908.586463100542, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 127.0, 118.0, 119.0, 120.0, 119.0, 119.0, 118.0, 121.0, 120.0], "value": 118.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 111.0, 145.0, 123.0, 114.0, 108.0, 99.0, 120.0, 150.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 200.0, 195.0, 205.0, 198.0, 193.0, 230.0, 266.0, 261.0, 192.0], "value": 213.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 59.0, 82.0, 63.0, 53.0, 60.0, 60.0, 78.0, 59.0], "value": 64.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 89.0, 73.0, 70.0, 70.0, 86.0, 70.0, 72.0, 88.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 125.0, 91.0, 88.0, 90.0, 75.0, 130.0, 121.0, 94.0, 90.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 119.0, 165.0, 165.0, 101.0, 140.0, 130.0, 114.0, 164.0, 162.0], "value": 142.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 129.0, 87.0, 77.0, 140.0, 142.0, 93.0, 89.0, 91.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 59.0, 60.0, 61.0, 60.0, 58.0, 60.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 91.0, 103.0, 107.0, 88.0, 69.0, 70.0, 72.0, 72.0, 73.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 85.0, 82.0, 84.0, 83.0, 87.0, 88.0, 85.0, 82.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 139.0, 138.0, 143.0, 147.0, 142.0, 160.0, 258.0, 160.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 186.0, 207.0, 216.0, 150.0, 216.0, 210.0, 167.0, 167.0, 203.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 68.0, 69.0, 67.0, 69.0, 68.0, 66.0, 69.0], "value": 68.1, "unit": "score"}]}]} 17:09:25 INFO - Return code: 0 17:09:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:09:25 ERROR - # TBPL SUCCESS # 17:09:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:09:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:09:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:09:25 INFO - 2017-01-20 17:09:25.536 screenresolution[1654:10603] starting screenresolution argv=screenresolution get 17:09:25 INFO - 2017-01-20 17:09:25.538 screenresolution[1654:10603] Display 0: 1600x1200x32@60 17:09:25 INFO - 2017-01-20 17:09:25.545 screenresolution[1655:10606] starting screenresolution argv=screenresolution list 17:09:25 INFO - Available Modes on Display 0 17:09:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:09:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:09:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:09:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:09:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:09:25 INFO - Intel Iris: 17:09:25 INFO - Chipset Model: Intel Iris 17:09:25 INFO - Type: GPU 17:09:25 INFO - Bus: Built-In 17:09:25 INFO - VRAM (Dynamic, Max): 1536 MB 17:09:25 INFO - Vendor: Intel (0x8086) 17:09:25 INFO - Device ID: 0x0a2e 17:09:25 INFO - Revision ID: 0x0009 17:09:25 INFO - Displays: 17:09:25 INFO - Display: 17:09:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:09:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:09:25 INFO - Display Serial Number: 1600x1200 60 17:09:25 INFO - Main Display: Yes 17:09:25 INFO - Mirror: Off 17:09:25 INFO - Online: Yes 17:09:25 INFO - Rotation: Supported 17:09:25 INFO - Return code: 0 17:09:25 INFO - Running post-action listener: _resource_record_post_action 17:09:25 INFO - [mozharness: 2017-01-21 01:09:25.786051Z] Finished run-tests step (success) 17:09:25 INFO - Running post-run listener: _resource_record_post_run 17:09:25 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 40225280; Write bytes: 662788096; Read time: 393; Write time: 1999 17:09:25 INFO - TinderboxPrint: CPU usage
54.4% 17:09:25 INFO - TinderboxPrint: I/O read bytes / time
40,225,280 / 393 17:09:25 INFO - TinderboxPrint: I/O write bytes / time
662,788,096 / 1,999 17:09:25 INFO - TinderboxPrint: CPU idle
2,207.2 (45.6%) 17:09:25 INFO - TinderboxPrint: CPU system
976.0 (20.2%) 17:09:25 INFO - TinderboxPrint: CPU user
1,658.5 (34.3%) 17:09:25 INFO - TinderboxPrint: Swap in / out
389,636,096 / 0 17:09:25 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348647936; Write bytes: 346396672; Read time: 28231; Write time: 645 17:09:25 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35641856; Write bytes: 309080064; Read time: 356; Write time: 1324 17:09:26 INFO - Running post-run listener: _upload_blobber_files 17:09:26 INFO - Blob upload gear active. 17:09:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:09:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:09:26 INFO - (blobuploader) - INFO - Open directory for files ... 17:09:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:09:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:09:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:09:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:09:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:09:27 INFO - (blobuploader) - INFO - Done attempting. 17:09:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:09:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:09:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:09:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:09:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:09:28 INFO - (blobuploader) - INFO - Done attempting. 17:09:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:09:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:09:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:09:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:09:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:09:30 INFO - (blobuploader) - INFO - Done attempting. 17:09:30 INFO - (blobuploader) - INFO - Iteration through files over. 17:09:30 INFO - Return code: 0 17:09:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:09:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:09:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/064179c8aad9f77c300849ee4e489340dec8ab881abf3f0212cdbf902bfbbcde2cc599f17dfd745546a4b6a7c882db8ad21efce58a9713e0d7526b95c6e6e4ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e11d5b590fcafa77bc7652cbad8a43de780b4376b5e8f78b15a61cdd0808b6baea4edecc00a417ff4606244d2b4df6e2ec461c4f13cc7fa5c2cc8b6b2d1692f1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a40da2d38dc921180f11a3975aa6a7a304d40ebaebd1c7694934536a490ef1ebea172d470928e44bcc05dfc907fc96e82d9024abad3813ac8dcd8f519e23706"} 17:09:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:09:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:09:30 INFO - Contents: 17:09:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/064179c8aad9f77c300849ee4e489340dec8ab881abf3f0212cdbf902bfbbcde2cc599f17dfd745546a4b6a7c882db8ad21efce58a9713e0d7526b95c6e6e4ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e11d5b590fcafa77bc7652cbad8a43de780b4376b5e8f78b15a61cdd0808b6baea4edecc00a417ff4606244d2b4df6e2ec461c4f13cc7fa5c2cc8b6b2d1692f1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a40da2d38dc921180f11a3975aa6a7a304d40ebaebd1c7694934536a490ef1ebea172d470928e44bcc05dfc907fc96e82d9024abad3813ac8dcd8f519e23706"} 17:09:30 INFO - Running post-run listener: copy_logs_to_upload_dir 17:09:30 INFO - Copying logs to upload dir... 17:09:30 INFO - mkdir: /builds/slave/test/build/upload/logs 17:09:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.213848 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-20 17:09:31.007403) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 17:09:31.010827) ========= 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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/064179c8aad9f77c300849ee4e489340dec8ab881abf3f0212cdbf902bfbbcde2cc599f17dfd745546a4b6a7c882db8ad21efce58a9713e0d7526b95c6e6e4ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e11d5b590fcafa77bc7652cbad8a43de780b4376b5e8f78b15a61cdd0808b6baea4edecc00a417ff4606244d2b4df6e2ec461c4f13cc7fa5c2cc8b6b2d1692f1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a40da2d38dc921180f11a3975aa6a7a304d40ebaebd1c7694934536a490ef1ebea172d470928e44bcc05dfc907fc96e82d9024abad3813ac8dcd8f519e23706"} build_url:https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011237 build_url: 'https://queue.taskcluster.net/v1/task/VXqHMeAWRCGQhcysnSpgWQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/064179c8aad9f77c300849ee4e489340dec8ab881abf3f0212cdbf902bfbbcde2cc599f17dfd745546a4b6a7c882db8ad21efce58a9713e0d7526b95c6e6e4ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e11d5b590fcafa77bc7652cbad8a43de780b4376b5e8f78b15a61cdd0808b6baea4edecc00a417ff4606244d2b4df6e2ec461c4f13cc7fa5c2cc8b6b2d1692f1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a40da2d38dc921180f11a3975aa6a7a304d40ebaebd1c7694934536a490ef1ebea172d470928e44bcc05dfc907fc96e82d9024abad3813ac8dcd8f519e23706"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 17:09:31.044987) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 17:09:31.045280) ========= 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.WyPd9Pggqp/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.SLjrQh5O5U/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.005140 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 17:09:31.092907) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 17:09:31.093192) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 17:09:31.093500) ========= ========= Total master_lag: 0.18 =========