builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0185 starttime: 1487003423.81 results: success (0) buildid: 20170213053639 builduid: 6a3a688ab40e4ccba504016970236cc4 revision: f984c74df66ca1fce52d414fe9cba21b16298fe4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:23.810896) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:23.811383) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:23.811661) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:23.838526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:23.838888) ========= 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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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-02-13 08:30:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 46.5M=0s 2017-02-13 08:30:24 (46.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114471 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:23.980551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:23.980862) ========= 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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.028141 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:24.034570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-13 08:30:24.034910) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f984c74df66ca1fce52d414fe9cba21b16298fe4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f984c74df66ca1fce52d414fe9cba21b16298fe4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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-02-13 08:30:24,261 truncating revision to first 12 chars 2017-02-13 08:30:24,261 Setting DEBUG logging. 2017-02-13 08:30:24,262 attempt 1/10 2017-02-13 08:30:24,262 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f984c74df66c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-13 08:30:25,456 unpacking tar archive at: mozilla-beta-f984c74df66c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.455885 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-13 08:30:25.517320) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:25.517797) ========= script_repo_revision: f984c74df66ca1fce52d414fe9cba21b16298fe4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:25.518281) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:25.518595) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-13 08:30:25.542441) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-13 08:30:25.543019) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:30:25 INFO - MultiFileLogger online at 20170213 08:30:25 in /builds/slave/test 08:30:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:30:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:25 INFO - {'append_to_log': False, 08:30:25 INFO - 'base_work_dir': '/builds/slave/test', 08:30:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:30:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:25 INFO - 'branch': 'Mozilla-Beta', 08:30:25 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:25 INFO - 'config_files': ('talos/mac_config.py',), 08:30:25 INFO - 'default_actions': ('clobber', 08:30:25 INFO - 'read-buildbot-config', 08:30:25 INFO - 'download-and-extract', 08:30:25 INFO - 'populate-webroot', 08:30:25 INFO - 'create-virtualenv', 08:30:25 INFO - 'install', 08:30:25 INFO - 'run-tests'), 08:30:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:25 INFO - 'download_minidump_stackwalk': True, 08:30:25 INFO - 'download_symbols': 'ondemand', 08:30:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:25 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:25 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:25 INFO - 'installer_path': 'installer.exe', 08:30:25 INFO - 'log_level': 'info', 08:30:25 INFO - 'log_name': 'talos', 08:30:25 INFO - 'log_to_console': True, 08:30:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:30:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:30:25 INFO - 'opt_config_files': (), 08:30:25 INFO - 'pip_index': False, 08:30:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:25 INFO - 'cmd': ('bash', 08:30:25 INFO - '-c', 08:30:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:25 INFO - 'enabled': True, 08:30:25 INFO - 'halt_on_failure': False, 08:30:25 INFO - 'name': 'check_screen_resolution'},), 08:30:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:25 INFO - 'cmd': ('bash', 08:30:25 INFO - '-c', 08:30:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:25 INFO - 'enabled': True, 08:30:25 INFO - 'halt_on_failure': False, 08:30:25 INFO - 'name': 'check_screen_resolution'},), 08:30:25 INFO - 'run_cmd_checks_enabled': True, 08:30:25 INFO - 'sps_profile': False, 08:30:25 INFO - 'sps_profile_interval': 0, 08:30:25 INFO - 'suite': 'dromaeojs-e10s', 08:30:25 INFO - 'system_bits': '32', 08:30:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:25 INFO - 'title': 't-yosemite-r7-0185', 08:30:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:25 INFO - 'use_talos_json': True, 08:30:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:25 INFO - 'work_dir': 'build'} 08:30:25 INFO - [mozharness: 2017-02-13 16:30:25.882829Z] Running clobber step. 08:30:25 INFO - Running pre-action listener: _resource_record_pre_action 08:30:25 INFO - Running main action method: clobber 08:30:25 INFO - rmtree: /builds/slave/test/build 08:30:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:30 INFO - Running post-action listener: _resource_record_post_action 08:30:30 INFO - [mozharness: 2017-02-13 16:30:30.306575Z] Finished clobber step (success) 08:30:30 INFO - [mozharness: 2017-02-13 16:30:30.306708Z] Running read-buildbot-config step. 08:30:30 INFO - Running pre-action listener: _resource_record_pre_action 08:30:30 INFO - Running main action method: read_buildbot_config 08:30:30 INFO - Using buildbot properties: 08:30:30 INFO - { 08:30:30 INFO - "project": "", 08:30:30 INFO - "product": "firefox", 08:30:30 INFO - "script_repo_revision": "production", 08:30:30 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:30:30 INFO - "repository": "", 08:30:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:30:30 INFO - "buildid": "20170213053639", 08:30:30 INFO - "pgo_build": "False", 08:30:30 INFO - "basedir": "/builds/slave/test", 08:30:30 INFO - "buildnumber": 6, 08:30:30 INFO - "slavename": "t-yosemite-r7-0185", 08:30:30 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:30:30 INFO - "platform": "yosemite_r7", 08:30:30 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:30:30 INFO - "branch": "mozilla-beta", 08:30:30 INFO - "repo_path": "releases/mozilla-beta", 08:30:30 INFO - "revision": "f984c74df66ca1fce52d414fe9cba21b16298fe4", 08:30:30 INFO - "stage_platform": "macosx64", 08:30:30 INFO - "builduid": "6a3a688ab40e4ccba504016970236cc4", 08:30:30 INFO - "slavebuilddir": "test" 08:30:30 INFO - } 08:30:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 08:30:30 INFO - Running post-action listener: _resource_record_post_action 08:30:30 INFO - [mozharness: 2017-02-13 16:30:30.309938Z] Finished read-buildbot-config step (success) 08:30:30 INFO - [mozharness: 2017-02-13 16:30:30.310045Z] Running download-and-extract step. 08:30:30 INFO - Running pre-action listener: _resource_record_pre_action 08:30:30 INFO - Running main action method: download_and_extract 08:30:30 INFO - mkdir: /builds/slave/test/build/tests 08:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:30 INFO - trying https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 08:30:30 INFO - Downloading https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 08:30:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 08:30:31 INFO - Downloaded 1165 bytes. 08:30:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 08:30:31 INFO - Using the following test package requirements: 08:30:31 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 08:30:31 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:30:31 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 08:30:31 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:30:31 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 08:30:31 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:30:31 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:30:31 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 08:30:31 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 08:30:31 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:30:31 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 08:30:31 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:30:31 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 08:30:31 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:30:31 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 08:30:31 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:30:31 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 08:30:31 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 08:30:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 08:30:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 08:30:31 INFO - Fetch https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 08:30:31 INFO - Http code: 200 08:30:31 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 08:30:31 INFO - x-amz-cf-id: P_aQX-C8tUtQlKOGpc5OJBfEJ1i8AiKu6G4UV3Mi2ePdDbRpUeHrUg== 08:30:31 INFO - x-amz-version-id: F95uz3p7vgj9v87QjFheh8p4k0SY3Qn3 08:30:32 INFO - Expected file size: 16094123 08:30:32 INFO - Obtained file size: 16094123 08:30:38 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:30:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 08:30:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 08:30:38 INFO - Fetch https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 08:30:39 INFO - Http code: 200 08:30:39 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 08:30:39 INFO - x-amz-cf-id: MPJG-VSnRJC7gmyHv_H3AbBETjZBWWQPzv0vyZRym9EuYD8t1Fljhg== 08:30:39 INFO - x-amz-version-id: F95uz3p7vgj9v87QjFheh8p4k0SY3Qn3 08:30:39 INFO - Expected file size: 16094123 08:30:39 INFO - Obtained file size: 16094123 08:30:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 08:30:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 08:30:45 INFO - Fetch https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 08:30:46 INFO - Http code: 200 08:30:46 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 08:30:46 INFO - x-amz-cf-id: 1u6M3g4M4n4K0o00TSj11ESpa7Iwv3kcPtgEPaYcsqN6S847lVDUWg== 08:30:46 INFO - x-amz-version-id: f3yz9XlZsCSmCBerOnKVl.ulu7IKEP6j 08:30:47 INFO - Expected file size: 14403005 08:30:47 INFO - Obtained file size: 14403005 08:30: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')]} 08:30:47 INFO - trying https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 08:30:47 INFO - Downloading https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 08:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 08:30:49 INFO - Downloaded 82137144 bytes. 08:30:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 08:30:49 INFO - mkdir: /builds/slave/test/properties 08:30:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:49 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:49 INFO - Contents: 08:30:49 INFO - build_url:https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 08:30:50 INFO - Running post-action listener: _resource_record_post_action 08:30:50 INFO - Running post-action listener: set_extra_try_arguments 08:30:50 INFO - [mozharness: 2017-02-13 16:30:50.391146Z] Finished download-and-extract step (success) 08:30:50 INFO - [mozharness: 2017-02-13 16:30:50.391275Z] Running populate-webroot step. 08:30:50 INFO - Running pre-action listener: _resource_record_pre_action 08:30:50 INFO - Running main action method: populate_webroot 08:30:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:50 INFO - '--tppagecycles', 08:30:50 INFO - '7'], 08:30:50 INFO - 'tests': ['tsvgm']}}, 08:30:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:30:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:50 INFO - 'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:50 INFO - 'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['damp', 'tps']}, 08:30:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:50 INFO - 'tests': ['damp', 'tps']}, 08:30:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['dromaeo_dom']}, 08:30:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['basic_compositor_video']}, 08:30:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:30:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['a11yr', 08:30:50 INFO - 'ts_paint', 08:30:50 INFO - 'tpaint', 08:30:50 INFO - 'sessionrestore', 08:30:50 INFO - 'sessionrestore_no_auto_restore', 08:30:50 INFO - 'tabpaint']}, 08:30:50 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:50 INFO - 'ts_paint', 08:30:50 INFO - 'tpaint', 08:30:50 INFO - 'sessionrestore', 08:30:50 INFO - 'sessionrestore_no_auto_restore', 08:30:50 INFO - 'tabpaint']}, 08:30:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:30:50 INFO - 'ts_paint', 08:30:50 INFO - 'tpaint', 08:30:50 INFO - 'sessionrestore', 08:30:50 INFO - 'sessionrestore_no_auto_restore', 08:30:50 INFO - 'tabpaint']}, 08:30:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:30:50 INFO - 'ts_paint', 08:30:50 INFO - 'tpaint', 08:30:50 INFO - 'sessionrestore', 08:30:50 INFO - 'sessionrestore_no_auto_restore', 08:30:50 INFO - 'tabpaint']}, 08:30:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['a11yr', 08:30:50 INFO - 'ts_paint', 08:30:50 INFO - 'tpaint', 08:30:50 INFO - 'sessionrestore', 08:30:50 INFO - 'sessionrestore_no_auto_restore', 08:30:50 INFO - 'tabpaint']}, 08:30:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['a11yr', 08:30:50 INFO - 'ts_paint', 08:30:50 INFO - 'tpaint', 08:30:50 INFO - 'sessionrestore', 08:30:50 INFO - 'sessionrestore_no_auto_restore', 08:30:50 INFO - 'tabpaint']}, 08:30:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['tsvgx', 08:30:50 INFO - 'tsvgr_opacity', 08:30:50 INFO - 'tart', 08:30:50 INFO - 'tscrollx', 08:30:50 INFO - 'cart']}, 08:30:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:50 INFO - 'tsvgr_opacity', 08:30:50 INFO - 'tart', 08:30:50 INFO - 'tscrollx', 08:30:50 INFO - 'cart']}, 08:30:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:50 INFO - 'talos_options': ['--disable-e10s'], 08:30:50 INFO - 'tests': ['tp5o']}, 08:30:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:50 INFO - 'tests': ['tp5o']}, 08:30:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:50 INFO - 'talos_options': ['--disable-e10s', 08:30:50 INFO - '--xperf_path', 08:30:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:50 INFO - 'tests': ['tp5n']}, 08:30:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:50 INFO - 'talos_options': ['--xperf_path', 08:30:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:50 INFO - 'tests': ['tp5n']}}, 08:30:50 INFO - 'talos.zip': {'path': '', 08:30:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:50 INFO - Running post-action listener: _resource_record_post_action 08:30:50 INFO - [mozharness: 2017-02-13 16:30:50.410357Z] Finished populate-webroot step (success) 08:30:50 INFO - [mozharness: 2017-02-13 16:30:50.410491Z] Running create-virtualenv step. 08:30:50 INFO - Running pre-action listener: _resource_record_pre_action 08:30:50 INFO - Running main action method: create_virtualenv 08:30:50 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:50 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 08:30:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:30:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:50 INFO - Using real prefix '/tools/python27' 08:30:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:51 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:54 INFO - Installing pip.................done. 08:30:54 INFO - Return code: 0 08:30:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:30:54 INFO - Reading from file tmpfile_stdout 08:30:54 INFO - Output received: 08:30:54 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) 08:30:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:30: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', 'pip>=1.5'] in /builds/slave/test/build 08:30: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 pip>=1.5 08:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:54 INFO - 'HOME': '/Users/cltbld', 08:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:54 INFO - 'LOGNAME': 'cltbld', 08:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:54 INFO - 'MOZ_NO_REMOTE': '1', 08:30:54 INFO - 'NO_EM_RESTART': '1', 08:30:54 INFO - 'PAGER': '/bin/cat', 08:30:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:54 INFO - 'PWD': '/builds/slave/test', 08:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:54 INFO - 'SHELL': '/bin/bash', 08:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:30:54 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:54 INFO - 'USER': 'cltbld', 08:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:54 INFO - 'XPC_FLAGS': '0x0', 08:30:54 INFO - 'XPC_SERVICE_NAME': '0', 08:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:54 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 08:30:54 INFO - Cleaning up... 08:30:54 INFO - Return code: 0 08:30:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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', '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 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:30: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 psutil>=3.1.1 08:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:54 INFO - 'HOME': '/Users/cltbld', 08:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:54 INFO - 'LOGNAME': 'cltbld', 08:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:54 INFO - 'MOZ_NO_REMOTE': '1', 08:30:54 INFO - 'NO_EM_RESTART': '1', 08:30:54 INFO - 'PAGER': '/bin/cat', 08:30:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:54 INFO - 'PWD': '/builds/slave/test', 08:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:54 INFO - 'SHELL': '/bin/bash', 08:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:30:54 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:54 INFO - 'USER': 'cltbld', 08:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:54 INFO - 'XPC_FLAGS': '0x0', 08:30:54 INFO - 'XPC_SERVICE_NAME': '0', 08:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:54 INFO - Downloading/unpacking psutil>=3.1.1 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:58 INFO - Installing collected packages: psutil 08:30:58 INFO - Running setup.py install for psutil 08:30:58 INFO - building 'psutil._psutil_osx' extension 08:30:58 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 08:30:59 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 08:30:59 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 08:30:59 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 08:30:59 INFO - building 'psutil._psutil_posix' extension 08:30:59 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 08:30:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:30:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:30:59 INFO - ^ 08:30:59 INFO - 1 warning generated. 08:30:59 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 08:30:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:59 INFO - Successfully installed psutil 08:30:59 INFO - Cleaning up... 08:30:59 INFO - Return code: 0 08:30:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:59 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 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:30:59 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 08:30:59 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 08:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:59 INFO - 'HOME': '/Users/cltbld', 08:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:59 INFO - 'LOGNAME': 'cltbld', 08:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:59 INFO - 'MOZ_NO_REMOTE': '1', 08:30:59 INFO - 'NO_EM_RESTART': '1', 08:30:59 INFO - 'PAGER': '/bin/cat', 08:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:59 INFO - 'PWD': '/builds/slave/test', 08:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:59 INFO - 'SHELL': '/bin/bash', 08:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:30:59 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:59 INFO - 'USER': 'cltbld', 08:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:59 INFO - 'XPC_FLAGS': '0x0', 08:30:59 INFO - 'XPC_SERVICE_NAME': '0', 08:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:31:02 INFO - Installing collected packages: mozsystemmonitor 08:31:02 INFO - Running setup.py install for mozsystemmonitor 08:31:02 INFO - Successfully installed mozsystemmonitor 08:31:02 INFO - Cleaning up... 08:31:02 INFO - Return code: 0 08:31:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:31:02 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')]} 08:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:02 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')]} 08:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:02 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 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:31:02 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 08:31:02 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 08:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:02 INFO - 'HOME': '/Users/cltbld', 08:31:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:02 INFO - 'LOGNAME': 'cltbld', 08:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:02 INFO - 'MOZ_NO_REMOTE': '1', 08:31:02 INFO - 'NO_EM_RESTART': '1', 08:31:02 INFO - 'PAGER': '/bin/cat', 08:31:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:02 INFO - 'PWD': '/builds/slave/test', 08:31:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:02 INFO - 'SHELL': '/bin/bash', 08:31:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:31:02 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:31:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:02 INFO - 'USER': 'cltbld', 08:31:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:02 INFO - 'XPC_FLAGS': '0x0', 08:31:02 INFO - 'XPC_SERVICE_NAME': '0', 08:31:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:03 INFO - Downloading/unpacking jsonschema==2.5.1 08:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:31:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:31:05 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 08:31:05 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 08:31:05 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 08:31:05 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 08:31:05 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 08:31:05 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 08:31:06 INFO - Downloading functools32-3.2.3-2.tar.gz 08:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:31:06 INFO - warning: no files found matching '*.txt' 08:31:06 INFO - no previously-included directories found matching 'build' 08:31:06 INFO - no previously-included directories found matching 'dist' 08:31:06 INFO - no previously-included directories found matching '.git*' 08:31:06 INFO - Installing collected packages: jsonschema, functools32 08:31:06 INFO - Running setup.py install for functools32 08:31:06 INFO - warning: no files found matching '*.txt' 08:31:06 INFO - no previously-included directories found matching 'build' 08:31:06 INFO - no previously-included directories found matching 'dist' 08:31:06 INFO - no previously-included directories found matching '.git*' 08:31:06 INFO - Successfully installed jsonschema functools32 08:31:06 INFO - Cleaning up... 08:31:06 INFO - Return code: 0 08:31:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 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 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:31:06 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 08:31:06 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 08:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:06 INFO - 'HOME': '/Users/cltbld', 08:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:06 INFO - 'LOGNAME': 'cltbld', 08:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:06 INFO - 'MOZ_NO_REMOTE': '1', 08:31:06 INFO - 'NO_EM_RESTART': '1', 08:31:06 INFO - 'PAGER': '/bin/cat', 08:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:06 INFO - 'PWD': '/builds/slave/test', 08:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:06 INFO - 'SHELL': '/bin/bash', 08:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:31:06 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:06 INFO - 'USER': 'cltbld', 08:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:06 INFO - 'XPC_FLAGS': '0x0', 08:31:06 INFO - 'XPC_SERVICE_NAME': '0', 08:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:31:06 INFO - Cleaning up... 08:31:06 INFO - Return code: 0 08:31:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 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 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:31:06 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 08:31:06 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 08:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:06 INFO - 'HOME': '/Users/cltbld', 08:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:06 INFO - 'LOGNAME': 'cltbld', 08:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:06 INFO - 'MOZ_NO_REMOTE': '1', 08:31:06 INFO - 'NO_EM_RESTART': '1', 08:31:06 INFO - 'PAGER': '/bin/cat', 08:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:06 INFO - 'PWD': '/builds/slave/test', 08:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:06 INFO - 'SHELL': '/bin/bash', 08:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:31:06 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:06 INFO - 'USER': 'cltbld', 08:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:06 INFO - 'XPC_FLAGS': '0x0', 08:31:06 INFO - 'XPC_SERVICE_NAME': '0', 08:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:06 INFO - Downloading/unpacking blobuploader==1.2.4 08:31:06 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 08:31:06 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 08:31:06 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 08:31:06 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 08:31:06 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 08:31:06 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 08:31:09 INFO - Downloading blobuploader-1.2.4.tar.gz 08:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:31:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:31: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 08:31: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 08:31: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 08:31: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 08:31: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 08:31: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 08:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:31:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:31:10 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 08:31:10 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 08:31:10 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 08:31:10 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 08:31:10 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 08:31:10 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 08:31:10 INFO - Downloading docopt-0.6.1.tar.gz 08:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:31:10 INFO - Installing collected packages: blobuploader, requests, docopt 08:31:10 INFO - Running setup.py install for blobuploader 08:31:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:31:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:31:10 INFO - Running setup.py install for requests 08:31:11 INFO - Running setup.py install for docopt 08:31:11 INFO - Successfully installed blobuploader requests docopt 08:31:11 INFO - Cleaning up... 08:31:11 INFO - Return code: 0 08:31:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:11 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 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:31:11 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 08:31:11 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 08:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:11 INFO - 'HOME': '/Users/cltbld', 08:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:11 INFO - 'LOGNAME': 'cltbld', 08:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:11 INFO - 'MOZ_NO_REMOTE': '1', 08:31:11 INFO - 'NO_EM_RESTART': '1', 08:31:11 INFO - 'PAGER': '/bin/cat', 08:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:11 INFO - 'PWD': '/builds/slave/test', 08:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:11 INFO - 'SHELL': '/bin/bash', 08:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:31:11 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:11 INFO - 'USER': 'cltbld', 08:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:11 INFO - 'XPC_FLAGS': '0x0', 08:31:11 INFO - 'XPC_SERVICE_NAME': '0', 08:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:11 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-XYXi_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-MR9l4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-OuytVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-CjVVnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-6VXE6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-b3foWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-jTwBBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-GwZLBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-JrfxIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-AF67yS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-YcM7dP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-CASUup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-FFjqQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-RUAy04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-N3lJQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-aFrgww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-HLuGSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:31:13 INFO - Running setup.py install for manifestparser 08:31:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:31:13 INFO - Running setup.py install for mozcrash 08:31:14 INFO - Running setup.py install for mozdebug 08:31:14 INFO - Running setup.py install for mozdevice 08:31:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:31:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:31:14 INFO - Running setup.py install for mozfile 08:31:14 INFO - Running setup.py install for mozhttpd 08:31:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:31:14 INFO - Running setup.py install for mozinfo 08:31:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:31:14 INFO - Running setup.py install for mozInstall 08:31:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:31:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:31:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:31:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:31:14 INFO - Running setup.py install for mozleak 08:31:15 INFO - Running setup.py install for mozlog 08:31:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:31:15 INFO - Running setup.py install for moznetwork 08:31:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:31:15 INFO - Running setup.py install for mozprocess 08:31:15 INFO - Running setup.py install for mozprofile 08:31:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:31:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:31:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:31:15 INFO - Running setup.py install for mozrunner 08:31:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:31:15 INFO - Running setup.py install for mozscreenshot 08:31:15 INFO - Running setup.py install for moztest 08:31:16 INFO - Running setup.py install for mozversion 08:31:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:31:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:31:16 INFO - Cleaning up... 08:31:16 INFO - Return code: 0 08:31:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:16 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 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:31:16 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 08:31:16 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 08:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:16 INFO - 'HOME': '/Users/cltbld', 08:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:16 INFO - 'LOGNAME': 'cltbld', 08:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:16 INFO - 'MOZ_NO_REMOTE': '1', 08:31:16 INFO - 'NO_EM_RESTART': '1', 08:31:16 INFO - 'PAGER': '/bin/cat', 08:31:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:16 INFO - 'PWD': '/builds/slave/test', 08:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:16 INFO - 'SHELL': '/bin/bash', 08:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:31:16 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:16 INFO - 'USER': 'cltbld', 08:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:16 INFO - 'XPC_FLAGS': '0x0', 08:31:16 INFO - 'XPC_SERVICE_NAME': '0', 08:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:16 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-sjCoZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:16 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)) 08:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:16 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-MPTef2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:16 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)) 08:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:16 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-c1vF4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:16 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)) 08:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:16 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-K6NGnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-khUYsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-YwL0Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-EPH69l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ee9gvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-KoGOsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-IYSdYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-nQtfR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-qmq6Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-x_RrH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:17 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)) 08:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:17 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-4r_hQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:18 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)) 08:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:18 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-cdIRR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:18 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)) 08:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:18 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-JkEfkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:18 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)) 08:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:18 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-GcJeOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:18 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)) 08:31:18 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)) 08:31:18 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)) 08:31:18 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)) 08:31:18 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)) 08:31:18 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)) 08:31:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31:18 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 08:31:18 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 08:31:18 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 08:31:18 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 08:31:18 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 08:31:18 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 08:31:20 INFO - Downloading blessings-1.6.tar.gz 08:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:31:21 INFO - Installing collected packages: blessings 08:31:21 INFO - Running setup.py install for blessings 08:31:21 INFO - Successfully installed blessings 08:31:21 INFO - Cleaning up... 08:31:21 INFO - Return code: 0 08:31:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:31:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:21 INFO - Reading from file tmpfile_stdout 08:31:21 INFO - Current package versions: 08:31:21 INFO - blessings == 1.6 08:31:21 INFO - blobuploader == 1.2.4 08:31:21 INFO - docopt == 0.6.1 08:31:21 INFO - functools32 == 3.2.3-2 08:31:21 INFO - jsonschema == 2.5.1 08:31:21 INFO - manifestparser == 1.1 08:31:21 INFO - mozInstall == 1.12 08:31:21 INFO - mozcrash == 1.0 08:31:21 INFO - mozdebug == 0.1 08:31:21 INFO - mozdevice == 0.48 08:31:21 INFO - mozfile == 1.2 08:31:21 INFO - mozhttpd == 0.7 08:31:21 INFO - mozinfo == 0.9 08:31:21 INFO - mozleak == 0.1 08:31:21 INFO - mozlog == 3.4 08:31:21 INFO - moznetwork == 0.27 08:31:21 INFO - mozprocess == 0.23 08:31:21 INFO - mozprofile == 0.28 08:31:21 INFO - mozrunner == 6.13 08:31:21 INFO - mozscreenshot == 0.1 08:31:21 INFO - mozsystemmonitor == 0.3 08:31:21 INFO - moztest == 0.8 08:31:21 INFO - mozversion == 1.4 08:31:21 INFO - psutil == 3.1.1 08:31:21 INFO - requests == 1.2.3 08:31:21 INFO - wsgiref == 0.1.2 08:31:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31: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')]} 08:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:22 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 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:31:22 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 08:31:22 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 08:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:22 INFO - 'HOME': '/Users/cltbld', 08:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:22 INFO - 'LOGNAME': 'cltbld', 08:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:22 INFO - 'MOZ_NO_REMOTE': '1', 08:31:22 INFO - 'NO_EM_RESTART': '1', 08:31:22 INFO - 'PAGER': '/bin/cat', 08:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:22 INFO - 'PWD': '/builds/slave/test', 08:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:22 INFO - 'SHELL': '/bin/bash', 08:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:31:22 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:22 INFO - 'USER': 'cltbld', 08:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:22 INFO - 'XPC_FLAGS': '0x0', 08:31:22 INFO - 'XPC_SERVICE_NAME': '0', 08:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 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)) 08:31:22 INFO - Cleaning up... 08:31:22 INFO - Return code: 0 08:31:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102c2e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ba4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0f9d6aff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, '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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 08:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:23 INFO - 'HOME': '/Users/cltbld', 08:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:23 INFO - 'LOGNAME': 'cltbld', 08:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:23 INFO - 'MOZ_NO_REMOTE': '1', 08:31:23 INFO - 'NO_EM_RESTART': '1', 08:31:23 INFO - 'PAGER': '/bin/cat', 08:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:23 INFO - 'PWD': '/builds/slave/test', 08:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:23 INFO - 'SHELL': '/bin/bash', 08:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:31:23 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:23 INFO - 'USER': 'cltbld', 08:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:23 INFO - 'XPC_FLAGS': '0x0', 08:31:23 INFO - 'XPC_SERVICE_NAME': '0', 08:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:31:23 INFO - Cleaning up... 08:31:23 INFO - Return code: 0 08:31:23 INFO - Running post-action listener: _resource_record_post_action 08:31:23 INFO - Running post-action listener: _start_resource_monitoring 08:31:23 INFO - Starting resource monitoring. 08:31:23 INFO - [mozharness: 2017-02-13 16:31:23.423002Z] Finished create-virtualenv step (success) 08:31:23 INFO - [mozharness: 2017-02-13 16:31:23.423961Z] Running install step. 08:31:23 INFO - Running pre-action listener: _resource_record_pre_action 08:31:23 INFO - Running main action method: install 08:31:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:23 INFO - Reading from file tmpfile_stdout 08:31:23 INFO - Detecting whether we're running mozinstall >=1.0... 08:31:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:31:23 INFO - Reading from file tmpfile_stdout 08:31:23 INFO - Output received: 08:31:23 INFO - Usage: mozinstall [options] installer 08:31:23 INFO - Options: 08:31:23 INFO - -h, --help show this help message and exit 08:31:23 INFO - -d DEST, --destination=DEST 08:31:23 INFO - Directory to install application into. [default: 08:31:23 INFO - "/builds/slave/test"] 08:31:23 INFO - --app=APP Application being installed. [default: firefox] 08:31:23 INFO - mkdir: /builds/slave/test/build/application 08:31:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:31:45 INFO - Reading from file tmpfile_stdout 08:31:45 INFO - Output received: 08:31:45 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:31:45 INFO - Running post-action listener: _resource_record_post_action 08:31:45 INFO - [mozharness: 2017-02-13 16:31:45.521336Z] Finished install step (success) 08:31:45 INFO - [mozharness: 2017-02-13 16:31:45.521475Z] Running run-tests step. 08:31:45 INFO - Running pre-action listener: _resource_record_pre_action 08:31:45 INFO - Running main action method: run_tests 08:31:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:31:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:31:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:31:45 INFO - 2017-02-13 08:31:45.535 screenresolution[1625:7755] starting screenresolution argv=screenresolution get 08:31:45 INFO - 2017-02-13 08:31:45.538 screenresolution[1625:7755] Display 0: 1600x1200x32@60 08:31:45 INFO - 2017-02-13 08:31:45.544 screenresolution[1626:7757] starting screenresolution argv=screenresolution list 08:31:45 INFO - Available Modes on Display 0 08:31:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:31:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:31:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:31:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:31:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:31:45 INFO - Intel Iris: 08:31:45 INFO - Chipset Model: Intel Iris 08:31:45 INFO - Type: GPU 08:31:45 INFO - Bus: Built-In 08:31:45 INFO - VRAM (Dynamic, Max): 1536 MB 08:31:45 INFO - Vendor: Intel (0x8086) 08:31:45 INFO - Device ID: 0x0a2e 08:31:45 INFO - Revision ID: 0x0009 08:31:45 INFO - Displays: 08:31:45 INFO - Display: 08:31:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:31:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:31:45 INFO - Display Serial Number: 1600x1200 60 08:31:45 INFO - Main Display: Yes 08:31:45 INFO - Mirror: Off 08:31:45 INFO - Online: Yes 08:31:45 INFO - Rotation: Supported 08:31:45 INFO - Return code: 0 08:31:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:31:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:31:45 INFO - Python 2.7.3 08:31:45 INFO - Return code: 0 08:31:45 INFO - grabbing minidump binary from tooltool 08:31: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')]} 08:31:45 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 0x102a42d30>, '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 0x1028a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:31:45 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 08:31:45 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 08:31:45 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 08:31:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:45 INFO - Return code: 0 08:31:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:31:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:31:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:31:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:31:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:31:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 08:31:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0185 --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 08:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PtmWQ9Uvcd/Render', 08:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:45 INFO - 'HOME': '/Users/cltbld', 08:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:45 INFO - 'LOGNAME': 'cltbld', 08:31:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:45 INFO - 'MOZ_NO_REMOTE': '1', 08:31:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:45 INFO - 'NO_EM_RESTART': '1', 08:31:45 INFO - 'PAGER': '/bin/cat', 08:31:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:45 INFO - 'PWD': '/builds/slave/test', 08:31:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:45 INFO - 'SHELL': '/bin/bash', 08:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GzyE8yAcD0/Listeners', 08:31:45 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 08:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:45 INFO - 'USER': 'cltbld', 08:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:45 INFO - 'XPC_FLAGS': '0x0', 08:31:45 INFO - 'XPC_SERVICE_NAME': '0', 08:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 08:31:46 INFO - mozversion application_buildid: 20170213053639 08:31:46 INFO - mozversion application_changeset: f984c74df66ca1fce52d414fe9cba21b16298fe4 08:31:46 INFO - mozversion application_display_name: Firefox 08:31:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:46 INFO - mozversion application_name: Firefox 08:31:46 INFO - mozversion application_remotingname: firefox 08:31:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:31:46 INFO - mozversion application_vendor: Mozilla 08:31:46 INFO - mozversion application_version: 52.0 08:31:46 INFO - mozversion platform_buildid: 20170213053639 08:31:46 INFO - mozversion platform_changeset: f984c74df66ca1fce52d414fe9cba21b16298fe4 08:31:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:31:46 INFO - mozversion platform_version: 52.0 08:31:46 INFO - using testdate: 1487003506 08:31:46 INFO - actual date: 1487003506 08:31:46 INFO - starting webserver on 'localhost:49238' 08:31:46 INFO - SUITE-START | Running 2 tests 08:31:46 INFO - TEST-START | dromaeo_css 08:31:46 INFO - operating with platform_type : mac_ 08:31:46 INFO - Initialising browser for dromaeo_css test... 08:31:46 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpzjsVj4/profile http://localhost:49238/getInfo.html) 08:31:49 INFO - PROCESS | 1636 | 2017-02-13 08:31:49.151 plugin-container[1639:7936] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 08:31:49 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:31:49 INFO - PROCESS | 1636 | 2017-02-13 08:31:49.153 plugin-container[1639:7936] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a2f, name = 'com.apple.CFPasteboardClient' 08:31:49 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:31:49 INFO - PROCESS | 1636 | 2017-02-13 08:31:49.153 plugin-container[1639:7936] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:31:49 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 08:31:49 INFO - PROCESS | 1636 | colorDepth:24 08:31:49 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 08:31:49 INFO - PROCESS | 1636 | __metrics 08:31:49 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 08:31:50 INFO - TEST-INFO | 1636: exit 0 08:31:50 INFO - Browser initialized. 08:31:50 INFO - Running cycle 1/1 for dromaeo_css test... 08:31:50 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpzjsVj4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:52 INFO - PROCESS | 1640 | 2017-02-13 08:31:52.446 plugin-container[1641:8060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 08:31:52 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:31:52 INFO - PROCESS | 1640 | 2017-02-13 08:31:52.446 plugin-container[1641:8060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 08:31:52 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:31:52 INFO - PROCESS | 1640 | 2017-02-13 08:31:52.446 plugin-container[1641:8060] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:32:02 INFO - PROCESS | 1640 | RSS: Main: 177004544 08:32:02 INFO - PROCESS | 1640 | 08:35:03 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 08:35:05 INFO - PROCESS | 1640 | RSS: Main: 182624256 08:35:05 INFO - PROCESS | 1640 | 08:38:07 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 08:38:08 INFO - PROCESS | 1640 | RSS: Main: 192868352 08:38:08 INFO - PROCESS | 1640 | 08:41:09 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 08:41:10 INFO - PROCESS | 1640 | RSS: Main: 195272704 08:41:10 INFO - PROCESS | 1640 | 08:44:12 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 08:44:13 INFO - PROCESS | 1640 | RSS: Main: 198111232 08:44:13 INFO - PROCESS | 1640 | 08:47:16 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 08:47:16 INFO - PROCESS | 1640 | RSS: Main: 199471104 08:47:16 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 08:50:19 INFO - PROCESS | 1640 | RSS: Main: 200142848 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | __start_tp_report 08:50:19 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 08:50:19 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 08:50:19 INFO - PROCESS | 1640 | |i|pagename|runs| 08:50:19 INFO - PROCESS | 1640 | |0;dojo.html;3471.5834099908006;3540.752147349686;3543.645635436458;3548.804815735137;3588.3182195382874;5772.595918285721;5831.591788574796;5845.7953971611005;5859.531237501001;5894.58737888348;5833.737481813318;5927.73325200366;5927.940720592793;5959.225300710912;5970.492508136803;5225.372955245366;5337.893242135155;5360.383555891064;5365.3293338332705;5374.91937620934;5348.732563371836;5374.220737993008;5391.487808658191;5392.541633961118;5404.91892621611;5210;5264.2893209416625;5355.973220133894;5364.973175134119;5415.241866138744;5288.735563221823;5298.523132918037;5346.224797404355;5354;5399.325084364455;5230.529252367305;5317.734113294358;5328.573714102862;5330.307060082165;5366.329208848894;5695.829125126212;5868.383819698955;5911.763529458816;5972.581919265654;5977;6216.751329946796;6235.657038862906;6239.126522286883;6314.052892066181;6369.554131210817;6290.622562646256;6297.748090076391;6306.716197771111;6315.684215789238;6325.019621958604;5957.791477298317;5968.850778730566;5970.164177015221;5973.104034394875;5977.22296101504;5065.442801291855;6202.875942481215;6238.500919926395;6273.7176827042895;6313.526485513605;5324.600654950894;6206.882761102955;6243.37566243372;6282.403171698681;6313.08460273267;6300;6305.873882522324;6319.178506794179;6329.828981638411;6339.2709838368255;6128.571000030044;6138.204480309982;6156.5998210116195;6225.657588832658;6331.113644089831;6133.877322453526;6141.846453838601;6221.253449585988;6232.8130156095385;6251.906221406683;5076.74616269185;5079.288899554065;5083.644144909895;5085.7202853842655;5110.642255042111;4352.847650332224;4445.866624001285;4532.841350552781;4534.727916325031;4538.341940418621;6004.789832355934;6068.939310606837;6101.359357267512;6119.563485741643;6128.080787881764;6277.3526380514095;6306.905396419057;6313.463355614732;6313.684315784192;6332.620042797447;5854.765809367577;5988.700564971734;6082.057281121433;6098.908516372258;6121.928662484048;5014.598832093424;5553.6390134641115;5562.471565201299;5571.303587051619;5575.107982722745;6465.385788350193;6497.935020649733;6512.511561632802;6515.511336649675;6524.249711283263;6228.5951413158;6229.0967809668255;6242.1261023455845;6305.369463053841;6308.810735677937;6481.287058423475;6483.740650373932;6526.510511711545;6538.967305163444;6567.408933196035;6305.747770089145;6310.211223597051;6341.4292713656;6347.555671103163;6356.173697419306;5301.920471193012;6105.358937311518;6222;6275.654838983717;6303.621782693053;6155.076738489083;6256.937430625818;6280.560360774884;6293.37066293367;6323.177986861679;6220.409061139185;6276.435120839146;6277.18396608438;6285.874282514415;6295.874082518416;5978.252718410199;6009.45914867664;6044.546659000681;6154.507639388748;6185.567010309235;6196.597221180609;6220.284667263141;6248.343923887926;6293.811185664437;6304.243490781136;6169.444749972524;6179.783707570214;6195.349488303844;6203.565750397429;6208.906866397098 08:50:19 INFO - PROCESS | 1640 | |1;ext.html;479.7193641719594;502.0887089931776;545.8362491252625;558.8044184535413;567.2229046206697;31904;39526.60473395265;39546.01134971624;39614.60385396145;39642.81071567858;40077.198456030936;40584.39123413151;40661;40668.18663626733;40738.79630601843;9453.338266321343;9500.952495237549;9641.566129524153;9643.987381324965;9654.855177172349;9479.47862867542;9597.040295970382;9663.806723865519;9672.177864881494;9702.369345992523;9554.092361225707;9669.854952175723;9698.078682525149;9720.270979676518;9720.319577629569;9535.094166054214;9569.760755981086;9619.951900240523;9713.320067595307;9716.076972687584;9455.763605909839;9466.337356385056;9530.999245079305;9600.039996000414;9682.75793105171;37923.43114853279;38240.42639360412;38479.845604631904;38547.61452385468;38663.8066809667;3193.8562764675644;3362.142653623335;3788.3370410178386;3985.481887354654;4069.04377471294;29865.55201671977;30684.84657576698;30840.228994275098;31167.22081947947;31228.53157202621;3232.2404235004756;3300.5544251525976;3311.8344082796007;3312.7681062325646;3327.2014716467875;479203;481277;481773;482418;483560;58579;58883;59040.22879313627;59093;59147.11279330814;58548.70725646344;58662;59418.405815942155;61001;61091.6945415279;15842.366305347658;15888.523344299689;15963.281652325673;16077.919610401874;16103.27535260916;12644.747105057848;12690.936545317214;12981.675458113434;12982.740345193231;13000.154989925813;14809.777853332209;15068.623284417758;15333.769993450107;15535.922320388325;15597.844021559866;6065.969670151621;6551.279359270477;6818.761343352959;6869.7595584154315;6899.275576064541;869.9652013919499;975.3903810118676;996.1632228927591;996.3274789517047;1071.7427817323787;30177;30267;30705;30775.076747697603;30900.53649195264;974.5858010345574;1041.5209003858158;1090.7055095124272;1137.7155711072232;1198.279342641087;11352.94323528377;15742.763858542132;15885.364585416684;15913.363465461252;16034.599135021717;677547;682007;693864;694219;695049;59123.704381477815;59142.40857591369;59190.70404647949;59236.40763592309;59464;1400.4958215042577;1410.8941829362634;1439.0789894467548;1441.08491108145;1442.1419255542935;25503.6174457387;29468.852655736584;30685.846570767004;31155.688443116185;31374.843125784224;46591.30113048239;48724;49180.75409623072;49339.753301233264;49620.007599848526;564725;565529;566936;568161;568600;26762.063327784213;27047;27076.864615676797;27212.183634490997;27315.317117071438;46683.53316466928;46708.53291467042;47324.2901356473;47445.762771185924;47744.522554774005;32114.19714507156;34614.82692586521;38093.047673808374;38336.42495362516;39622.60377396189;33187.66812331846;37488;37853.242935141694;38229.42655860109;38557.036074098374;29931.85034074816;36892.07769805669;37349.066273343386;38399.616003840725;38525.036874077254;33454.99635010953;33889.15277118092;33999.66000339965;34756.9572912813;34798.82600586981;25203.99184032618;25321.62017569701;25397.238082857544;25788.613170802077;25814.612780807925 08:50:19 INFO - PROCESS | 1640 | |2;jquery.html;316.968303169683;363.2589517384534;370.6849178198532;375.40310905659993;388.4910766895367;19151.233950641974;19999.700004499944;20006.69989950152;20756.481087972807;20932.581348373024;16464;19612.01939903006;19685.803141968576;20128.899355503203;20143.29498467554;5118.539331460168;5822.272215973004;6069.15031895535;6083.391660833903;6100.993336099538;6529.5429319947625;6652.201735791712;6779.62712050837;6785.76249831256;6797.626130562817;6525.608463492182;6630.73477060917;6643;6651.7006734696815;6693.464522838161;6475.4819614430735;6623.370779775938;6642.103316052319;6686.364795344434;6724.731010759564;5066.164082926312;5606.971965140189;5707.828765137053;5756.079027355645;5787.218725472049;25289;25324.240272791845;25339.873300633564;25571.872140639178;25844.612330814864;2215.3796936857707;2275.84069115162;2342.672025916373;2808.199663096008;2928.546075358323;15248.771268430983;15362.00146990442;15429.30568124437;15526.767098493532;15538.300776465087;1860.6278744251204;1862.431958252746;1921.6829223178158;1927.3736035788424;1934.516370907273;117621.41189293998;118778;119755;119783.4010829949;123960;9676.467794271382;10062.295639305325;10193.133583645473;10654.893451065545;10754;12918.22490650564;13013.544525941546;13039.674008149683;13067.477300908047;13259.204447732975;7536.623168841536;8505.829883402297;8602.35482338827;8698.13018698125;8746.69386571467;6587.110740050128;8717.825643487222;8899.15458031486;8981.101889810967;8990.505522196341;5249.816256431007;5613.438656134354;6533.562616224423;6589.17635295588;6611.305812889577;1524.542637208833;1795.937142200043;1813.6916724156924;1826.7716535433071;1848.7613299089373;889.515214208258;906.9909300906907;962.6341990043738;1008.7074748322905;1047.9214058945456;16616.91990020669;17695.380661677038;20991.370258892257;21141.68287475689;21456.81987490703;977.8544722461876;1004.7237009822358;1012.4431562640518;1025.0979138358198;1078.1105587890022;10694.786104277871;11097.33415995043;11570.363630000262;11633.243839150597;11694.2983420995;564490;597600;603123.9843800841;611215;614467;108952;119733;122717;130787.34606326907;138460;973.3284034016506;1023.902729240721;1034.4982683398546;1034.5189486888783;1036.6889933020216;18798.718019229447;19061.428157155307;19296.131674074983;19318.8068119317;19371.903140484206;57210.71394643;57867;58446.4155358441;58966.70516647561;59272;87493;87565.56217219128;88496.67254991051;88660;91020.54489727509;5394.460553944574;8015.318697910743;8079.151689072798;8122.1065682776025;8175.182481751777;12569.748605028031;12899;13068.477260909838;13806.447742090204;13833;10301.793964120825;10454.163666906781;11297.604583839855;11601.361925094201;11767.117466189899;11373.033292170257;11414.429278535874;11432.82850757256;11503.309801411608;11637.418129093681;11581.363025033705;11689.707757306136;11776.587819426279;11893.82159267629;12070;7926.445148839526;8053.8791918120105;8406.201410865959;8459.069502354612;8472.703455379033;7176.677049532679;9898.851517227104;11175.664730058112;11232.943835280772;11316.886831131913 08:50:19 INFO - PROCESS | 1640 | |3;mootools.html;1489.702059588082;1525.9542213733585;1709.3419033672033;1805.711086226204;1863.8136186381373;6973.198082220546;7528.887066694004;7765.301122898938;8296.004479462465;8310.626021829017;7906;8194.426390152692;8203.548804815733;8302.169783021709;8303.875441868377;2301.528186721718;2343.8476499027593;2346.5189636124555;2348.4833336665993;2353.2822489140804;2277.533105713333;2361.126383238204;2375.8336916415856;2376.192094687806;2393.617021276596;2273.874936878471;2321.442853715105;2352.9882350588223;2356.1988923765916;2362.9881850590723;2302.1712183613968;2320.8259380546356;2321.802646775018;2340.508493216427;2351.870647114408;2340.157543284416;2343.308723926446;2358.6697862299293;2362.11420717231;2373.9762602373926;8201.917980820175;8225.341972642174;8230.794230144234;8241.093479717227;8248.175182481704;4853.975730121327;4918.2376731606655;5075.264086707407;5088.084144853925;5094.77073531692;3287.797175892158;3321.933561328784;3372.6458721834347;3516.595591506984;3530.929381412383;941.7598512379368;1016.6899095775785;1049.564430761233;1074.258761454594;1087.7498175419698;10541.84187237192;10589.04698577124;10608.469576521144;10618.415987120705;10634.042936135707;4182.958170418317;4355.7822108894425;4429.645628349725;4488.259437192892;4602.309653551994;7873.842523149505;9153.908460915438;9257.768555786159;9388.71833844972;9407.43555386679;5079.3650793650795;5092.592592592584;5129.230615407719;5129.256257842592;5140.537351638371;4253.914921701548;4378.671599629977;4556.270996640522;4660.021395506914;4663.790129444184;3334.699877011081;3740.3828368318923;3754.5494540655295;3832.9425058624142;3851.1334949636107;1213.798339643764;1245.2466257914014;1255.4978008796372;1260.1431026901612;1265.8860702536815;776.4836383804819;880.1418616848522;905.2441211588266;916.1845957098188;925.648253663617;4666.763307723456;5078.289039534468;5489.58828087895;5547.667139971614;5577.944220557823;942.1050002497655;967.5259123029806;974.206022092007;984.7390441532999;992.7220378294089;3508.4035713928693;3652.251288485854;3711.814409279525;3760.8495660173285;3761.4169803680475;9768.609255629695;9987.500624968869;10064.043915827984;10068.194544436428;10102.444365559817;9731.854022189673;9743.220542356736;9920.751981200383;9971.451570163566;10053.246006549538;6471.320511346335;7661.157272699998;7783.9610801946765;7803.375729941591;7879.527228366316;5554.361248456479;5571.415001424792;5584.218209450762;5621.747021383966;5688.345840228427;9203.907960920305;9213.815723685622;9257.25941924631;9296.814063718823;9354.391964522285;9372.203362713974;9392.107749763762;9421.528923553931;9466.621335146518;9569.521523923637;6522.5760325578685;6706.362895524917;6900.2409734928115;7001.614911179934;7157.033800437082;7263.5109802491415;7471;7696.538207707556;7809.882851757115;7884.960575197088;6970.268121847133;7119.288071192839;7130.073090498202;7147.64261786919;7189;6751.527393082437;6942.826429339105;6959.478039146983;7031.015657807809;7047.964760176166;6443.806685799434;6561.114249576246;6625.768098116543;6651.50113741481;6663.633500157538;4715.24556071027;4738.147133515862;4768.427788665383;4779.737114458738;4783.6412269079265;3911.4915061041884;4640.86077417678;4816;4853.320535125156;4895.996320754308 08:50:19 INFO - PROCESS | 1640 | |4;prototype.html;272.453730270807;303.5507448975516;307.0481507327285;308.28785505482324;309.33338655198054;5551.555875529958;5563.36021357544;5596.636218645785;5610.803621873235;5629.943700562993;4274.461193970168;5492.615516913817;5495.807646732365;5542.870561431729;5570.080936645459;3364.4187232000922;3962.9405558916637;4025.537063237721;4089.9550224887557;4116.608922152405;3564.893053208395;4088.713790034699;4123.855665051724;4134.214499245134;4141.482314710661;3560.789331627246;3960.8019599019935;4096;4107.178564287155;4110.403991421257;3101.6433110192393;3529.7352698547456;4065.9593404066172;4138.193052354777;4140.751554906716;3224.1456014156265;3402.28552004077;3493.3537295600395;3515.33208690348;3525.3478106550115;5160.741962901878;5375.897940922102;5395.271638328853;5396.568274538027;5417.756200970965;3570.767900086512;4000.6399424051683;4709.293605959099;4740.961386603759;4759.0024379521365;5528.281323427969;5543.556515478793;5671.234383358276;5764.452377024176;5770.566512924845;2946.9852650736825;3585.8924232273075;3905.3946638271113;3916.530016398022;3928.0572662561067;5380.973095134499;5669.376368599451;5742.913856292159;5765.250517432749;6086.726097325631;3728.7203459740626;3894.922101557953;3900.473436086092;3934.370500719871;3965.345717956505;3808.7718684569836;3999.3601023836045;4024.8730355500506;4026.275270451347;4033;3947.50656167979;4695.201815691273;4711.56297329311;4713.481517033192;4727.479977202505;5483.396826349099;5775.5668324875805;5820.679862607512;5884.852878678067;5918.201042859245;1120.6694025262568;1274.706817431996;1287.4013583683632;1309.4107651556837;1322.418136020148;781.0744268042388;836.3476488339105;849.9192576705203;871.6164887449502;883.7746374674417;4883;5053.873653158627;5099.056674515226;5201.555269023553;5285.312909321764;4690.624750019991;5089.724631309718;5457.608309880955;5644.193857965412;5747.827565173052;4529.275315949432;4532.968405070369;4832.897689505076;4843.016204939856;5224.582033437316;2592.300078978703;2960.763138948879;2985.4327677741157;2999.6100506934395;3015.8492075396143;5151.304573882478;5219.712915789592;5308.283381743492;5715.142728590745;5762.328924611899;5307.681539107666;5545.502714267125;5752.798652047242;5767.596268261182;6193.752249909953;6264.780732674426;6286.748530058747;6300.21247344082;6325.098049012053;6334.13617693216;4434.423524941866;4794.44180641286;6087.908681369871;6117.113018612187;6155.753769849156;5189.766460509361;5485.177223416519;5857.36418611635;5930.436608522184;5979.5814292999075;5717.799262154827;6258.749650013949;6260.217472815898;6273.843153921006;6298.370162983665;3376.57792775903;3677.8344974475676;3727.030971947259;3938.980305098456;4051.5340735815757;5311.203319502106;5990.281166259902;6014.578979471395;6016.458518733335;6061.939160646781;4430.889227769331;5362.28119255053;5420.988468533335;5631.521320687787;5878.970605146947;4809.519048095302;5077.39071311445;5617.292215230346;5764.57887687164;5862.413758624274;5684.516816070515;5751.827445176484;5767.163761254511;5802.274715660543;5825.563082768725;5593.944060559343;5739.9139012915675;5806.083992282621;5841.8539536510225;5902.498287645427;4796.232602783538;4855.732934688626;5373.838784836461;5706.28671416073;5951.345352011189 08:50:19 INFO - PROCESS | 1640 | |5;yui.html;665.4443539644394;712.5617745086771;714.5426926766867;718.0270733156574;775.8331958628894;11735.354555499443;14228.43086276549;14915.105093694387;15033.699326013473;15047.398104075824;13522.93238533806;13537.526186583471;13543.45826166952;13645.044846860723;13677.999999999975;681.3050688297945;703.2194264366549;819.2217393476192;854.1714536899198;867.319154463746;839.2991851803752;862.3015357560365;862.6463150108457;864.4769914201912;865.1391865094208;866.1901122450529;884.5444596033049;884.752269364579;909.4361495872569;911.803962148138;883.7967267528472;885.4642941020686;902.4089221559889;904.6517090919978;905.5653286422488;645.6223109480959;665.5574043261225;670.7518218259227;678.1726293921457;679.1307126877605;13674.863251367558;13745;13796.655083622889;13970.161790292615;14625.634359141;13913.443462261497;14192.92903535486;14283;14291.285435728276;14503.70992580153;11613.245139065933;11715.589954351643;11726;11734.11994100446;12009.819852702216;3654.817259137059;3660.4875317455576;3704.4258139988347;3832.482614846988;3907.8046097695;92287.61568576477;94509.5274523623;95450.52274738651;95465;96018;10493;10644.840327395095;10650.73373165677;10720.624778132727;10759.300645458177;15723.371065157266;16612.916935415247;18833.964131972873;19804.70292945607;19816.70274945877;7600.657970391235;7612;7709.575973321521;7735.7679269622;7858.803529911684;4364.541723119027;4372.672049596293;4507.098580283956;4532.592066713945;4568.360429539867;3346.8159251240927;3373.1735724747596;3444.2078321986082;3462.4113900636953;3465.012471445626;518.4530320511897;546.1970902772917;554.9732993961139;618.4928358745785;624.65643895858;624.2832211418599;638.3010603389281;640.7953048269223;644.0468107201368;672.2067959807376;7983.12185659577;8906.332025098143;8941.46351218929;9034.23209027227;9040.593173307218;567.8353277549547;633.8985762278105;636.920384951881;648.176815444383;662.2715013485127;3359.6808303211155;3391.6777906098882;3993.1813978134414;4432.468103827561;4447.444069491314;79469;95693;98722;123548.38225808993;127158;63421;64343.03485447722;67038;91169.54415227882;99287;77945.61027194827;87147.20690361965;88087;93542.53228733812;96848.0315196853;7611.923880761122;8363.456375335583;8740.868886966573;8748.168923952215;8889.777755555904;39495.01262468461;43182.704518864484;44313.556864432234;51008.48991510038;52519.737401314276;55793.72103139458;57115.42884571101;64726;66489;67705.66147169232;12562.685932851751;12701.555445559361;12775.936120319338;12777.16948398351;12867;14267.500637477639;14417.423303068179;14579.416823327372;14594.635134121732;14705.485308014167;13170.736585268049;13463.663408414868;13475.663108422368;13490.258035808125;13597.252151131386;12546.811797822857;13167.2757998311;13322.33388330599;13672.210393619407;13842.377093031038;13116.278604676452;13260.403281852146;13500.594982150551;13760.867065604847;13775.931120344334;11020.944895275472;11112.777744444904;11166.609168679057;11248.831267531157;11433.656990290305;11235.55057797679;11283.605073822377;11517;11574.189806713786;11590.536378544764 08:50:19 INFO - PROCESS | 1640 | __end_tp_report 08:50:19 INFO - PROCESS | 1640 | __start_cc_report 08:50:19 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,356 08:50:19 INFO - PROCESS | 1640 | __end_cc_report 08:50:19 INFO - PROCESS | 1640 | __startTimestamp1487004619469__endTimestamp 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | ------- Summary: start ------- 08:50:19 INFO - PROCESS | 1640 | Number of tests: 6 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5860.32 Median:6128.57 stddev:632.85 (10.3%) stddev-sans-first:606.41 08:50:19 INFO - PROCESS | 1640 | Values: 3471.6 3540.8 3543.6 3548.8 3588.3 5772.6 5831.6 5845.8 5859.5 5894.6 5833.7 5927.7 5927.9 5959.2 5970.5 5225.4 5337.9 5360.4 5365.3 5374.9 5348.7 5374.2 5391.5 5392.5 5404.9 5210.0 5264.3 5356.0 5365.0 5415.2 5288.7 5298.5 5346.2 5354.0 5399.3 5230.5 5317.7 5328.6 5330.3 5366.3 5695.8 5868.4 5911.8 5972.6 5977.0 6216.8 6235.7 6239.1 6314.1 6369.6 6290.6 6297.7 6306.7 6315.7 6325.0 5957.8 5968.9 5970.2 5973.1 5977.2 5065.4 6202.9 6238.5 6273.7 6313.5 5324.6 6206.9 6243.4 6282.4 6313.1 6300.0 6305.9 6319.2 6329.8 6339.3 6128.6 6138.2 6156.6 6225.7 6331.1 6133.9 6141.8 6221.3 6232.8 6251.9 5076.7 5079.3 5083.6 5085.7 5110.6 4352.8 4445.9 4532.8 4534.7 4538.3 6004.8 6068.9 6101.4 6119.6 6128.1 6277.4 6306.9 6313.5 6313.7 6332.6 5854.8 5988.7 6082.1 6098.9 6121.9 5014.6 5553.6 5562.5 5571.3 5575.1 6465.4 6497.9 6512.5 6515.5 6524.2 6228.6 6229.1 6242.1 6305.4 6308.8 6481.3 6483.7 6526.5 6539.0 6567.4 6305.7 6310.2 6341.4 6347.6 6356.2 5301.9 6105.4 6222.0 6275.7 6303.6 6155.1 6256.9 6280.6 6293.4 6323.2 6220.4 6276.4 6277.2 6285.9 6295.9 5978.3 6009.5 6044.5 6154.5 6185.6 6196.6 6220.3 6248.3 6293.8 6304.2 6169.4 6179.8 6195.3 6203.6 6208.9 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:70558.03 Median:39618.60 stddev:156663.36 (395.4%) stddev-sans-first:157014.49 08:50:19 INFO - PROCESS | 1640 | Values: 479.7 502.1 545.8 558.8 567.2 31904.0 39526.6 39546.0 39614.6 39642.8 40077.2 40584.4 40661.0 40668.2 40738.8 9453.3 9501.0 9641.6 9644.0 9654.9 9479.5 9597.0 9663.8 9672.2 9702.4 9554.1 9669.9 9698.1 9720.3 9720.3 9535.1 9569.8 9620.0 9713.3 9716.1 9455.8 9466.3 9531.0 9600.0 9682.8 37923.4 38240.4 38479.8 38547.6 38663.8 3193.9 3362.1 3788.3 3985.5 4069.0 29865.6 30684.8 30840.2 31167.2 31228.5 3232.2 3300.6 3311.8 3312.8 3327.2 479203.0 481277.0 481773.0 482418.0 483560.0 58579.0 58883.0 59040.2 59093.0 59147.1 58548.7 58662.0 59418.4 61001.0 61091.7 15842.4 15888.5 15963.3 16077.9 16103.3 12644.7 12690.9 12981.7 12982.7 13000.2 14809.8 15068.6 15333.8 15535.9 15597.8 6066.0 6551.3 6818.8 6869.8 6899.3 870.0 975.4 996.2 996.3 1071.7 30177.0 30267.0 30705.0 30775.1 30900.5 974.6 1041.5 1090.7 1137.7 1198.3 11352.9 15742.8 15885.4 15913.4 16034.6 677547.0 682007.0 693864.0 694219.0 695049.0 59123.7 59142.4 59190.7 59236.4 59464.0 1400.5 1410.9 1439.1 1441.1 1442.1 25503.6 29468.9 30685.8 31155.7 31374.8 46591.3 48724.0 49180.8 49339.8 49620.0 564725.0 565529.0 566936.0 568161.0 568600.0 26762.1 27047.0 27076.9 27212.2 27315.3 46683.5 46708.5 47324.3 47445.8 47744.5 32114.2 34614.8 38093.0 38336.4 39622.6 33187.7 37488.0 37853.2 38229.4 38557.0 29931.9 36892.1 37349.1 38399.6 38525.0 33455.0 33889.2 33999.7 34757.0 34798.8 25204.0 25321.6 25397.2 25788.6 25814.6 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:35568.30 Median:21299.25 stddev:99970.59 (469.4%) stddev-sans-first:100216.00 08:50:19 INFO - PROCESS | 1640 | Values: 317.0 363.3 370.7 375.4 388.5 19151.2 19999.7 20006.7 20756.5 20932.6 16464.0 19612.0 19685.8 20128.9 20143.3 5118.5 5822.3 6069.2 6083.4 6101.0 6529.5 6652.2 6779.6 6785.8 6797.6 6525.6 6630.7 6643.0 6651.7 6693.5 6475.5 6623.4 6642.1 6686.4 6724.7 5066.2 5607.0 5707.8 5756.1 5787.2 25289.0 25324.2 25339.9 25571.9 25844.6 2215.4 2275.8 2342.7 2808.2 2928.5 15248.8 15362.0 15429.3 15526.8 15538.3 1860.6 1862.4 1921.7 1927.4 1934.5 117621.4 118778.0 119755.0 119783.4 123960.0 9676.5 10062.3 10193.1 10654.9 10754.0 12918.2 13013.5 13039.7 13067.5 13259.2 7536.6 8505.8 8602.4 8698.1 8746.7 6587.1 8717.8 8899.2 8981.1 8990.5 5249.8 5613.4 6533.6 6589.2 6611.3 1524.5 1795.9 1813.7 1826.8 1848.8 889.5 907.0 962.6 1008.7 1047.9 16616.9 17695.4 20991.4 21141.7 21456.8 977.9 1004.7 1012.4 1025.1 1078.1 10694.8 11097.3 11570.4 11633.2 11694.3 564490.0 597600.0 603124.0 611215.0 614467.0 108952.0 119733.0 122717.0 130787.3 138460.0 973.3 1023.9 1034.5 1034.5 1036.7 18798.7 19061.4 19296.1 19318.8 19371.9 57210.7 57867.0 58446.4 58966.7 59272.0 87493.0 87565.6 88496.7 88660.0 91020.5 5394.5 8015.3 8079.2 8122.1 8175.2 12569.7 12899.0 13068.5 13806.4 13833.0 10301.8 10454.2 11297.6 11601.4 11767.1 11373.0 11414.4 11432.8 11503.3 11637.4 11581.4 11689.7 11776.6 11893.8 12070.0 7926.4 8053.9 8406.2 8459.1 8472.7 7176.7 9898.9 11175.7 11232.9 11316.9 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5351.32 Median:5093.68 stddev:2911.84 (57.2%) stddev-sans-first:2905.54 08:50:19 INFO - PROCESS | 1640 | Values: 1489.7 1526.0 1709.3 1805.7 1863.8 6973.2 7528.9 7765.3 8296.0 8310.6 7906.0 8194.4 8203.5 8302.2 8303.9 2301.5 2343.8 2346.5 2348.5 2353.3 2277.5 2361.1 2375.8 2376.2 2393.6 2273.9 2321.4 2353.0 2356.2 2363.0 2302.2 2320.8 2321.8 2340.5 2351.9 2340.2 2343.3 2358.7 2362.1 2374.0 8201.9 8225.3 8230.8 8241.1 8248.2 4854.0 4918.2 5075.3 5088.1 5094.8 3287.8 3321.9 3372.6 3516.6 3530.9 941.8 1016.7 1049.6 1074.3 1087.7 10541.8 10589.0 10608.5 10618.4 10634.0 4183.0 4355.8 4429.6 4488.3 4602.3 7873.8 9153.9 9257.8 9388.7 9407.4 5079.4 5092.6 5129.2 5129.3 5140.5 4253.9 4378.7 4556.3 4660.0 4663.8 3334.7 3740.4 3754.5 3832.9 3851.1 1213.8 1245.2 1255.5 1260.1 1265.9 776.5 880.1 905.2 916.2 925.6 4666.8 5078.3 5489.6 5547.7 5577.9 942.1 967.5 974.2 984.7 992.7 3508.4 3652.3 3711.8 3760.8 3761.4 9768.6 9987.5 10064.0 10068.2 10102.4 9731.9 9743.2 9920.8 9971.5 10053.2 6471.3 7661.2 7784.0 7803.4 7879.5 5554.4 5571.4 5584.2 5621.7 5688.3 9203.9 9213.8 9257.3 9296.8 9354.4 9372.2 9392.1 9421.5 9466.6 9569.5 6522.6 6706.4 6900.2 7001.6 7157.0 7263.5 7471.0 7696.5 7809.9 7885.0 6970.3 7119.3 7130.1 7147.6 7189.0 6751.5 6942.8 6959.5 7031.0 7048.0 6443.8 6561.1 6625.8 6651.5 6663.6 4715.2 4738.1 4768.4 4779.7 4783.6 3911.5 4640.9 4816.0 4853.3 4896.0 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4599.14 Median:5210.63 stddev:1471.34 (28.2%) stddev-sans-first:1439.18 08:50:19 INFO - PROCESS | 1640 | Values: 272.5 303.6 307.0 308.3 309.3 5551.6 5563.4 5596.6 5610.8 5629.9 4274.5 5492.6 5495.8 5542.9 5570.1 3364.4 3962.9 4025.5 4090.0 4116.6 3564.9 4088.7 4123.9 4134.2 4141.5 3560.8 3960.8 4096.0 4107.2 4110.4 3101.6 3529.7 4066.0 4138.2 4140.8 3224.1 3402.3 3493.4 3515.3 3525.3 5160.7 5375.9 5395.3 5396.6 5417.8 3570.8 4000.6 4709.3 4741.0 4759.0 5528.3 5543.6 5671.2 5764.5 5770.6 2947.0 3585.9 3905.4 3916.5 3928.1 5381.0 5669.4 5742.9 5765.3 6086.7 3728.7 3894.9 3900.5 3934.4 3965.3 3808.8 3999.4 4024.9 4026.3 4033.0 3947.5 4695.2 4711.6 4713.5 4727.5 5483.4 5775.6 5820.7 5884.9 5918.2 1120.7 1274.7 1287.4 1309.4 1322.4 781.1 836.3 849.9 871.6 883.8 4883.0 5053.9 5099.1 5201.6 5285.3 4690.6 5089.7 5457.6 5644.2 5747.8 4529.3 4533.0 4832.9 4843.0 5224.6 2592.3 2960.8 2985.4 2999.6 3015.8 5151.3 5219.7 5308.3 5715.1 5762.3 5307.7 5545.5 5752.8 5767.6 6193.8 6264.8 6286.7 6300.2 6325.1 6334.1 4434.4 4794.4 6087.9 6117.1 6155.8 5189.8 5485.2 5857.4 5930.4 5979.6 5717.8 6258.7 6260.2 6273.8 6298.4 3376.6 3677.8 3727.0 3939.0 4051.5 5311.2 5990.3 6014.6 6016.5 6061.9 4430.9 5362.3 5421.0 5631.5 5879.0 4809.5 5077.4 5617.3 5764.6 5862.4 5684.5 5751.8 5767.2 5802.3 5825.6 5593.9 5739.9 5806.1 5841.9 5902.5 4796.2 4855.7 5373.8 5706.3 5951.3 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:19659.72 Median:4439.96 stddev:28632.36 (644.9%) stddev-sans-first:28677.16 08:50:19 INFO - PROCESS | 1640 | Values: 665.4 712.6 714.5 718.0 775.8 11735.4 14228.4 14915.1 15033.7 15047.4 13522.9 13537.5 13543.5 13645.0 13678.0 681.3 703.2 819.2 854.2 867.3 839.3 862.3 862.6 864.5 865.1 866.2 884.5 884.8 909.4 911.8 883.8 885.5 902.4 904.7 905.6 645.6 665.6 670.8 678.2 679.1 13674.9 13745.0 13796.7 13970.2 14625.6 13913.4 14192.9 14283.0 14291.3 14503.7 11613.2 11715.6 11726.0 11734.1 12009.8 3654.8 3660.5 3704.4 3832.5 3907.8 92287.6 94509.5 95450.5 95465.0 96018.0 10493.0 10644.8 10650.7 10720.6 10759.3 15723.4 16612.9 18834.0 19804.7 19816.7 7600.7 7612.0 7709.6 7735.8 7858.8 4364.5 4372.7 4507.1 4532.6 4568.4 3346.8 3373.2 3444.2 3462.4 3465.0 518.5 546.2 555.0 618.5 624.7 624.3 638.3 640.8 644.0 672.2 7983.1 8906.3 8941.5 9034.2 9040.6 567.8 633.9 636.9 648.2 662.3 3359.7 3391.7 3993.2 4432.5 4447.4 79469.0 95693.0 98722.0 123548.4 127158.0 63421.0 64343.0 67038.0 91169.5 99287.0 77945.6 87147.2 88087.0 93542.5 96848.0 7611.9 8363.5 8740.9 8748.2 8889.8 39495.0 43182.7 44313.6 51008.5 52519.7 55793.7 57115.4 64726.0 66489.0 67705.7 12562.7 12701.6 12775.9 12777.2 12867.0 14267.5 14417.4 14579.4 14594.6 14705.5 13170.7 13463.7 13475.7 13490.3 13597.3 12546.8 13167.3 13322.3 13672.2 13842.4 13116.3 13260.4 13500.6 13760.9 13775.9 11020.9 11112.8 11166.6 11248.8 11433.7 11235.6 11283.6 11517.0 11574.2 11590.5 08:50:19 INFO - PROCESS | 1640 | -------- Summary: end -------- 08:50:19 INFO - PROCESS | 1640 | 08:50:19 INFO - TEST-INFO | 1640: exit 0 08:50:20 INFO - TEST-OK | dromaeo_css | took 1113896ms 08:50:20 INFO - TEST-START | kraken 08:50:20 INFO - operating with platform_type : mac_ 08:50:20 INFO - Initialising browser for kraken test... 08:50:20 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpcPctKt/profile http://localhost:49238/getInfo.html) 08:50:22 INFO - PROCESS | 1651 | 2017-02-13 08:50:22.916 plugin-container[1653:10428] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874b, name = 'com.apple.tsm.portname' 08:50:22 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:22 INFO - PROCESS | 1651 | 2017-02-13 08:50:22.917 plugin-container[1653:10428] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 08:50:22 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:22 INFO - PROCESS | 1651 | 2017-02-13 08:50:22.917 plugin-container[1653:10428] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:22 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:50:22 INFO - PROCESS | 1651 | colorDepth:24 08:50:22 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:50:22 INFO - PROCESS | 1651 | __metrics 08:50:23 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 08:50:23 INFO - TEST-INFO | 1651: exit 0 08:50:23 INFO - Browser initialized. 08:50:23 INFO - Running cycle 1/1 for kraken test... 08:50:23 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpcPctKt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:26 INFO - PROCESS | 1654 | 2017-02-13 08:50:26.151 plugin-container[1655:10555] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463f, name = 'com.apple.tsm.portname' 08:50:26 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:26 INFO - PROCESS | 1654 | 2017-02-13 08:50:26.152 plugin-container[1655:10555] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8623, name = 'com.apple.CFPasteboardClient' 08:50:26 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:26 INFO - PROCESS | 1654 | 2017-02-13 08:50:26.152 plugin-container[1655:10555] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:35 INFO - PROCESS | 1654 | RSS: Main: 177135616 08:50:35 INFO - PROCESS | 1654 | 08:50:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:39 INFO - PROCESS | 1654 | RSS: Main: 190009344 08:50:39 INFO - PROCESS | 1654 | 08:50:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 08:50:42 INFO - PROCESS | 1654 | RSS: Main: 198930432 08:50:42 INFO - PROCESS | 1654 | 08:50:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 08:50:46 INFO - PROCESS | 1654 | RSS: Main: 201969664 08:50:46 INFO - PROCESS | 1654 | 08:50:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 08:50:49 INFO - PROCESS | 1654 | RSS: Main: 202080256 08:50:49 INFO - PROCESS | 1654 | 08:50:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:51 INFO - PROCESS | 1654 | RSS: Main: 203341824 08:50:51 INFO - PROCESS | 1654 | 08:50:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:55 INFO - PROCESS | 1654 | RSS: Main: 207044608 08:50:55 INFO - PROCESS | 1654 | 08:50:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:58 INFO - PROCESS | 1654 | RSS: Main: 207663104 08:50:58 INFO - PROCESS | 1654 | 08:51:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 08:51:02 INFO - PROCESS | 1654 | RSS: Main: 207085568 08:51:02 INFO - PROCESS | 1654 | 08:51:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:51:04 INFO - PROCESS | 1654 | RSS: Main: 205893632 08:51:04 INFO - PROCESS | 1654 | 08:51:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:51:07 INFO - PROCESS | 1654 | RSS: Main: 205983744 08:51:07 INFO - PROCESS | 1654 | 08:51:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:51:09 INFO - PROCESS | 1654 | RSS: Main: 205950976 08:51:09 INFO - PROCESS | 1654 | 08:51:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:51:13 INFO - PROCESS | 1654 | RSS: Main: 205983744 08:51:13 INFO - PROCESS | 1654 | 08:51:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:17 INFO - PROCESS | 1654 | RSS: Main: 206295040 08:51:17 INFO - PROCESS | 1654 | 08:51:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 08:51:19 INFO - PROCESS | 1654 | RSS: Main: 208232448 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | __start_tp_report 08:51:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 08:51:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 08:51:19 INFO - PROCESS | 1654 | |i|pagename|runs| 08:51:19 INFO - PROCESS | 1654 | |0;ai-astar;102;118;117;117;118;117;118;117;118;117 08:51:19 INFO - PROCESS | 1654 | |1;audio-beat-detection;144;138;97;165;112;142;142;142;135;113 08:51:19 INFO - PROCESS | 1654 | |2;audio-dft;195;204;196;196;200;198;199;193;199;199 08:51:19 INFO - PROCESS | 1654 | |3;audio-fft;76;52;66;109;85;85;53;58;78;58 08:51:19 INFO - PROCESS | 1654 | |4;audio-oscillator;80;70;87;73;67;71;84;68;69;87 08:51:19 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;94;91;106;106;77;74;123;126;76;74 08:51:19 INFO - PROCESS | 1654 | |6;imaging-darkroom;98;99;98;98;98;118;152;153;98;98 08:51:19 INFO - PROCESS | 1654 | |7;imaging-desaturate;126;97;141;141;119;89;95;92;127;72 08:51:19 INFO - PROCESS | 1654 | |8;json-parse-financial;55;56;55;56;59;58;59;60;58;57 08:51:19 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;67;73;74;81;79;70;68;65;64;71 08:51:19 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;82;85;83;80;79;83;83;86;104;77 08:51:19 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;146;151;149;153;159;149;172;154;182;150 08:51:19 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;166;167;160;162;160;166;166;165;163;163 08:51:19 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;67;68;65;69;71;69;66;66;63 08:51:19 INFO - PROCESS | 1654 | __end_tp_report 08:51:19 INFO - PROCESS | 1654 | __start_cc_report 08:51:19 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,920 08:51:19 INFO - PROCESS | 1654 | __end_cc_report 08:51:19 INFO - PROCESS | 1654 | __startTimestamp1487004679044__endTimestamp 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | ------- Summary: start ------- 08:51:19 INFO - PROCESS | 1654 | Number of tests: 14 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:115.90 Median:117.50 stddev:4.91 (4.2%) stddev-sans-first:0.53 08:51:19 INFO - PROCESS | 1654 | Values: 102.0 118.0 117.0 117.0 118.0 117.0 118.0 117.0 118.0 117.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:142.00 stddev:19.87 (14.0%) stddev-sans-first:20.67 08:51:19 INFO - PROCESS | 1654 | Values: 144.0 138.0 97.0 165.0 112.0 142.0 142.0 142.0 135.0 113.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:197.90 Median:199.00 stddev:3.07 (1.5%) stddev-sans-first:3.07 08:51:19 INFO - PROCESS | 1654 | Values: 195.0 204.0 196.0 196.0 200.0 198.0 199.0 193.0 199.0 199.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:72.00 Median:71.00 stddev:18.10 (25.5%) stddev-sans-first:19.14 08:51:19 INFO - PROCESS | 1654 | Values: 76.0 52.0 66.0 109.0 85.0 85.0 53.0 58.0 78.0 58.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.50 stddev:8.06 (10.5%) stddev-sans-first:8.39 08:51:19 INFO - PROCESS | 1654 | Values: 80.0 70.0 87.0 73.0 67.0 71.0 84.0 68.0 69.0 87.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:75.00 stddev:19.92 (26.6%) stddev-sans-first:21.12 08:51:19 INFO - PROCESS | 1654 | Values: 94.0 91.0 106.0 106.0 77.0 74.0 123.0 126.0 76.0 74.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:111.00 Median:98.00 stddev:22.74 (23.2%) stddev-sans-first:23.62 08:51:19 INFO - PROCESS | 1654 | Values: 98.0 99.0 98.0 98.0 98.0 118.0 152.0 153.0 98.0 98.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:109.90 Median:80.50 stddev:23.92 (29.7%) stddev-sans-first:24.65 08:51:19 INFO - PROCESS | 1654 | Values: 126.0 97.0 141.0 141.0 119.0 89.0 95.0 92.0 127.0 72.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:1.77 (3.0%) stddev-sans-first:1.67 08:51:19 INFO - PROCESS | 1654 | Values: 55.0 56.0 55.0 56.0 59.0 58.0 59.0 60.0 58.0 57.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:72.00 stddev:5.65 (7.9%) stddev-sans-first:5.79 08:51:19 INFO - PROCESS | 1654 | Values: 67.0 73.0 74.0 81.0 79.0 70.0 68.0 65.0 64.0 71.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:83.00 stddev:7.47 (9.0%) stddev-sans-first:7.88 08:51:19 INFO - PROCESS | 1654 | Values: 82.0 85.0 83.0 80.0 79.0 83.0 83.0 86.0 104.0 77.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:153.50 stddev:11.60 (7.6%) stddev-sans-first:11.66 08:51:19 INFO - PROCESS | 1654 | Values: 146.0 151.0 149.0 153.0 159.0 149.0 172.0 154.0 182.0 150.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:165.50 stddev:2.57 (1.6%) stddev-sans-first:2.60 08:51:19 INFO - PROCESS | 1654 | Values: 166.0 167.0 160.0 162.0 160.0 166.0 166.0 165.0 163.0 163.0 08:51:19 INFO - PROCESS | 1654 | 08:51:19 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:68.00 stddev:2.30 (3.4%) stddev-sans-first:2.42 08:51:19 INFO - PROCESS | 1654 | Values: 68.0 67.0 68.0 65.0 69.0 71.0 69.0 66.0 66.0 63.0 08:51:19 INFO - PROCESS | 1654 | -------- Summary: end -------- 08:51:19 INFO - PROCESS | 1654 | 08:51:20 INFO - TEST-INFO | 1654: exit 0 08:51:20 INFO - TEST-OK | kraken | took 60301ms 08:51:20 INFO - SUITE-END | took 1174s 08:51:20 INFO - Completed test suite (00:19:34) 08:51:20 INFO - javascript benchmark 08:51:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7268.3914234901295, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3471.5834099908006, 3540.752147349686, 3543.645635436458, 3548.804815735137, 3588.3182195382874, 5772.595918285721, 5831.591788574796, 5845.7953971611005, 5859.531237501001, 5894.58737888348, 5833.737481813318, 5927.73325200366, 5927.940720592793, 5959.225300710912, 5970.492508136803, 5225.372955245366, 5337.893242135155, 5360.383555891064, 5365.3293338332705, 5374.91937620934, 5348.732563371836, 5374.220737993008, 5391.487808658191, 5392.541633961118, 5404.91892621611, 5210.0, 5264.2893209416625, 5355.973220133894, 5364.973175134119, 5415.241866138744, 5288.735563221823, 5298.523132918037, 5346.224797404355, 5354.0, 5399.325084364455, 5230.529252367305, 5317.734113294358, 5328.573714102862, 5330.307060082165, 5366.329208848894, 5695.829125126212, 5868.383819698955, 5911.763529458816, 5972.581919265654, 5977.0, 6216.751329946796, 6235.657038862906, 6239.126522286883, 6314.052892066181, 6369.554131210817, 6290.622562646256, 6297.748090076391, 6306.716197771111, 6315.684215789238, 6325.019621958604, 5957.791477298317, 5968.850778730566, 5970.164177015221, 5973.104034394875, 5977.22296101504, 5065.442801291855, 6202.875942481215, 6238.500919926395, 6273.7176827042895, 6313.526485513605, 5324.600654950894, 6206.882761102955, 6243.37566243372, 6282.403171698681, 6313.08460273267, 6300.0, 6305.873882522324, 6319.178506794179, 6329.828981638411, 6339.2709838368255, 6128.571000030044, 6138.204480309982, 6156.5998210116195, 6225.657588832658, 6331.113644089831, 6133.877322453526, 6141.846453838601, 6221.253449585988, 6232.8130156095385, 6251.906221406683, 5076.74616269185, 5079.288899554065, 5083.644144909895, 5085.7202853842655, 5110.642255042111, 4352.847650332224, 4445.866624001285, 4532.841350552781, 4534.727916325031, 4538.341940418621, 6004.789832355934, 6068.939310606837, 6101.359357267512, 6119.563485741643, 6128.080787881764, 6277.3526380514095, 6306.905396419057, 6313.463355614732, 6313.684315784192, 6332.620042797447, 5854.765809367577, 5988.700564971734, 6082.057281121433, 6098.908516372258, 6121.928662484048, 5014.598832093424, 5553.6390134641115, 5562.471565201299, 5571.303587051619, 5575.107982722745, 6465.385788350193, 6497.935020649733, 6512.511561632802, 6515.511336649675, 6524.249711283263, 6228.5951413158, 6229.0967809668255, 6242.1261023455845, 6305.369463053841, 6308.810735677937, 6481.287058423475, 6483.740650373932, 6526.510511711545, 6538.967305163444, 6567.408933196035, 6305.747770089145, 6310.211223597051, 6341.4292713656, 6347.555671103163, 6356.173697419306, 5301.920471193012, 6105.358937311518, 6222.0, 6275.654838983717, 6303.621782693053, 6155.076738489083, 6256.937430625818, 6280.560360774884, 6293.37066293367, 6323.177986861679, 6220.409061139185, 6276.435120839146, 6277.18396608438, 6285.874282514415, 6295.874082518416, 5978.252718410199, 6009.45914867664, 6044.546659000681, 6154.507639388748, 6185.567010309235, 6196.597221180609, 6220.284667263141, 6248.343923887926, 6293.811185664437, 6304.243490781136, 6169.444749972524, 6179.783707570214, 6195.349488303844, 6203.565750397429, 6208.906866397098], "value": 5821.8897076086005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.7193641719594, 502.0887089931776, 545.8362491252625, 558.8044184535413, 567.2229046206697, 31904.0, 39526.60473395265, 39546.01134971624, 39614.60385396145, 39642.81071567858, 40077.198456030936, 40584.39123413151, 40661.0, 40668.18663626733, 40738.79630601843, 9453.338266321343, 9500.952495237549, 9641.566129524153, 9643.987381324965, 9654.855177172349, 9479.47862867542, 9597.040295970382, 9663.806723865519, 9672.177864881494, 9702.369345992523, 9554.092361225707, 9669.854952175723, 9698.078682525149, 9720.270979676518, 9720.319577629569, 9535.094166054214, 9569.760755981086, 9619.951900240523, 9713.320067595307, 9716.076972687584, 9455.763605909839, 9466.337356385056, 9530.999245079305, 9600.039996000414, 9682.75793105171, 37923.43114853279, 38240.42639360412, 38479.845604631904, 38547.61452385468, 38663.8066809667, 3193.8562764675644, 3362.142653623335, 3788.3370410178386, 3985.481887354654, 4069.04377471294, 29865.55201671977, 30684.84657576698, 30840.228994275098, 31167.22081947947, 31228.53157202621, 3232.2404235004756, 3300.5544251525976, 3311.8344082796007, 3312.7681062325646, 3327.2014716467875, 479203.0, 481277.0, 481773.0, 482418.0, 483560.0, 58579.0, 58883.0, 59040.22879313627, 59093.0, 59147.11279330814, 58548.70725646344, 58662.0, 59418.405815942155, 61001.0, 61091.6945415279, 15842.366305347658, 15888.523344299689, 15963.281652325673, 16077.919610401874, 16103.27535260916, 12644.747105057848, 12690.936545317214, 12981.675458113434, 12982.740345193231, 13000.154989925813, 14809.777853332209, 15068.623284417758, 15333.769993450107, 15535.922320388325, 15597.844021559866, 6065.969670151621, 6551.279359270477, 6818.761343352959, 6869.7595584154315, 6899.275576064541, 869.9652013919499, 975.3903810118676, 996.1632228927591, 996.3274789517047, 1071.7427817323787, 30177.0, 30267.0, 30705.0, 30775.076747697603, 30900.53649195264, 974.5858010345574, 1041.5209003858158, 1090.7055095124272, 1137.7155711072232, 1198.279342641087, 11352.94323528377, 15742.763858542132, 15885.364585416684, 15913.363465461252, 16034.599135021717, 677547.0, 682007.0, 693864.0, 694219.0, 695049.0, 59123.704381477815, 59142.40857591369, 59190.70404647949, 59236.40763592309, 59464.0, 1400.4958215042577, 1410.8941829362634, 1439.0789894467548, 1441.08491108145, 1442.1419255542935, 25503.6174457387, 29468.852655736584, 30685.846570767004, 31155.688443116185, 31374.843125784224, 46591.30113048239, 48724.0, 49180.75409623072, 49339.753301233264, 49620.007599848526, 564725.0, 565529.0, 566936.0, 568161.0, 568600.0, 26762.063327784213, 27047.0, 27076.864615676797, 27212.183634490997, 27315.317117071438, 46683.53316466928, 46708.53291467042, 47324.2901356473, 47445.762771185924, 47744.522554774005, 32114.19714507156, 34614.82692586521, 38093.047673808374, 38336.42495362516, 39622.60377396189, 33187.66812331846, 37488.0, 37853.242935141694, 38229.42655860109, 38557.036074098374, 29931.85034074816, 36892.07769805669, 37349.066273343386, 38399.616003840725, 38525.036874077254, 33454.99635010953, 33889.15277118092, 33999.66000339965, 34756.9572912813, 34798.82600586981, 25203.99184032618, 25321.62017569701, 25397.238082857544, 25788.613170802077, 25814.612780807925], "value": 19747.04211279591, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.968303169683, 363.2589517384534, 370.6849178198532, 375.40310905659993, 388.4910766895367, 19151.233950641974, 19999.700004499944, 20006.69989950152, 20756.481087972807, 20932.581348373024, 16464.0, 19612.01939903006, 19685.803141968576, 20128.899355503203, 20143.29498467554, 5118.539331460168, 5822.272215973004, 6069.15031895535, 6083.391660833903, 6100.993336099538, 6529.5429319947625, 6652.201735791712, 6779.62712050837, 6785.76249831256, 6797.626130562817, 6525.608463492182, 6630.73477060917, 6643.0, 6651.7006734696815, 6693.464522838161, 6475.4819614430735, 6623.370779775938, 6642.103316052319, 6686.364795344434, 6724.731010759564, 5066.164082926312, 5606.971965140189, 5707.828765137053, 5756.079027355645, 5787.218725472049, 25289.0, 25324.240272791845, 25339.873300633564, 25571.872140639178, 25844.612330814864, 2215.3796936857707, 2275.84069115162, 2342.672025916373, 2808.199663096008, 2928.546075358323, 15248.771268430983, 15362.00146990442, 15429.30568124437, 15526.767098493532, 15538.300776465087, 1860.6278744251204, 1862.431958252746, 1921.6829223178158, 1927.3736035788424, 1934.516370907273, 117621.41189293998, 118778.0, 119755.0, 119783.4010829949, 123960.0, 9676.467794271382, 10062.295639305325, 10193.133583645473, 10654.893451065545, 10754.0, 12918.22490650564, 13013.544525941546, 13039.674008149683, 13067.477300908047, 13259.204447732975, 7536.623168841536, 8505.829883402297, 8602.35482338827, 8698.13018698125, 8746.69386571467, 6587.110740050128, 8717.825643487222, 8899.15458031486, 8981.101889810967, 8990.505522196341, 5249.816256431007, 5613.438656134354, 6533.562616224423, 6589.17635295588, 6611.305812889577, 1524.542637208833, 1795.937142200043, 1813.6916724156924, 1826.7716535433071, 1848.7613299089373, 889.515214208258, 906.9909300906907, 962.6341990043738, 1008.7074748322905, 1047.9214058945456, 16616.91990020669, 17695.380661677038, 20991.370258892257, 21141.68287475689, 21456.81987490703, 977.8544722461876, 1004.7237009822358, 1012.4431562640518, 1025.0979138358198, 1078.1105587890022, 10694.786104277871, 11097.33415995043, 11570.363630000262, 11633.243839150597, 11694.2983420995, 564490.0, 597600.0, 603123.9843800841, 611215.0, 614467.0, 108952.0, 119733.0, 122717.0, 130787.34606326907, 138460.0, 973.3284034016506, 1023.902729240721, 1034.4982683398546, 1034.5189486888783, 1036.6889933020216, 18798.718019229447, 19061.428157155307, 19296.131674074983, 19318.8068119317, 19371.903140484206, 57210.71394643, 57867.0, 58446.4155358441, 58966.70516647561, 59272.0, 87493.0, 87565.56217219128, 88496.67254991051, 88660.0, 91020.54489727509, 5394.460553944574, 8015.318697910743, 8079.151689072798, 8122.1065682776025, 8175.182481751777, 12569.748605028031, 12899.0, 13068.477260909838, 13806.447742090204, 13833.0, 10301.793964120825, 10454.163666906781, 11297.604583839855, 11601.361925094201, 11767.117466189899, 11373.033292170257, 11414.429278535874, 11432.82850757256, 11503.309801411608, 11637.418129093681, 11581.363025033705, 11689.707757306136, 11776.587819426279, 11893.82159267629, 12070.0, 7926.445148839526, 8053.8791918120105, 8406.201410865959, 8459.069502354612, 8472.703455379033, 7176.677049532679, 9898.851517227104, 11175.664730058112, 11232.943835280772, 11316.886831131913], "value": 9880.926048030657, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1489.702059588082, 1525.9542213733585, 1709.3419033672033, 1805.711086226204, 1863.8136186381373, 6973.198082220546, 7528.887066694004, 7765.301122898938, 8296.004479462465, 8310.626021829017, 7906.0, 8194.426390152692, 8203.548804815733, 8302.169783021709, 8303.875441868377, 2301.528186721718, 2343.8476499027593, 2346.5189636124555, 2348.4833336665993, 2353.2822489140804, 2277.533105713333, 2361.126383238204, 2375.8336916415856, 2376.192094687806, 2393.617021276596, 2273.874936878471, 2321.442853715105, 2352.9882350588223, 2356.1988923765916, 2362.9881850590723, 2302.1712183613968, 2320.8259380546356, 2321.802646775018, 2340.508493216427, 2351.870647114408, 2340.157543284416, 2343.308723926446, 2358.6697862299293, 2362.11420717231, 2373.9762602373926, 8201.917980820175, 8225.341972642174, 8230.794230144234, 8241.093479717227, 8248.175182481704, 4853.975730121327, 4918.2376731606655, 5075.264086707407, 5088.084144853925, 5094.77073531692, 3287.797175892158, 3321.933561328784, 3372.6458721834347, 3516.595591506984, 3530.929381412383, 941.7598512379368, 1016.6899095775785, 1049.564430761233, 1074.258761454594, 1087.7498175419698, 10541.84187237192, 10589.04698577124, 10608.469576521144, 10618.415987120705, 10634.042936135707, 4182.958170418317, 4355.7822108894425, 4429.645628349725, 4488.259437192892, 4602.309653551994, 7873.842523149505, 9153.908460915438, 9257.768555786159, 9388.71833844972, 9407.43555386679, 5079.3650793650795, 5092.592592592584, 5129.230615407719, 5129.256257842592, 5140.537351638371, 4253.914921701548, 4378.671599629977, 4556.270996640522, 4660.021395506914, 4663.790129444184, 3334.699877011081, 3740.3828368318923, 3754.5494540655295, 3832.9425058624142, 3851.1334949636107, 1213.798339643764, 1245.2466257914014, 1255.4978008796372, 1260.1431026901612, 1265.8860702536815, 776.4836383804819, 880.1418616848522, 905.2441211588266, 916.1845957098188, 925.648253663617, 4666.763307723456, 5078.289039534468, 5489.58828087895, 5547.667139971614, 5577.944220557823, 942.1050002497655, 967.5259123029806, 974.206022092007, 984.7390441532999, 992.7220378294089, 3508.4035713928693, 3652.251288485854, 3711.814409279525, 3760.8495660173285, 3761.4169803680475, 9768.609255629695, 9987.500624968869, 10064.043915827984, 10068.194544436428, 10102.444365559817, 9731.854022189673, 9743.220542356736, 9920.751981200383, 9971.451570163566, 10053.246006549538, 6471.320511346335, 7661.157272699998, 7783.9610801946765, 7803.375729941591, 7879.527228366316, 5554.361248456479, 5571.415001424792, 5584.218209450762, 5621.747021383966, 5688.345840228427, 9203.907960920305, 9213.815723685622, 9257.25941924631, 9296.814063718823, 9354.391964522285, 9372.203362713974, 9392.107749763762, 9421.528923553931, 9466.621335146518, 9569.521523923637, 6522.5760325578685, 6706.362895524917, 6900.2409734928115, 7001.614911179934, 7157.033800437082, 7263.5109802491415, 7471.0, 7696.538207707556, 7809.882851757115, 7884.960575197088, 6970.268121847133, 7119.288071192839, 7130.073090498202, 7147.64261786919, 7189.0, 6751.527393082437, 6942.826429339105, 6959.478039146983, 7031.015657807809, 7047.964760176166, 6443.806685799434, 6561.114249576246, 6625.768098116543, 6651.50113741481, 6663.633500157538, 4715.24556071027, 4738.147133515862, 4768.427788665383, 4779.737114458738, 4783.6412269079265, 3911.4915061041884, 4640.86077417678, 4816.0, 4853.320535125156, 4895.996320754308], "value": 4367.059372057486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.453730270807, 303.5507448975516, 307.0481507327285, 308.28785505482324, 309.33338655198054, 5551.555875529958, 5563.36021357544, 5596.636218645785, 5610.803621873235, 5629.943700562993, 4274.461193970168, 5492.615516913817, 5495.807646732365, 5542.870561431729, 5570.080936645459, 3364.4187232000922, 3962.9405558916637, 4025.537063237721, 4089.9550224887557, 4116.608922152405, 3564.893053208395, 4088.713790034699, 4123.855665051724, 4134.214499245134, 4141.482314710661, 3560.789331627246, 3960.8019599019935, 4096.0, 4107.178564287155, 4110.403991421257, 3101.6433110192393, 3529.7352698547456, 4065.9593404066172, 4138.193052354777, 4140.751554906716, 3224.1456014156265, 3402.28552004077, 3493.3537295600395, 3515.33208690348, 3525.3478106550115, 5160.741962901878, 5375.897940922102, 5395.271638328853, 5396.568274538027, 5417.756200970965, 3570.767900086512, 4000.6399424051683, 4709.293605959099, 4740.961386603759, 4759.0024379521365, 5528.281323427969, 5543.556515478793, 5671.234383358276, 5764.452377024176, 5770.566512924845, 2946.9852650736825, 3585.8924232273075, 3905.3946638271113, 3916.530016398022, 3928.0572662561067, 5380.973095134499, 5669.376368599451, 5742.913856292159, 5765.250517432749, 6086.726097325631, 3728.7203459740626, 3894.922101557953, 3900.473436086092, 3934.370500719871, 3965.345717956505, 3808.7718684569836, 3999.3601023836045, 4024.8730355500506, 4026.275270451347, 4033.0, 3947.50656167979, 4695.201815691273, 4711.56297329311, 4713.481517033192, 4727.479977202505, 5483.396826349099, 5775.5668324875805, 5820.679862607512, 5884.852878678067, 5918.201042859245, 1120.6694025262568, 1274.706817431996, 1287.4013583683632, 1309.4107651556837, 1322.418136020148, 781.0744268042388, 836.3476488339105, 849.9192576705203, 871.6164887449502, 883.7746374674417, 4883.0, 5053.873653158627, 5099.056674515226, 5201.555269023553, 5285.312909321764, 4690.624750019991, 5089.724631309718, 5457.608309880955, 5644.193857965412, 5747.827565173052, 4529.275315949432, 4532.968405070369, 4832.897689505076, 4843.016204939856, 5224.582033437316, 2592.300078978703, 2960.763138948879, 2985.4327677741157, 2999.6100506934395, 3015.8492075396143, 5151.304573882478, 5219.712915789592, 5308.283381743492, 5715.142728590745, 5762.328924611899, 5307.681539107666, 5545.502714267125, 5752.798652047242, 5767.596268261182, 6193.752249909953, 6264.780732674426, 6286.748530058747, 6300.21247344082, 6325.098049012053, 6334.13617693216, 4434.423524941866, 4794.44180641286, 6087.908681369871, 6117.113018612187, 6155.753769849156, 5189.766460509361, 5485.177223416519, 5857.36418611635, 5930.436608522184, 5979.5814292999075, 5717.799262154827, 6258.749650013949, 6260.217472815898, 6273.843153921006, 6298.370162983665, 3376.57792775903, 3677.8344974475676, 3727.030971947259, 3938.980305098456, 4051.5340735815757, 5311.203319502106, 5990.281166259902, 6014.578979471395, 6016.458518733335, 6061.939160646781, 4430.889227769331, 5362.28119255053, 5420.988468533335, 5631.521320687787, 5878.970605146947, 4809.519048095302, 5077.39071311445, 5617.292215230346, 5764.57887687164, 5862.413758624274, 5684.516816070515, 5751.827445176484, 5767.163761254511, 5802.274715660543, 5825.563082768725, 5593.944060559343, 5739.9139012915675, 5806.083992282621, 5841.8539536510225, 5902.498287645427, 4796.232602783538, 4855.732934688626, 5373.838784836461, 5706.28671416073, 5951.345352011189], "value": 4123.319164567574, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.4443539644394, 712.5617745086771, 714.5426926766867, 718.0270733156574, 775.8331958628894, 11735.354555499443, 14228.43086276549, 14915.105093694387, 15033.699326013473, 15047.398104075824, 13522.93238533806, 13537.526186583471, 13543.45826166952, 13645.044846860723, 13677.999999999975, 681.3050688297945, 703.2194264366549, 819.2217393476192, 854.1714536899198, 867.319154463746, 839.2991851803752, 862.3015357560365, 862.6463150108457, 864.4769914201912, 865.1391865094208, 866.1901122450529, 884.5444596033049, 884.752269364579, 909.4361495872569, 911.803962148138, 883.7967267528472, 885.4642941020686, 902.4089221559889, 904.6517090919978, 905.5653286422488, 645.6223109480959, 665.5574043261225, 670.7518218259227, 678.1726293921457, 679.1307126877605, 13674.863251367558, 13745.0, 13796.655083622889, 13970.161790292615, 14625.634359141, 13913.443462261497, 14192.92903535486, 14283.0, 14291.285435728276, 14503.70992580153, 11613.245139065933, 11715.589954351643, 11726.0, 11734.11994100446, 12009.819852702216, 3654.817259137059, 3660.4875317455576, 3704.4258139988347, 3832.482614846988, 3907.8046097695, 92287.61568576477, 94509.5274523623, 95450.52274738651, 95465.0, 96018.0, 10493.0, 10644.840327395095, 10650.73373165677, 10720.624778132727, 10759.300645458177, 15723.371065157266, 16612.916935415247, 18833.964131972873, 19804.70292945607, 19816.70274945877, 7600.657970391235, 7612.0, 7709.575973321521, 7735.7679269622, 7858.803529911684, 4364.541723119027, 4372.672049596293, 4507.098580283956, 4532.592066713945, 4568.360429539867, 3346.8159251240927, 3373.1735724747596, 3444.2078321986082, 3462.4113900636953, 3465.012471445626, 518.4530320511897, 546.1970902772917, 554.9732993961139, 618.4928358745785, 624.65643895858, 624.2832211418599, 638.3010603389281, 640.7953048269223, 644.0468107201368, 672.2067959807376, 7983.12185659577, 8906.332025098143, 8941.46351218929, 9034.23209027227, 9040.593173307218, 567.8353277549547, 633.8985762278105, 636.920384951881, 648.176815444383, 662.2715013485127, 3359.6808303211155, 3391.6777906098882, 3993.1813978134414, 4432.468103827561, 4447.444069491314, 79469.0, 95693.0, 98722.0, 123548.38225808993, 127158.0, 63421.0, 64343.03485447722, 67038.0, 91169.54415227882, 99287.0, 77945.61027194827, 87147.20690361965, 88087.0, 93542.53228733812, 96848.0315196853, 7611.923880761122, 8363.456375335583, 8740.868886966573, 8748.168923952215, 8889.777755555904, 39495.01262468461, 43182.704518864484, 44313.556864432234, 51008.48991510038, 52519.737401314276, 55793.72103139458, 57115.42884571101, 64726.0, 66489.0, 67705.66147169232, 12562.685932851751, 12701.555445559361, 12775.936120319338, 12777.16948398351, 12867.0, 14267.500637477639, 14417.423303068179, 14579.416823327372, 14594.635134121732, 14705.485308014167, 13170.736585268049, 13463.663408414868, 13475.663108422368, 13490.258035808125, 13597.252151131386, 12546.811797822857, 13167.2757998311, 13322.33388330599, 13672.210393619407, 13842.377093031038, 13116.278604676452, 13260.403281852146, 13500.594982150551, 13760.867065604847, 13775.931120344334, 11020.944895275472, 11112.777744444904, 11166.609168679057, 11248.831267531157, 11433.656990290305, 11235.55057797679, 11283.605073822377, 11517.0, 11574.189806713786, 11590.536378544764], "value": 7207.473698766979, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1510.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 118.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 138.0, 97.0, 165.0, 112.0, 142.0, 142.0, 142.0, 135.0, 113.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 196.0, 196.0, 200.0, 198.0, 199.0, 193.0, 199.0, 199.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 52.0, 66.0, 109.0, 85.0, 85.0, 53.0, 58.0, 78.0, 58.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 70.0, 87.0, 73.0, 67.0, 71.0, 84.0, 68.0, 69.0, 87.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 91.0, 106.0, 106.0, 77.0, 74.0, 123.0, 126.0, 76.0, 74.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 98.0, 98.0, 118.0, 152.0, 153.0, 98.0, 98.0], "value": 111.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 97.0, 141.0, 141.0, 119.0, 89.0, 95.0, 92.0, 127.0, 72.0], "value": 109.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 56.0, 59.0, 58.0, 59.0, 60.0, 58.0, 57.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 74.0, 81.0, 79.0, 70.0, 68.0, 65.0, 64.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 83.0, 80.0, 79.0, 83.0, 83.0, 86.0, 104.0, 77.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 151.0, 149.0, 153.0, 159.0, 149.0, 172.0, 154.0, 182.0, 150.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 160.0, 162.0, 160.0, 166.0, 166.0, 165.0, 163.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 65.0, 69.0, 71.0, 69.0, 66.0, 66.0, 63.0], "value": 67.2, "unit": "score"}]}]} 08:51:20 INFO - Return code: 0 08:51:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:51:20 ERROR - # TBPL SUCCESS # 08:51:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:20 INFO - 2017-02-13 08:51:20.885 screenresolution[1659:10928] starting screenresolution argv=screenresolution get 08:51:20 INFO - 2017-02-13 08:51:20.888 screenresolution[1659:10928] Display 0: 1600x1200x32@60 08:51:20 INFO - 2017-02-13 08:51:20.894 screenresolution[1660:10931] starting screenresolution argv=screenresolution list 08:51:20 INFO - Available Modes on Display 0 08:51:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:21 INFO - Intel Iris: 08:51:21 INFO - Chipset Model: Intel Iris 08:51:21 INFO - Type: GPU 08:51:21 INFO - Bus: Built-In 08:51:21 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:21 INFO - Vendor: Intel (0x8086) 08:51:21 INFO - Device ID: 0x0a2e 08:51:21 INFO - Revision ID: 0x0009 08:51:21 INFO - Displays: 08:51:21 INFO - Display: 08:51:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:21 INFO - Display Serial Number: 1600x1200 60 08:51:21 INFO - Main Display: Yes 08:51:21 INFO - Mirror: Off 08:51:21 INFO - Online: Yes 08:51:21 INFO - Rotation: Supported 08:51:21 INFO - Return code: 0 08:51:21 INFO - Running post-action listener: _resource_record_post_action 08:51:21 INFO - [mozharness: 2017-02-13 16:51:21.129362Z] Finished run-tests step (success) 08:51:21 INFO - Running post-run listener: _resource_record_post_run 08:51:21 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 39426048; Write bytes: 503513600; Read time: 386; Write time: 2193 08:51:21 INFO - TinderboxPrint: CPU usage
54.0% 08:51:21 INFO - TinderboxPrint: I/O read bytes / time
39,426,048 / 386 08:51:21 INFO - TinderboxPrint: I/O write bytes / time
503,513,600 / 2,193 08:51:21 INFO - TinderboxPrint: CPU idle
2,203.2 (46.0%) 08:51:21 INFO - TinderboxPrint: CPU system
968.2 (20.2%) 08:51:21 INFO - TinderboxPrint: CPU user
1,618.3 (33.8%) 08:51:21 INFO - TinderboxPrint: Swap in / out
230,543,360 / 0 08:51:21 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 192461312; Write bytes: 194889216; Read time: 17830; Write time: 461 08:51:21 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35198976; Write bytes: 307080192; Read time: 353; Write time: 1725 08:51:21 INFO - Running post-run listener: _upload_blobber_files 08:51:21 INFO - Blob upload gear active. 08:51:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:51:21 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 08:51:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:51:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:51:22 INFO - (blobuploader) - INFO - Open directory for files ... 08:51:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:51:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:51:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:23 INFO - (blobuploader) - INFO - Done attempting. 08:51:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:51:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:51:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:24 INFO - (blobuploader) - INFO - Done attempting. 08:51:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:51:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:51:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:26 INFO - (blobuploader) - INFO - Done attempting. 08:51:26 INFO - (blobuploader) - INFO - Iteration through files over. 08:51:26 INFO - Return code: 0 08:51:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:51:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:51:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1d99c807b095c5b5a9661093861e93a28057abd629916c64e3701055f5a36732ced6288d919bfe13fee5d4703e8553bf111379fca35a7cc6813f52d40bbd3c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3879f4d247de603cdf954005108118cb2d92c3ce0cbe5e2c97ad926bcd31c0229c36bcf695ac872d048bddc3dab3b642b3332148711c09fa073f29dbe670344f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93e71fd403b7a214875f825fbd5060ce0264d2f90a40d0e833b49e80b5e7eda84c8e199afb251bcc1a1339c2dae9d40399a5da5fab8ce6aeb374ee1a8efa524c"} 08:51:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:51:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:51:26 INFO - Contents: 08:51:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1d99c807b095c5b5a9661093861e93a28057abd629916c64e3701055f5a36732ced6288d919bfe13fee5d4703e8553bf111379fca35a7cc6813f52d40bbd3c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3879f4d247de603cdf954005108118cb2d92c3ce0cbe5e2c97ad926bcd31c0229c36bcf695ac872d048bddc3dab3b642b3332148711c09fa073f29dbe670344f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93e71fd403b7a214875f825fbd5060ce0264d2f90a40d0e833b49e80b5e7eda84c8e199afb251bcc1a1339c2dae9d40399a5da5fab8ce6aeb374ee1a8efa524c"} 08:51:26 INFO - Running post-run listener: copy_logs_to_upload_dir 08:51:26 INFO - Copying logs to upload dir... 08:51:26 INFO - mkdir: /builds/slave/test/build/upload/logs 08:51:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.586997 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-13 08:51:26.245117) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-13 08:51:26.248975) ========= 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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1d99c807b095c5b5a9661093861e93a28057abd629916c64e3701055f5a36732ced6288d919bfe13fee5d4703e8553bf111379fca35a7cc6813f52d40bbd3c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3879f4d247de603cdf954005108118cb2d92c3ce0cbe5e2c97ad926bcd31c0229c36bcf695ac872d048bddc3dab3b642b3332148711c09fa073f29dbe670344f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93e71fd403b7a214875f825fbd5060ce0264d2f90a40d0e833b49e80b5e7eda84c8e199afb251bcc1a1339c2dae9d40399a5da5fab8ce6aeb374ee1a8efa524c"} build_url:https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011067 build_url: 'https://queue.taskcluster.net/v1/task/OyT8oZYMTLqYfXdtQ8dntA/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1d99c807b095c5b5a9661093861e93a28057abd629916c64e3701055f5a36732ced6288d919bfe13fee5d4703e8553bf111379fca35a7cc6813f52d40bbd3c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3879f4d247de603cdf954005108118cb2d92c3ce0cbe5e2c97ad926bcd31c0229c36bcf695ac872d048bddc3dab3b642b3332148711c09fa073f29dbe670344f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93e71fd403b7a214875f825fbd5060ce0264d2f90a40d0e833b49e80b5e7eda84c8e199afb251bcc1a1339c2dae9d40399a5da5fab8ce6aeb374ee1a8efa524c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-13 08:51:26.294205) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:51:26.294778) ========= 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.PtmWQ9Uvcd/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.GzyE8yAcD0/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.005056 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:51:26.345782) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-13 08:51:26.346240) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-13 08:51:26.346784) ========= ========= Total master_lag: 0.27 =========