builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0298 starttime: 1480951936.74 results: success (0) buildid: 20161205062639 builduid: a5330a2e4c1b484f8f6d8f5cf1e2061e revision: 15a86e8b45cc6840917edb57f259f6fb4f94c1fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:16.743445) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:16.743888) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:16.744174) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:16.771646) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:16.772034) ========= 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.R9E3chBSez/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.gzwZSHiIlx/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-05 07:32:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.3M=0.001s 2016-12-05 07:32:17 (19.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.597750 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:17.393752) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:17.394076) ========= 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.R9E3chBSez/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.gzwZSHiIlx/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.025243 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:17.443084) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-05 07:32:17.443460) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 15a86e8b45cc6840917edb57f259f6fb4f94c1fa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 15a86e8b45cc6840917edb57f259f6fb4f94c1fa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R9E3chBSez/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.gzwZSHiIlx/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-05 07:32:17,541 truncating revision to first 12 chars 2016-12-05 07:32:17,542 Setting DEBUG logging. 2016-12-05 07:32:17,542 attempt 1/10 2016-12-05 07:32:17,542 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/15a86e8b45cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-05 07:32:19,700 unpacking tar archive at: mozilla-aurora-15a86e8b45cc/testing/mozharness/ program finished with exit code 0 elapsedTime=2.438899 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-05 07:32:19.906092) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:19.906416) ========= script_repo_revision: 15a86e8b45cc6840917edb57f259f6fb4f94c1fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:19.906790) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:19.907068) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 07:32:19.926608) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-05 07:32:19.926958) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R9E3chBSez/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.gzwZSHiIlx/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:32:20 INFO - MultiFileLogger online at 20161205 07:32:20 in /builds/slave/test 07:32:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:32:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:32:20 INFO - {'append_to_log': False, 07:32:20 INFO - 'base_work_dir': '/builds/slave/test', 07:32:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:32:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:32:20 INFO - 'branch': 'Mozilla-Aurora', 07:32:20 INFO - 'buildbot_json_path': 'buildprops.json', 07:32:20 INFO - 'config_files': ('talos/mac_config.py',), 07:32:20 INFO - 'default_actions': ('clobber', 07:32:20 INFO - 'read-buildbot-config', 07:32:20 INFO - 'download-and-extract', 07:32:20 INFO - 'populate-webroot', 07:32:20 INFO - 'create-virtualenv', 07:32:20 INFO - 'install', 07:32:20 INFO - 'run-tests'), 07:32:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:32:20 INFO - 'download_minidump_stackwalk': True, 07:32:20 INFO - 'download_symbols': 'ondemand', 07:32:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:32:20 INFO - 'tooltool.py': '/tools/tooltool.py', 07:32:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:32:20 INFO - '/tools/misc-python/virtualenv.py')}, 07:32:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:32:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:32:20 INFO - 'installer_path': 'installer.exe', 07:32:20 INFO - 'log_level': 'info', 07:32:20 INFO - 'log_name': 'talos', 07:32:20 INFO - 'log_to_console': True, 07:32:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:32:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:32:20 INFO - 'opt_config_files': (), 07:32:20 INFO - 'pip_index': False, 07:32:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:20 INFO - 'cmd': ('bash', 07:32:20 INFO - '-c', 07:32:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:20 INFO - 'enabled': True, 07:32:20 INFO - 'halt_on_failure': False, 07:32:20 INFO - 'name': 'check_screen_resolution'},), 07:32:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:20 INFO - 'cmd': ('bash', 07:32:20 INFO - '-c', 07:32:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:20 INFO - 'enabled': True, 07:32:20 INFO - 'halt_on_failure': False, 07:32:20 INFO - 'name': 'check_screen_resolution'},), 07:32:20 INFO - 'run_cmd_checks_enabled': True, 07:32:20 INFO - 'sps_profile': False, 07:32:20 INFO - 'sps_profile_interval': 0, 07:32:20 INFO - 'suite': 'dromaeojs-e10s', 07:32:20 INFO - 'system_bits': '32', 07:32:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:32:20 INFO - 'title': 't-yosemite-r7-0298', 07:32:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:32:20 INFO - 'use_talos_json': True, 07:32:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:32:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:32:20 INFO - 'work_dir': 'build'} 07:32:20 INFO - [mozharness: 2016-12-05 15:32:20.132380Z] Running clobber step. 07:32:20 INFO - Running pre-action listener: _resource_record_pre_action 07:32:20 INFO - Running main action method: clobber 07:32:20 INFO - rmtree: /builds/slave/test/build 07:32:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:32:22 INFO - Running post-action listener: _resource_record_post_action 07:32:22 INFO - [mozharness: 2016-12-05 15:32:22.384201Z] Finished clobber step (success) 07:32:22 INFO - [mozharness: 2016-12-05 15:32:22.384384Z] Running read-buildbot-config step. 07:32:22 INFO - Running pre-action listener: _resource_record_pre_action 07:32:22 INFO - Running main action method: read_buildbot_config 07:32:22 INFO - Using buildbot properties: 07:32:22 INFO - { 07:32:22 INFO - "project": "", 07:32:22 INFO - "product": "firefox", 07:32:22 INFO - "script_repo_revision": "production", 07:32:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:32:22 INFO - "repository": "", 07:32:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:32:22 INFO - "buildid": "20161205062639", 07:32:22 INFO - "pgo_build": "False", 07:32:22 INFO - "basedir": "/builds/slave/test", 07:32:22 INFO - "buildnumber": 44, 07:32:22 INFO - "slavename": "t-yosemite-r7-0298", 07:32:22 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:32:22 INFO - "platform": "yosemite_r7", 07:32:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:32:22 INFO - "branch": "mozilla-aurora", 07:32:22 INFO - "repo_path": "releases/mozilla-aurora", 07:32:22 INFO - "revision": "15a86e8b45cc6840917edb57f259f6fb4f94c1fa", 07:32:22 INFO - "stage_platform": "macosx64", 07:32:22 INFO - "builduid": "a5330a2e4c1b484f8f6d8f5cf1e2061e", 07:32:22 INFO - "slavebuilddir": "test" 07:32:22 INFO - } 07:32:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:32:22 INFO - Running post-action listener: _resource_record_post_action 07:32:22 INFO - [mozharness: 2016-12-05 15:32:22.387818Z] Finished read-buildbot-config step (success) 07:32:22 INFO - [mozharness: 2016-12-05 15:32:22.387920Z] Running download-and-extract step. 07:32:22 INFO - Running pre-action listener: _resource_record_pre_action 07:32:22 INFO - Running main action method: download_and_extract 07:32:22 INFO - mkdir: /builds/slave/test/build/tests 07:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:22 INFO - trying https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:32:22 INFO - Downloading https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:32:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 07:32:23 INFO - Downloaded 1199 bytes. 07:32:23 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:32:23 INFO - Using the following test package requirements: 07:32:23 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:32:23 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:23 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:32:23 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:23 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:32:23 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:23 INFO - u'jsshell-mac.zip'], 07:32:23 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:23 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:32:23 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:32:23 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:23 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:32:23 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:23 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:32:23 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:23 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:32:23 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:23 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:32:23 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:32:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:32:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:32:23 INFO - Fetch https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:32:23 INFO - Http code: 200 07:32:23 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 07:32:23 INFO - x-amz-cf-id: 5DiZD3Szbx78WwGbvdkjA5fnEoXZltYyzUrlHXoQ8alYqAfSKAgExw== 07:32:23 INFO - x-amz-version-id: 3EH3GNqek1TvaohG5k9NlZBBBlKID.p6 07:32:24 INFO - Expected file size: 16281894 07:32:24 INFO - Obtained file size: 16281894 07:32:30 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:32:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:32:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:32:30 INFO - Fetch https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:32:31 INFO - Http code: 200 07:32:31 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 07:32:31 INFO - x-amz-cf-id: KOk7HEqaQXbLyoRADNTu6tlwVOyqWD_BxBNe5NnnDBAd3wWrSz4H7w== 07:32:31 INFO - x-amz-version-id: 3EH3GNqek1TvaohG5k9NlZBBBlKID.p6 07:32:31 INFO - Expected file size: 16281894 07:32:31 INFO - Obtained file size: 16281894 07:32:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:32:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:32:38 INFO - Fetch https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:32:39 INFO - Http code: 200 07:32:39 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 07:32:39 INFO - x-amz-cf-id: j1czcw7Nfh6p0PIz1OtUQU6zWySrOKuBO2PuxltLScqPRyytZnyivA== 07:32:39 INFO - x-amz-version-id: uByEskOXo9UJXI8SPzLZ966bDH2hrYGi 07:32:39 INFO - Expected file size: 14409038 07:32:39 INFO - Obtained file size: 14409038 07:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:39 INFO - trying https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:39 INFO - Downloading https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:32:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 07:32:42 INFO - Downloaded 109760198 bytes. 07:32:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:42 INFO - mkdir: /builds/slave/test/properties 07:32:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:32:42 INFO - Writing to file /builds/slave/test/properties/build_url 07:32:42 INFO - Contents: 07:32:42 INFO - build_url:https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:43 INFO - Running post-action listener: _resource_record_post_action 07:32:43 INFO - Running post-action listener: set_extra_try_arguments 07:32:43 INFO - [mozharness: 2016-12-05 15:32:43.195953Z] Finished download-and-extract step (success) 07:32:43 INFO - [mozharness: 2016-12-05 15:32:43.196089Z] Running populate-webroot step. 07:32:43 INFO - Running pre-action listener: _resource_record_pre_action 07:32:43 INFO - Running main action method: populate_webroot 07:32:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:32:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:32:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:32:43 INFO - '--tppagecycles', 07:32:43 INFO - '7'], 07:32:43 INFO - 'tests': ['tsvgm']}}, 07:32:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:32:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:32:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:32:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:32:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:43 INFO - 'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:43 INFO - 'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['damp', 'tps']}, 07:32:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:43 INFO - 'tests': ['damp', 'tps']}, 07:32:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['dromaeo_dom']}, 07:32:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:32:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['basic_compositor_video']}, 07:32:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:32:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['a11yr', 07:32:43 INFO - 'ts_paint', 07:32:43 INFO - 'tpaint', 07:32:43 INFO - 'sessionrestore', 07:32:43 INFO - 'sessionrestore_no_auto_restore', 07:32:43 INFO - 'tabpaint']}, 07:32:43 INFO - 'other-e10s': {'tests': ['a11yr', 07:32:43 INFO - 'ts_paint', 07:32:43 INFO - 'tpaint', 07:32:43 INFO - 'sessionrestore', 07:32:43 INFO - 'sessionrestore_no_auto_restore', 07:32:43 INFO - 'tabpaint']}, 07:32:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:32:43 INFO - 'ts_paint', 07:32:43 INFO - 'tpaint', 07:32:43 INFO - 'sessionrestore', 07:32:43 INFO - 'sessionrestore_no_auto_restore', 07:32:43 INFO - 'tabpaint']}, 07:32:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:32:43 INFO - 'ts_paint', 07:32:43 INFO - 'tpaint', 07:32:43 INFO - 'sessionrestore', 07:32:43 INFO - 'sessionrestore_no_auto_restore', 07:32:43 INFO - 'tabpaint']}, 07:32:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['a11yr', 07:32:43 INFO - 'ts_paint', 07:32:43 INFO - 'tpaint', 07:32:43 INFO - 'sessionrestore', 07:32:43 INFO - 'sessionrestore_no_auto_restore', 07:32:43 INFO - 'tabpaint']}, 07:32:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['a11yr', 07:32:43 INFO - 'ts_paint', 07:32:43 INFO - 'tpaint', 07:32:43 INFO - 'sessionrestore', 07:32:43 INFO - 'sessionrestore_no_auto_restore', 07:32:43 INFO - 'tabpaint']}, 07:32:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['tsvgx', 07:32:43 INFO - 'tsvgr_opacity', 07:32:43 INFO - 'tart', 07:32:43 INFO - 'tscrollx', 07:32:43 INFO - 'cart']}, 07:32:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:32:43 INFO - 'tsvgr_opacity', 07:32:43 INFO - 'tart', 07:32:43 INFO - 'tscrollx', 07:32:43 INFO - 'cart']}, 07:32:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:43 INFO - 'talos_options': ['--disable-e10s'], 07:32:43 INFO - 'tests': ['tp5o']}, 07:32:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:43 INFO - 'tests': ['tp5o']}, 07:32:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:43 INFO - 'talos_options': ['--disable-e10s', 07:32:43 INFO - '--xperf_path', 07:32:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:43 INFO - 'tests': ['tp5n']}, 07:32:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:43 INFO - 'talos_options': ['--xperf_path', 07:32:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:43 INFO - 'tests': ['tp5n']}}, 07:32:43 INFO - 'talos.zip': {'path': '', 07:32:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:32:43 INFO - Running post-action listener: _resource_record_post_action 07:32:43 INFO - [mozharness: 2016-12-05 15:32:43.215076Z] Finished populate-webroot step (success) 07:32:43 INFO - [mozharness: 2016-12-05 15:32:43.215187Z] Running create-virtualenv step. 07:32:43 INFO - Running pre-action listener: _resource_record_pre_action 07:32:43 INFO - Running main action method: create_virtualenv 07:32:43 INFO - Creating virtualenv /builds/slave/test/build/venv 07:32:43 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 07:32:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:32:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:32:43 INFO - Using real prefix '/tools/python27' 07:32:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:32:44 INFO - Installing distribute.............................................................................................................................................................................................done. 07:32:46 INFO - Installing pip.................done. 07:32:46 INFO - Return code: 0 07:32:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:32:47 INFO - Reading from file tmpfile_stdout 07:32:47 INFO - Output received: 07:32:47 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) 07:32:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:47 INFO - 'HOME': '/Users/cltbld', 07:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:47 INFO - 'LOGNAME': 'cltbld', 07:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:47 INFO - 'MOZ_NO_REMOTE': '1', 07:32:47 INFO - 'NO_EM_RESTART': '1', 07:32:47 INFO - 'PAGER': '/bin/cat', 07:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:47 INFO - 'PWD': '/builds/slave/test', 07:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:47 INFO - 'SHELL': '/bin/bash', 07:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:32:47 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:47 INFO - 'USER': 'cltbld', 07:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:47 INFO - 'XPC_FLAGS': '0x0', 07:32:47 INFO - 'XPC_SERVICE_NAME': '0', 07:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:47 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 07:32:47 INFO - Cleaning up... 07:32:47 INFO - Return code: 0 07:32:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:47 INFO - 'HOME': '/Users/cltbld', 07:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:47 INFO - 'LOGNAME': 'cltbld', 07:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:47 INFO - 'MOZ_NO_REMOTE': '1', 07:32:47 INFO - 'NO_EM_RESTART': '1', 07:32:47 INFO - 'PAGER': '/bin/cat', 07:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:47 INFO - 'PWD': '/builds/slave/test', 07:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:47 INFO - 'SHELL': '/bin/bash', 07:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:32:47 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:47 INFO - 'USER': 'cltbld', 07:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:47 INFO - 'XPC_FLAGS': '0x0', 07:32:47 INFO - 'XPC_SERVICE_NAME': '0', 07:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:47 INFO - Downloading/unpacking psutil>=3.1.1 07:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:32:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:51 INFO - Installing collected packages: psutil 07:32:51 INFO - Running setup.py install for psutil 07:32:51 INFO - building 'psutil._psutil_osx' extension 07:32:51 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 07:32:52 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 07:32:52 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 07:32:52 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 07:32:52 INFO - building 'psutil._psutil_posix' extension 07:32:52 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 07:32:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:32:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:32:52 INFO - ^ 07:32:52 INFO - 1 warning generated. 07:32:52 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 07:32:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:52 INFO - Successfully installed psutil 07:32:52 INFO - Cleaning up... 07:32:52 INFO - Return code: 0 07:32:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:32:52 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')]} 07:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:52 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')]} 07:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:52 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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:32:52 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 07:32:52 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 07:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:52 INFO - 'HOME': '/Users/cltbld', 07:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:52 INFO - 'LOGNAME': 'cltbld', 07:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:52 INFO - 'MOZ_NO_REMOTE': '1', 07:32:52 INFO - 'NO_EM_RESTART': '1', 07:32:52 INFO - 'PAGER': '/bin/cat', 07:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:52 INFO - 'PWD': '/builds/slave/test', 07:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:52 INFO - 'SHELL': '/bin/bash', 07:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:32:52 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:52 INFO - 'USER': 'cltbld', 07:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:52 INFO - 'XPC_FLAGS': '0x0', 07:32:52 INFO - 'XPC_SERVICE_NAME': '0', 07:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:32:55 INFO - Installing collected packages: mozsystemmonitor 07:32:55 INFO - Running setup.py install for mozsystemmonitor 07:32:55 INFO - Successfully installed mozsystemmonitor 07:32:55 INFO - Cleaning up... 07:32:56 INFO - Return code: 0 07:32:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:56 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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:32:56 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 07:32:56 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 07:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:56 INFO - 'HOME': '/Users/cltbld', 07:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:56 INFO - 'LOGNAME': 'cltbld', 07:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:56 INFO - 'MOZ_NO_REMOTE': '1', 07:32:56 INFO - 'NO_EM_RESTART': '1', 07:32:56 INFO - 'PAGER': '/bin/cat', 07:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:56 INFO - 'PWD': '/builds/slave/test', 07:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:56 INFO - 'SHELL': '/bin/bash', 07:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:32:56 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:56 INFO - 'USER': 'cltbld', 07:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:56 INFO - 'XPC_FLAGS': '0x0', 07:32:56 INFO - 'XPC_SERVICE_NAME': '0', 07:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:56 INFO - Downloading/unpacking jsonschema==2.5.1 07:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:32:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:59 INFO - Downloading functools32-3.2.3-2.tar.gz 07:32:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:32:59 INFO - warning: no files found matching '*.txt' 07:32:59 INFO - no previously-included directories found matching 'build' 07:32:59 INFO - no previously-included directories found matching 'dist' 07:32:59 INFO - no previously-included directories found matching '.git*' 07:32:59 INFO - Installing collected packages: jsonschema, functools32 07:32:59 INFO - Running setup.py install for functools32 07:32:59 INFO - warning: no files found matching '*.txt' 07:32:59 INFO - no previously-included directories found matching 'build' 07:32:59 INFO - no previously-included directories found matching 'dist' 07:32:59 INFO - no previously-included directories found matching '.git*' 07:32:59 INFO - Successfully installed jsonschema functools32 07:32:59 INFO - Cleaning up... 07:32:59 INFO - Return code: 0 07:32:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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', '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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:32: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:32: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 functools32==3.2.3-2 07:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:59 INFO - 'HOME': '/Users/cltbld', 07:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:59 INFO - 'LOGNAME': 'cltbld', 07:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:59 INFO - 'MOZ_NO_REMOTE': '1', 07:32:59 INFO - 'NO_EM_RESTART': '1', 07:32:59 INFO - 'PAGER': '/bin/cat', 07:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:59 INFO - 'PWD': '/builds/slave/test', 07:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:59 INFO - 'SHELL': '/bin/bash', 07:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:32:59 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:59 INFO - 'USER': 'cltbld', 07:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:59 INFO - 'XPC_FLAGS': '0x0', 07:32:59 INFO - 'XPC_SERVICE_NAME': '0', 07:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:32:59 INFO - Cleaning up... 07:32:59 INFO - Return code: 0 07:32:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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', '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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:32: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 blobuploader==1.2.4 07:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:59 INFO - 'HOME': '/Users/cltbld', 07:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:59 INFO - 'LOGNAME': 'cltbld', 07:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:59 INFO - 'MOZ_NO_REMOTE': '1', 07:32:59 INFO - 'NO_EM_RESTART': '1', 07:32:59 INFO - 'PAGER': '/bin/cat', 07:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:59 INFO - 'PWD': '/builds/slave/test', 07:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:59 INFO - 'SHELL': '/bin/bash', 07:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:32:59 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:59 INFO - 'USER': 'cltbld', 07:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:59 INFO - 'XPC_FLAGS': '0x0', 07:32:59 INFO - 'XPC_SERVICE_NAME': '0', 07:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:00 INFO - Downloading/unpacking blobuploader==1.2.4 07:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:02 INFO - Downloading blobuploader-1.2.4.tar.gz 07:33:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:33:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:33:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:33: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 07:33: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 07:33: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 07:33: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 07:33: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 07:33: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 07:33:03 INFO - Downloading docopt-0.6.1.tar.gz 07:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:33:04 INFO - Installing collected packages: blobuploader, requests, docopt 07:33:04 INFO - Running setup.py install for blobuploader 07:33:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:33:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:33:04 INFO - Running setup.py install for requests 07:33:04 INFO - Running setup.py install for docopt 07:33:04 INFO - Successfully installed blobuploader requests docopt 07:33:04 INFO - Cleaning up... 07:33:04 INFO - Return code: 0 07:33:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:04 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')]} 07:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:04 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')]} 07:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:04 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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:33:04 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 07:33:04 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 07:33:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:33:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:04 INFO - 'HOME': '/Users/cltbld', 07:33:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:04 INFO - 'LOGNAME': 'cltbld', 07:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:04 INFO - 'MOZ_NO_REMOTE': '1', 07:33:04 INFO - 'NO_EM_RESTART': '1', 07:33:04 INFO - 'PAGER': '/bin/cat', 07:33:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:04 INFO - 'PWD': '/builds/slave/test', 07:33:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:04 INFO - 'SHELL': '/bin/bash', 07:33:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:33:04 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:33:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:04 INFO - 'USER': 'cltbld', 07:33:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:04 INFO - 'XPC_FLAGS': '0x0', 07:33:04 INFO - 'XPC_SERVICE_NAME': '0', 07:33:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:33:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-eEkbJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:33:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-sw7bH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:33:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Ev_uIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:33:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-u58VXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:33:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-UcJ0jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:33:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-CRIP46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:33:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-XfX2ZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:33:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ArS6ES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-aq1D7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ym4VV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-UqzxKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-EZZJV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-8g8HoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-RO8cMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-MrlPEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-BXsh9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:33:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-BhcA1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:33:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:33:06 INFO - Running setup.py install for manifestparser 07:33:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:33:07 INFO - Running setup.py install for mozcrash 07:33:07 INFO - Running setup.py install for mozdebug 07:33:07 INFO - Running setup.py install for mozdevice 07:33:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:33:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:33:07 INFO - Running setup.py install for mozfile 07:33:07 INFO - Running setup.py install for mozhttpd 07:33:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:33:07 INFO - Running setup.py install for mozinfo 07:33:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Running setup.py install for mozInstall 07:33:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Running setup.py install for mozleak 07:33:08 INFO - Running setup.py install for mozlog 07:33:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Running setup.py install for moznetwork 07:33:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Running setup.py install for mozprocess 07:33:08 INFO - Running setup.py install for mozprofile 07:33:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:33:08 INFO - Running setup.py install for mozrunner 07:33:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:33:09 INFO - Running setup.py install for mozscreenshot 07:33:09 INFO - Running setup.py install for moztest 07:33:09 INFO - Running setup.py install for mozversion 07:33:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:33:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:33:09 INFO - Cleaning up... 07:33:09 INFO - Return code: 0 07:33:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:09 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')]} 07:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:09 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')]} 07:33:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:09 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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:33:09 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 07:33:09 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 07:33:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:33:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:09 INFO - 'HOME': '/Users/cltbld', 07:33:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:09 INFO - 'LOGNAME': 'cltbld', 07:33:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:09 INFO - 'MOZ_NO_REMOTE': '1', 07:33:09 INFO - 'NO_EM_RESTART': '1', 07:33:09 INFO - 'PAGER': '/bin/cat', 07:33:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:09 INFO - 'PWD': '/builds/slave/test', 07:33:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:09 INFO - 'SHELL': '/bin/bash', 07:33:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:33:09 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:33:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:09 INFO - 'USER': 'cltbld', 07:33:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:09 INFO - 'XPC_FLAGS': '0x0', 07:33:09 INFO - 'XPC_SERVICE_NAME': '0', 07:33:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:33:09 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-eon3Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:33:09 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)) 07:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:33:09 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-SMdsjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-U70amn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-zMNrf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-3k1rkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-WNkGNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-uT7ZYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-pkphCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-U7zJMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-bB1uAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:33:10 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)) 07:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:33:10 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-CQnhZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:33:11 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)) 07:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:33:11 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-4mb9nC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:33:11 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)) 07:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:33:11 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-iewE8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:33:11 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)) 07:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:33:11 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-DzQ4mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:33:11 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)) 07:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:11 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ww2T2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:11 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)) 07:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:33:11 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Nf_qWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:33:11 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-5m4Zah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:33:11 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)) 07:33:11 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)) 07:33:11 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)) 07:33:11 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)) 07:33:11 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)) 07:33:11 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)) 07:33:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:33:11 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 07:33:11 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 07:33:11 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 07:33:11 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 07:33:11 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 07:33:11 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 07:33:14 INFO - Downloading blessings-1.6.tar.gz 07:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:33:14 INFO - Installing collected packages: blessings 07:33:14 INFO - Running setup.py install for blessings 07:33:14 INFO - Successfully installed blessings 07:33:14 INFO - Cleaning up... 07:33:14 INFO - Return code: 0 07:33:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:33:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:14 INFO - Reading from file tmpfile_stdout 07:33:14 INFO - Current package versions: 07:33:14 INFO - blessings == 1.6 07:33:14 INFO - blobuploader == 1.2.4 07:33:14 INFO - docopt == 0.6.1 07:33:14 INFO - functools32 == 3.2.3-2 07:33:14 INFO - jsonschema == 2.5.1 07:33:14 INFO - manifestparser == 1.1 07:33:14 INFO - mozInstall == 1.12 07:33:14 INFO - mozcrash == 1.0 07:33:14 INFO - mozdebug == 0.1 07:33:14 INFO - mozdevice == 0.48 07:33:14 INFO - mozfile == 1.2 07:33:14 INFO - mozhttpd == 0.7 07:33:14 INFO - mozinfo == 0.9 07:33:14 INFO - mozleak == 0.1 07:33:14 INFO - mozlog == 3.4 07:33:14 INFO - moznetwork == 0.27 07:33:14 INFO - mozprocess == 0.23 07:33:14 INFO - mozprofile == 0.28 07:33:14 INFO - mozrunner == 6.13 07:33:14 INFO - mozscreenshot == 0.1 07:33:14 INFO - mozsystemmonitor == 0.3 07:33:14 INFO - moztest == 0.7 07:33:14 INFO - mozversion == 1.4 07:33:14 INFO - psutil == 3.1.1 07:33:14 INFO - requests == 1.2.3 07:33:14 INFO - wsgiref == 0.1.2 07:33:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:14 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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:33:14 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 07:33:14 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 07:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:14 INFO - 'HOME': '/Users/cltbld', 07:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:14 INFO - 'LOGNAME': 'cltbld', 07:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:14 INFO - 'MOZ_NO_REMOTE': '1', 07:33:14 INFO - 'NO_EM_RESTART': '1', 07:33:14 INFO - 'PAGER': '/bin/cat', 07:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:14 INFO - 'PWD': '/builds/slave/test', 07:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:14 INFO - 'SHELL': '/bin/bash', 07:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:33:14 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:14 INFO - 'USER': 'cltbld', 07:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:14 INFO - 'XPC_FLAGS': '0x0', 07:33:14 INFO - 'XPC_SERVICE_NAME': '0', 07:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 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)) 07:33:15 INFO - Cleaning up... 07:33:15 INFO - Return code: 0 07:33:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:33:15 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')]} 07:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:15 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')]} 07:33:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:15 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 0x102033490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb363d5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, '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.R9E3chBSez/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.gzwZSHiIlx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 07:33:15 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 07:33:15 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 07:33:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:33:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:15 INFO - 'HOME': '/Users/cltbld', 07:33:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:15 INFO - 'LOGNAME': 'cltbld', 07:33:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:15 INFO - 'MOZ_NO_REMOTE': '1', 07:33:15 INFO - 'NO_EM_RESTART': '1', 07:33:15 INFO - 'PAGER': '/bin/cat', 07:33:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:15 INFO - 'PWD': '/builds/slave/test', 07:33:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:15 INFO - 'SHELL': '/bin/bash', 07:33:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:33:15 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:33:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:15 INFO - 'USER': 'cltbld', 07:33:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:15 INFO - 'XPC_FLAGS': '0x0', 07:33:15 INFO - 'XPC_SERVICE_NAME': '0', 07:33:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:33:15 INFO - Cleaning up... 07:33:15 INFO - Return code: 0 07:33:15 INFO - Running post-action listener: _resource_record_post_action 07:33:15 INFO - Running post-action listener: _start_resource_monitoring 07:33:15 INFO - Starting resource monitoring. 07:33:15 INFO - [mozharness: 2016-12-05 15:33:15.443675Z] Finished create-virtualenv step (success) 07:33:15 INFO - [mozharness: 2016-12-05 15:33:15.444750Z] Running install step. 07:33:15 INFO - Running pre-action listener: _resource_record_pre_action 07:33:15 INFO - Running main action method: install 07:33:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:15 INFO - Reading from file tmpfile_stdout 07:33:15 INFO - Detecting whether we're running mozinstall >=1.0... 07:33:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:33:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:33:15 INFO - Reading from file tmpfile_stdout 07:33:15 INFO - Output received: 07:33:15 INFO - Usage: mozinstall [options] installer 07:33:15 INFO - Options: 07:33:15 INFO - -h, --help show this help message and exit 07:33:15 INFO - -d DEST, --destination=DEST 07:33:15 INFO - Directory to install application into. [default: 07:33:15 INFO - "/builds/slave/test"] 07:33:15 INFO - --app=APP Application being installed. [default: firefox] 07:33:15 INFO - mkdir: /builds/slave/test/build/application 07:33:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:33:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:33:47 INFO - Reading from file tmpfile_stdout 07:33:47 INFO - Output received: 07:33:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:33:47 INFO - Running post-action listener: _resource_record_post_action 07:33:47 INFO - [mozharness: 2016-12-05 15:33:47.520147Z] Finished install step (success) 07:33:47 INFO - [mozharness: 2016-12-05 15:33:47.520334Z] Running run-tests step. 07:33:47 INFO - Running pre-action listener: _resource_record_pre_action 07:33:47 INFO - Running main action method: run_tests 07:33:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:33:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:33:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:33:47 INFO - 2016-12-05 07:33:47.535 screenresolution[1636:8334] starting screenresolution argv=screenresolution get 07:33:47 INFO - 2016-12-05 07:33:47.537 screenresolution[1636:8334] Display 0: 1600x1200x32@60 07:33:47 INFO - 2016-12-05 07:33:47.543 screenresolution[1637:8336] starting screenresolution argv=screenresolution list 07:33:47 INFO - Available Modes on Display 0 07:33:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:33:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:33:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:33:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:33:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:33:47 INFO - Intel Iris: 07:33:47 INFO - Chipset Model: Intel Iris 07:33:47 INFO - Type: GPU 07:33:47 INFO - Bus: Built-In 07:33:47 INFO - VRAM (Dynamic, Max): 1536 MB 07:33:47 INFO - Vendor: Intel (0x8086) 07:33:47 INFO - Device ID: 0x0a2e 07:33:47 INFO - Revision ID: 0x0009 07:33:47 INFO - Displays: 07:33:47 INFO - Display: 07:33:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:33:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:33:47 INFO - Display Serial Number: 1600x1200 60 07:33:47 INFO - Main Display: Yes 07:33:47 INFO - Mirror: Off 07:33:47 INFO - Online: Yes 07:33:47 INFO - Rotation: Supported 07:33:47 INFO - Return code: 0 07:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:33:47 INFO - Python 2.7.3 07:33:47 INFO - Return code: 0 07:33:47 INFO - grabbing minidump binary from tooltool 07:33: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')]} 07:33:47 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e47d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e3fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:33:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:33:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:33:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:33:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:33:47 INFO - Return code: 0 07:33:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:33:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:33:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:33:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:33:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 07:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0298 --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 07:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9E3chBSez/Render', 07:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:47 INFO - 'HOME': '/Users/cltbld', 07:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:47 INFO - 'LOGNAME': 'cltbld', 07:33:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:33:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:47 INFO - 'MOZ_NO_REMOTE': '1', 07:33:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:33:47 INFO - 'NO_EM_RESTART': '1', 07:33:47 INFO - 'PAGER': '/bin/cat', 07:33:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:47 INFO - 'PWD': '/builds/slave/test', 07:33:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:47 INFO - 'SHELL': '/bin/bash', 07:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzwZSHiIlx/Listeners', 07:33:47 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 07:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:47 INFO - 'USER': 'cltbld', 07:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:47 INFO - 'XPC_FLAGS': '0x0', 07:33:47 INFO - 'XPC_SERVICE_NAME': '0', 07:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 07:33:48 INFO - mozversion application_buildid: 20161205062639 07:33:48 INFO - mozversion application_changeset: 15a86e8b45cc6840917edb57f259f6fb4f94c1fa 07:33:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:33:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:33:48 INFO - mozversion application_name: Firefox 07:33:48 INFO - mozversion application_remotingname: firefox-dev 07:33:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:33:48 INFO - mozversion application_vendor: Mozilla 07:33:48 INFO - mozversion application_version: 52.0a2 07:33:48 INFO - mozversion platform_buildid: 20161205062639 07:33:48 INFO - mozversion platform_changeset: 15a86e8b45cc6840917edb57f259f6fb4f94c1fa 07:33:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:33:48 INFO - mozversion platform_version: 52.0a2 07:33:48 INFO - using testdate: 1480952028 07:33:48 INFO - actual date: 1480952028 07:33:48 INFO - starting webserver on 'localhost:49242' 07:33:48 INFO - SUITE-START | Running 2 tests 07:33:48 INFO - TEST-START | dromaeo_css 07:33:48 INFO - operating with platform_type : mac_ 07:33:48 INFO - Initialising browser for dromaeo_css test... 07:33:48 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpPdhkXb/profile http://localhost:49242/getInfo.html) 07:33:51 INFO - PROCESS | 1647 | 2016-12-05 07:33:51.213 plugin-container[1649:8519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x904b, name = 'com.apple.tsm.portname' 07:33:51 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:51 INFO - PROCESS | 1647 | 2016-12-05 07:33:51.217 plugin-container[1649:8519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1f, name = 'com.apple.CFPasteboardClient' 07:33:51 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:51 INFO - PROCESS | 1647 | 2016-12-05 07:33:51.217 plugin-container[1649:8519] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:51 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 07:33:51 INFO - PROCESS | 1647 | colorDepth:24 07:33:51 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 07:33:51 INFO - PROCESS | 1647 | __metrics 07:33:51 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 07:33:52 INFO - TEST-INFO | 1647: exit 0 07:33:52 INFO - Browser initialized. 07:33:52 INFO - Running cycle 1/1 for dromaeo_css test... 07:33:52 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpPdhkXb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:54 INFO - PROCESS | 1651 | 2016-12-05 07:33:54.742 plugin-container[1652:8656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 07:33:54 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:54 INFO - PROCESS | 1651 | 2016-12-05 07:33:54.743 plugin-container[1652:8656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 07:33:54 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:54 INFO - PROCESS | 1651 | 2016-12-05 07:33:54.743 plugin-container[1652:8656] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:34:04 INFO - PROCESS | 1651 | RSS: Main: 229490688 07:34:04 INFO - PROCESS | 1651 | 07:34:54 INFO - PROCESS | 1651 | 1480952094031 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:37:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 07:37:10 INFO - PROCESS | 1651 | RSS: Main: 233652224 07:37:10 INFO - PROCESS | 1651 | 07:40:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 07:40:13 INFO - PROCESS | 1651 | RSS: Main: 243236864 07:40:13 INFO - PROCESS | 1651 | 07:43:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 07:43:16 INFO - PROCESS | 1651 | RSS: Main: 246001664 07:43:16 INFO - PROCESS | 1651 | 07:46:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 07:46:18 INFO - PROCESS | 1651 | RSS: Main: 245276672 07:46:18 INFO - PROCESS | 1651 | 07:49:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 07:49:22 INFO - PROCESS | 1651 | RSS: Main: 248659968 07:49:22 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 07:52:24 INFO - PROCESS | 1651 | RSS: Main: 250548224 07:52:24 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | __start_tp_report 07:52:24 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 07:52:24 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 07:52:24 INFO - PROCESS | 1651 | |i|pagename|runs| 07:52:24 INFO - PROCESS | 1651 | |0;dojo.html;3273.590801149856;3335.5663763710713;3382.8477718502663;3391.8304084795786;3408.4716868885325;5700.800471983482;5703.543716502681;5719.370869204389;5768.134779783024;5776.566757493184;5731.426857314266;5773.595848290622;5794.855128621786;5817.767289308433;5835.35811060783;5216.113260745663;5255.5795536357;5261.815836445725;5318.547923426494;5326.440723724011;5241.842744717665;5299.311089558352;5334.359876814788;5359.5712343012465;5388.811391601294;5092.00705862357;5159.277701121846;5234.42421333653;5301.522862942354;5305.575553955713;5078.61910356721;5128.871778205575;5137.3064636274;5143.897122057538;5211.218317252443;4973;5075.959428317223;5124.5131712487255;5161.587073034186;5207.427183009866;4728.770519664912;5689.317281926154;5689.6586204827845;5753.021986262345;5777.826665200008;6220.973539365999;6256.343083976208;6266.373362663743;6279.403456671655;6309.747610095591;5433.239346491494;5490.231367608538;6204.6587437690905;6235.376462353774;6270.905936410957;5684.090545512699;5762.366139724627;5789.305283366023;5805.245318108618;5874.412558744091;5189.792408303701;6290.402411770874;6311.368863113744;6313.96843015782;6321.494280457552;6106.786262480792;6192.380761923771;6204.162438070893;6220.377962203744;6251.78118765841;6072.726727297282;6083.330833608299;6142.539309551712;6262.655553944577;6297.4647154992335;5458.536024437888;6110.984061328612;6112.205413296332;6144.709610981655;6166.321704612489;6246.781362652377;6254.3745625437095;6270.655113968775;6274.090256912722;6285.340039295899;3977.960220397817;4933.580645645088;4971.1797553403285;4987;4996.7001979881325;4384.671149663788;4388.978055109704;4407.757573333497;4436.82252709888;4499.460064792181;4820.662553621283;5284.810917543598;5657.943420565742;5866.2373891393845;5971.850703732442;6160.507159427235;6175.96912015446;6182.165407669997;6189.381061893775;6281;5873.089671100986;5890.263717035371;5930.466258036798;5956.642601443928;5962.1056841473255;5271.762770675329;5323.600729945269;5337.6797392156595;5400.540954018952;5441.809536666197;6252;6255.655938923403;6266.717997690206;6269.96865015672;6276.592021518587;6190.659513726789;6256;6286.74853005893;6314.305426403183;6317.652529110759;6362.968185159045;6367.140436041075;6375.5218358624215;6382.744690212339;6389.744410223539;6012.398760123952;6124.319907527481;6263.937360626335;6267;6283.622982621057;6178.320384757763;6219.533534984804;6232.844178895564;6252.812415627538;6269.7178626962805;6128.785492507741;6171.9074213887725;6202.193714817045;6205.565610407409;6211.472024877936;6108.938910610837;6110.694465276807;6187.257529096537;6187.690615469299;6229.314775374614;4914.582260507754;5846.152307915243;5909.054551271776;5909.320428150818;5911.970440147772;6058.969705151446;6139.447449729546;6160.229971253593;6185.072239164161;6198.690065496798;5908.113782932594;5917.437843404869;5981.192539007352;5984.9401505986125;5996.640201587918 07:52:24 INFO - PROCESS | 1651 | |1;ext.html;486.9651989522265;514.2466286889706;523.4739087217346;524.9815358205082;557.8744782423955;32220.355592888158;37261.81369093157;38230.61769382306;39396.212075758536;39435.40846887299;40179;40923.795381023054;41059.79470102653;41134.382984255186;41160.765177044734;9089.727308180765;9173.95413022934;9406.2945279104;9415.246780257597;9419.434833909952;9244.121808428188;9338.439693618404;9442.339036267464;9445.622175112987;9457.62169513222;9272.536373181347;9308.255339572817;9398.436093834393;9437.811243775155;9461.148496635302;9319.487428191447;9403.341766076377;9405.388649737779;9408.482533460658;9436.528173591361;9296.535173241311;9318.487483188423;9411.858822117674;9429.387089839138;9457.810843783085;38580.80709596462;38685.80657096697;38828;39191.21617567632;39284.41073383902;3433.8454769535433;3449.5170676105367;3451.9309613807836;3541.8583256669704;3672.3573374659327;28971.710282897322;29705.70294297051;30007.549886751716;30403.239919001982;30407.847960760053;3111.8132912025126;3169.3819705157503;3209.3902158590026;3274.1487213324467;3285.2608163163336;390539;500116;501012;501910;502891.99999999633;60314.69842650759;60566;60591;60608.393916061155;60706.69646651739;62553.68723156355;62618;62711;62985.685071575266;63205.68397157985;15638.296276667577;15967.920160399124;16142.35430582787;16171.867969242041;16195.109268990083;12180.390980451119;12337.568185113478;12348.938255308845;12428.13003089775;12433;13679.589612311647;15812.130332831804;16833.242504087346;17109.743353849703;17120.315187392614;6122.66325352101;7142.464315176278;7181.6409179540815;7205.2434494378385;7253.347198752138;871.7166920750655;927.8515437530098;965.75856035991;978.9265805064649;1066.989330106689;26056.86971565168;27768.72231277702;29736;29773.404531909673;30262.84868575643;904.7421484876778;975.2246963663904;1026.9127124194379;1039.740064983754;1044.5717255925035;13485.730285394433;15000.62498437526;15410.691786164438;15516;15649.061056336655;647098;651336;653654;654194;655710.7214463897;55837;56699.71650141723;57399.426005739406;57478.7126064367;57881;1323.1597935311147;1341.9329033548283;1361.5779108476404;1362.1146254934174;1368.9726205475836;28358.85820570884;28594.714052859206;28990.7100928988;29105.41789164247;29494;48066;48956.75521622369;49349.75325123352;49464.50535494599;49520.50479495303;597976;600209.9989500025;601003;601422;603421;38410.61589384182;38930.6106938927;39295;39407.40888886726;39482.60517394904;45459.31811022771;45578.088438231716;46068.769656151504;46472;46589.3011604833;38137;38452.80773596114;39167.804160979016;39509.40735888907;39543.45250296253;34078.65921340854;37928.241435171694;38017.85946421612;38227.61772382352;39127.60872391354;36890.893273201844;37043.814780925924;37705.62294377021;37995.43006854844;39129.80435097806;29314.85342573359;31638.841805790824;34315.65684343225;34672;35005.299894001466;25005.74994250107;25230.62154067727;25499.10753123706;25731.742682572934;26901 07:52:24 INFO - PROCESS | 1651 | |2;jquery.html;335.6442171298424;372.35210733324027;375.60936625909045;384.3120813743399;398.82651046794626;18924.05379731015;19298.807011929875;19576.608467830636;19698.507537311576;19858.007099644998;18943.81056189441;19157.90421047893;19502.609947801036;19963.10166042528;20047.699284510745;4993.6005119590345;5268.262443257938;5430.8913821723545;5432.266644003049;5856.853578660546;5697.831944451398;5760.971195144019;5786.566007549451;5810.941890581083;5818.621789583684;5348.893022139555;5538.861528461781;5708.628939118965;5732.7133643317875;5855.063189889618;5852.092925596539;5875.2949646042325;5913.733881975321;5914.142449344821;5926.555508336849;4975.651704380694;5180.378354597435;5198.792048318062;5236.057509648262;5250.81622143227;10257.512660664162;10364.429956352398;12894.871051289461;13119.868801312055;13255.536056237985;2037.2665840297482;2152.257471172443;2277.1802151225547;2295.231097582295;2321.2107883319754;9214.723558293263;10379.325343852626;10820.08029317509;12332.890039896452;14324.498642547462;1597.752348386002;1815.827496387841;1900.8479321654233;1912.693968964959;1930.1314408516084;105078.94921050845;113587.2961905572;116780.41609791896;116989;119041;11252.381119038368;11702.361669366295;11719.179657423898;11758.647240582795;11901.285922844483;11686.766264674829;14443;14657.340419680926;14675.266236688336;14689.853101468849;6976.860462790716;8438.873416898752;8477.406581539233;8579.313654907592;8601.870971935426;8093.797655058553;8245.175482451707;8251.876221856677;8313.833723325499;8367.121452247547;3983.9044262827956;4313.460817397825;4403.251447253974;4543.204939135638;4600.171969045604;1655.809581898073;1670.6324608586092;1898.1363479616741;1998.7701414337537;2001.6497113005166;860.0625318402971;882.4484697064335;926.893407258174;927.888653361601;960.6685693435893;19819;20359.287424940056;20469;20486.59026819455;20787.168513259603;854.1927878155208;909.3589019741199;939.9671011514564;1000.6397696829135;1033.1403473747225;10358.378497290187;10511.474426278655;10515.84226236607;10537.473126343806;10552.208584356204;571520;582972;583504;583774;585080;76644;97353.51323243338;108674;112720.43639781748;122459;934.9065093490597;953.6328513522344;963.1524258652481;970.3256236915418;980.6910823090847;14311.856881431053;18104.637907242046;18965.810341896406;18975.90512047431;19109.14008869632;49898.253561126636;53938;54177.56200971045;56958.14562781645;58747.11879321899;77705.2229477698;79810.20189798028;80499.59750201211;82136.58931705303;83752.58123709343;7010.649467526705;7874.409419293462;7975.4018448615425;8022.719204827803;8028;10531.473426328806;12511.812322815347;12660.936695316464;12702.745945080862;12886.355682216039;9052.864207036768;10612.840807387742;10793.62222322215;10806.189535785004;11091.72270693239;10658.73353166146;10697.037266646039;10930.344179349264;10975.725606859893;11021.669349919195;8792.516411597424;9239.72280831576;9610.375325603813;9700.417974921527;10969.945150274516;6049.546284028627;7300.342969132804;7425.071866016748;7442.06974128234;7514.549127052176;8415.621297041525;10811.189160812813;10998;11087.113030957342;11228.045616122394 07:52:24 INFO - PROCESS | 1651 | |3;mootools.html;1341.885939695126;1440.2510694438893;1591.8965267257634;1728.3777839977597;1774.8668849836265;7621.199774023717;7851.175626559215;8166.510009399432;8609.35429842762;8627.999999999993;7845.744680851065;7878.172791856859;8090.797730056737;8422;8479.109693482173;2294.4608017115966;2323.337848713113;2357.7642235776457;2361.5749165150264;2370;2345.495718420539;2378.2389635316704;2397.4845408237225;2402.8318017738766;2405.518896220754;2339.894704738291;2347.260612906929;2361.6103342948395;2374.5844477216506;2381.071382160959;2340.3330050935488;2346.7066616672914;2363.976360236393;2365.042157926043;2369.5024044950583;2359.3511784259467;2373.3117395955323;2381.297517232403;2387.462820865309;2390.964135537968;8482.448640838325;8490.617922193516;8534.274586660142;8551.273141782956;8558.529280889548;5140.383154021504;5220.060389129955;5275.103232450492;5282.471752824726;5285.973570132163;3476.7392445566684;3495.4756786481976;3525.083478295636;3556.7332450066347;3573.2674801665858;1036.2694300518117;1043.7234132954773;1093.103655002898;1107.1087774341652;1108.5288752280248;11038.779224415546;11160.995510404022;11191.888081119247;11214.215004949658;11239.100871930226;4469.821207151743;4657.301404789241;4733.928991065137;4746.098241334135;4757.881052973703;9450.669226577036;9500.572474238676;9501.857472137923;9531.714048578553;9537.093976072261;5135.38375394955;5214.713190774468;5225.764840582183;5233.110371236899;5259.342582177228;4274.764887931194;4284.978575107105;4746.145693775154;4748.62011039123;4805.8077676892535;3851.4607954886337;3879.941800872989;3933.331333673282;3958.327084395602;3967.444557761916;1270.2188154285047;1271.6947932496134;1280.9679758006125;1282.2498838179583;1287.1633438265014;753.773867839646;887.689308741948;903.1059251341125;904.7128304728654;921.3320342751585;5835.270591176103;5851.268591426072;5880.206172166702;5963.105534169822;6117.877642447126;820.8754007010315;845.7927807687033;892.3352102683413;892.5894088719132;902.4540153207215;3789.3558095123894;3868.477755502971;3898.7075969302414;3901.4147877818555;3933.3903244997073;9954.601815927283;10130.898691013143;10296.485175741183;10328.328658637165;10340.844887326695;9973.650922217688;9992;10114.393136411838;10121.443320617436;10202.336848104998;7460.142083660448;7779.3776497880035;7875.56684382353;7922.207779222032;7943.44395892282;5578.386377498391;5608.439156084359;5798.0143375627085;5936.020556608111;5942.910856337072;9265.768355791159;9279.953600231956;9357.906420935704;9524.666636667684;9563.474008929576;8597.656093756179;9509.381890177116;9597.560365944884;9609.951950240204;9752.073553012453;6599.109120268702;6805.693743781443;6957.826054348479;7080.398166155728;7224.638768061681;7873.724419645285;8014.519128852287;8031.437799353989;8033.799155020937;8046.919530804851;7203.531770434905;7300.087489063867;7378.631068446663;7472.738454154079;7477.476576639583;7051.788446346618;7165.856682866418;7188.7124515020905;7212.495125341386;7235.565866048054;6720.092787473628;6825.180978282638;6847.1098757163245;6859.039734437083;6888.690008949509;4817.108834865561;4853.029394121119;4926.975365123151;4938.407391113089;4974.701517908937;4307.504636966789;4709.599684026759;4891.26631005352;4901.044296362175;4905.828296009622 07:52:24 INFO - PROCESS | 1651 | |4;prototype.html;291.7913691710427;300.3887089772314;306.74386886949395;308.64659964340814;310.469097842689;4343.9348409773875;4433.578810013051;4970.042824519414;5524.419935906727;5602.011285993475;4425;4567.949371644516;5257.789688412459;5311.601629877755;5445.9727701361535;3253.8535765890592;4180.456540649711;4180.979095104521;4189.706720529564;4206.137741762931;3476.922154132227;3639.8180090995475;4172.852465571962;4210.094829611633;4215.620594146526;3703.2778608171416;4042.4542686737354;4056.574059723731;4071.42999980003;4099.052949848828;3526.682598566141;3920.509936257982;4042.818073186699;4058.5941405859176;4078.4909583454014;2789.679186893513;3288.358770039843;3527.3650742866275;3539.4159963605975;3544.556930383702;4974.825881094144;5004.824831130929;5355.7857685692525;5370.275012873251;5427.945720542783;4511.864644060651;4616.53834616539;4657.930131048037;4684.243408721695;4689.929651055237;4565.420537396393;4918.9754051229875;5242.951409718072;5456.890862182774;5675.404082571312;3678.44823276508;3735.4957080794015;3856.9614303857165;3863.459115723801;3867.864624738149;5051;6037.577369584131;6053.793737385845;6075.27096748393;6096.390360963913;3659.3779057560278;3959.0109467351963;3963.96936796435;3973.562908080109;3976.483057202603;3772.490713753663;3796.551947954694;3817.9809100954317;3845.134844659929;4029.7179197456485;3901.132667489393;4310.676699247569;4361.862687283272;4680.976595117071;4706.247000479907;5297.708626025528;5780.595358324961;5840.29916410025;5904.409559044061;5919.970400147971;1055.9229585822418;1068.7648717282186;1241.8696036916174;1290.6837824733;1303.7131830997166;777.2848978939277;857.5626430520355;864.8627055422099;876.9473831570126;884.1467983396054;4744.40160610483;4935.8025678972435;4973.577245934136;5202.3497062867145;5208.166693329125;4854.101991131652;5406.648567843077;5421.403645598981;5690.630109042899;5712.057510510802;4807.78364973577;4859.319695242669;5172.525830138393;5198.896022079537;5199.686139667811;2515.9748402515743;2720.1567514070703;2921.7954743167775;2961.8822541882228;2972.539256415259;4740.478547359788;5374.184329126364;5584.6038490377405;5869.325027621793;6007.519398448114;4721.379634302994;5721.284839395075;5849.830033993218;5849.912251316234;5850.619709718854;4529.750863702528;4987.376577927759;5528.364238112588;5788.710564471759;5790.7973220937065;4728.645351598575;4885.682430641997;5320.255164276927;5577.44225577439;5605.673645437261;5320.707361095177;5546.500814926676;5612;5630.718464076862;5638.5489160867855;4856.68431551948;4946.258061290835;5407.594025553463;5696.718238396494;5834.920539700169;3705.5923848377197;3715.851365945332;3750.981245093757;3751.9249615008093;3757.28611563802;5042.218456139304;5210.436868939379;5507.779688812563;5527.364353099445;5635.7601327707835;4953.108440480748;5229.424763276113;5439.782408703608;5496.917546236889;5513.944860551504;5074.162763144188;5398.433164517669;5402.297701298806;5432.809851655173;5503.981763373788;4599.701019433726;5334.013207556614;5399.568034557305;5454.063807348492;5709;5072.746362681925;5791.059994901755;5813.593048486566;5833.79581714638;5834.38738932406;4715.180703630071;4876.975615121902;5259.47405259471;5558.972205138948;5693.28833895763 07:52:24 INFO - PROCESS | 1651 | |5;yui.html;604.5284677951199;668.7859884836851;717.802604283822;722.3174100475053;768.565760345405;11184;12644;13439.93280033601;13574.796378054338;13607.251601161932;12362.814557781641;12670.873291267084;12761.170523915938;12777.233365998047;12779.361031948389;764.5641984069083;774.5817258680306;781.4373650971291;783.9412044096687;788.7830846517196;622.4553515673786;644.3974883483934;760.5854809129038;774.7714424244862;779.5283853268776;815.9469634473741;828.5401602110826;828.7679449754079;829.3075282139421;830.8670612701969;821.1788211788212;823.5964377455003;827.735124760077;827.954462504562;828.9212524810133;599.8260504453704;602.340437221242;605.7486143250545;607.0469363099935;608.6013661052018;11482.655520334403;13466.461341546326;13559.796603050961;13952.58142255734;13982.091164074405;12005.579804706793;12318.199317044453;12350.44423000967;12353.135280530367;13550.390232439564;12134.271943683407;12179.391030448442;12285.201461904948;12332.445039973223;12369.75260494794;3317.9004629861142;3493.441049432104;3817;4391.956080439187;4393.077453734688;69870.95193572101;74355.62822185854;82449.76325355124;84846.57576712077;94716.52641736747;9461.290403219786;9469.526523673789;9624.278179136592;9696.030396960248;9701.611935522642;18808.81191188098;19111.331103411314;19129.426117216506;19182.616347672967;19212.903935480233;7459.776206713807;7470.252974702595;7617.238276172338;7628.0464941882265;7911.564863932539;4210.7473551587;4270.7010509264055;4291.721038132511;4377.255866502702;4444.400005999213;3241.5785947827103;3312.685294896981;3340.6993370596765;3340.883069092619;3352.547406100145;609.7195290166574;658.4633523678187;660.5871330418488;661.5633681770008;661.5964261800298;556.290729320112;568.8037627018672;604.8911195984678;631.7188850961278;640.0303540136697;7795.727149549739;8576.914230857736;8632.525211113449;8763.502401815453;9075.954620226988;571.9714014299268;619.127030886448;640.3980258556942;642.2870613618879;650.8340373204898;4283.336082907154;4320.179165958458;4339.392485052095;4364.5635436456105;4430.13612345596;115379.84620153859;122375.38812306059;122544.38727806482;122940;125132;65734;69654;82010.21703705977;106041.46979265055;108928;62746;65790.34209657938;79672.00819979547;83127.5843620778;83279.91800205043;7193.928060719326;7616.809579760328;7837.216278372117;9443.197328227177;9589.280803939593;43815.78092109646;49849.75375615493;52553.21170182527;53767.731161343945;54511.72744136254;54449.72775136099;56236.718816407294;70420.64789676019;75806;77625.83561246473;10090.646827361006;12051.216670916363;12413.813792792935;12547.121701480808;12649.557265495663;13372.197668139554;13692.726145476836;13763.724725505634;13828.377723002288;14305.713885722436;12897;13119.540816071392;13280.80078798838;13394.93302533481;13425.66435839112;12702.237865728086;12793.744125117631;12808.807867881802;12994.895433888225;13190.73618527605;12652.304123273309;12662.683432913882;12679.556215532411;12808.423620937267;12884.098113131991;8896.510691912006;9028.864567031631;10211.387316761018;11046.723831904266;11278;10927.289726167774;11181.273217240854;11448.484818183035;11510.59712910044;11602.76794464123 07:52:24 INFO - PROCESS | 1651 | __end_tp_report 07:52:24 INFO - PROCESS | 1651 | __start_cc_report 07:52:24 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,372 07:52:24 INFO - PROCESS | 1651 | __end_cc_report 07:52:24 INFO - PROCESS | 1651 | __startTimestamp1480953144931__endTimestamp 07:52:24 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | ------- Summary: start ------- 07:52:24 INFO - PROCESS | 1651 | Number of tests: 6 07:52:24 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5722.76 Median:5930.47 stddev:665.93 (11.2%) stddev-sans-first:639.66 07:52:24 INFO - PROCESS | 1651 | Values: 3273.6 3335.6 3382.8 3391.8 3408.5 5700.8 5703.5 5719.4 5768.1 5776.6 5731.4 5773.6 5794.9 5817.8 5835.4 5216.1 5255.6 5261.8 5318.5 5326.4 5241.8 5299.3 5334.4 5359.6 5388.8 5092.0 5159.3 5234.4 5301.5 5305.6 5078.6 5128.9 5137.3 5143.9 5211.2 4973.0 5076.0 5124.5 5161.6 5207.4 4728.8 5689.3 5689.7 5753.0 5777.8 6221.0 6256.3 6266.4 6279.4 6309.7 5433.2 5490.2 6204.7 6235.4 6270.9 5684.1 5762.4 5789.3 5805.2 5874.4 5189.8 6290.4 6311.4 6314.0 6321.5 6106.8 6192.4 6204.2 6220.4 6251.8 6072.7 6083.3 6142.5 6262.7 6297.5 5458.5 6111.0 6112.2 6144.7 6166.3 6246.8 6254.4 6270.7 6274.1 6285.3 3978.0 4933.6 4971.2 4987.0 4996.7 4384.7 4389.0 4407.8 4436.8 4499.5 4820.7 5284.8 5657.9 5866.2 5971.9 6160.5 6176.0 6182.2 6189.4 6281.0 5873.1 5890.3 5930.5 5956.6 5962.1 5271.8 5323.6 5337.7 5400.5 5441.8 6252.0 6255.7 6266.7 6270.0 6276.6 6190.7 6256.0 6286.7 6314.3 6317.7 6363.0 6367.1 6375.5 6382.7 6389.7 6012.4 6124.3 6263.9 6267.0 6283.6 6178.3 6219.5 6232.8 6252.8 6269.7 6128.8 6171.9 6202.2 6205.6 6211.5 6108.9 6110.7 6187.3 6187.7 6229.3 4914.6 5846.2 5909.1 5909.3 5912.0 6059.0 6139.4 6160.2 6185.1 6198.7 5908.1 5917.4 5981.2 5984.9 5996.6 07:52:24 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:70791.75 Median:39421.41 stddev:155842.35 (395.3%) stddev-sans-first:156190.14 07:52:24 INFO - PROCESS | 1651 | Values: 487.0 514.2 523.5 525.0 557.9 32220.4 37261.8 38230.6 39396.2 39435.4 40179.0 40923.8 41059.8 41134.4 41160.8 9089.7 9174.0 9406.3 9415.2 9419.4 9244.1 9338.4 9442.3 9445.6 9457.6 9272.5 9308.3 9398.4 9437.8 9461.1 9319.5 9403.3 9405.4 9408.5 9436.5 9296.5 9318.5 9411.9 9429.4 9457.8 38580.8 38685.8 38828.0 39191.2 39284.4 3433.8 3449.5 3451.9 3541.9 3672.4 28971.7 29705.7 30007.5 30403.2 30407.8 3111.8 3169.4 3209.4 3274.1 3285.3 390539.0 500116.0 501012.0 501910.0 502892.0 60314.7 60566.0 60591.0 60608.4 60706.7 62553.7 62618.0 62711.0 62985.7 63205.7 15638.3 15967.9 16142.4 16171.9 16195.1 12180.4 12337.6 12348.9 12428.1 12433.0 13679.6 15812.1 16833.2 17109.7 17120.3 6122.7 7142.5 7181.6 7205.2 7253.3 871.7 927.9 965.8 978.9 1067.0 26056.9 27768.7 29736.0 29773.4 30262.8 904.7 975.2 1026.9 1039.7 1044.6 13485.7 15000.6 15410.7 15516.0 15649.1 647098.0 651336.0 653654.0 654194.0 655710.7 55837.0 56699.7 57399.4 57478.7 57881.0 1323.2 1341.9 1361.6 1362.1 1369.0 28358.9 28594.7 28990.7 29105.4 29494.0 48066.0 48956.8 49349.8 49464.5 49520.5 597976.0 600210.0 601003.0 601422.0 603421.0 38410.6 38930.6 39295.0 39407.4 39482.6 45459.3 45578.1 46068.8 46472.0 46589.3 38137.0 38452.8 39167.8 39509.4 39543.5 34078.7 37928.2 38017.9 38227.6 39127.6 36890.9 37043.8 37705.6 37995.4 39129.8 29314.9 31638.8 34315.7 34672.0 35005.3 25005.7 25230.6 25499.1 25731.7 26901.0 07:52:24 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:33280.56 Median:379.96 stddev:96732.05 (25458.4%) stddev-sans-first:96971.77 07:52:24 INFO - PROCESS | 1651 | Values: 335.6 372.4 375.6 384.3 398.8 18924.1 19298.8 19576.6 19698.5 19858.0 18943.8 19157.9 19502.6 19963.1 20047.7 4993.6 5268.3 5430.9 5432.3 5856.9 5697.8 5761.0 5786.6 5810.9 5818.6 5348.9 5538.9 5708.6 5732.7 5855.1 5852.1 5875.3 5913.7 5914.1 5926.6 4975.7 5180.4 5198.8 5236.1 5250.8 10257.5 10364.4 12894.9 13119.9 13255.5 2037.3 2152.3 2277.2 2295.2 2321.2 9214.7 10379.3 10820.1 12332.9 14324.5 1597.8 1815.8 1900.8 1912.7 1930.1 105078.9 113587.3 116780.4 116989.0 119041.0 11252.4 11702.4 11719.2 11758.6 11901.3 11686.8 14443.0 14657.3 14675.3 14689.9 6976.9 8438.9 8477.4 8579.3 8601.9 8093.8 8245.2 8251.9 8313.8 8367.1 3983.9 4313.5 4403.3 4543.2 4600.2 1655.8 1670.6 1898.1 1998.8 2001.6 860.1 882.4 926.9 927.9 960.7 19819.0 20359.3 20469.0 20486.6 20787.2 854.2 909.4 940.0 1000.6 1033.1 10358.4 10511.5 10515.8 10537.5 10552.2 571520.0 582972.0 583504.0 583774.0 585080.0 76644.0 97353.5 108674.0 112720.4 122459.0 934.9 953.6 963.2 970.3 980.7 14311.9 18104.6 18965.8 18975.9 19109.1 49898.3 53938.0 54177.6 56958.1 58747.1 77705.2 79810.2 80499.6 82136.6 83752.6 7010.6 7874.4 7975.4 8022.7 8028.0 10531.5 12511.8 12660.9 12702.7 12886.4 9052.9 10612.8 10793.6 10806.2 11091.7 10658.7 10697.0 10930.3 10975.7 11021.7 8792.5 9239.7 9610.4 9700.4 10969.9 6049.5 7300.3 7425.1 7442.1 7514.5 8415.6 10811.2 10998.0 11087.1 11228.0 07:52:24 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5516.17 Median:5222.91 stddev:3009.20 (57.6%) stddev-sans-first:3001.29 07:52:24 INFO - PROCESS | 1651 | Values: 1341.9 1440.3 1591.9 1728.4 1774.9 7621.2 7851.2 8166.5 8609.4 8628.0 7845.7 7878.2 8090.8 8422.0 8479.1 2294.5 2323.3 2357.8 2361.6 2370.0 2345.5 2378.2 2397.5 2402.8 2405.5 2339.9 2347.3 2361.6 2374.6 2381.1 2340.3 2346.7 2364.0 2365.0 2369.5 2359.4 2373.3 2381.3 2387.5 2391.0 8482.4 8490.6 8534.3 8551.3 8558.5 5140.4 5220.1 5275.1 5282.5 5286.0 3476.7 3495.5 3525.1 3556.7 3573.3 1036.3 1043.7 1093.1 1107.1 1108.5 11038.8 11161.0 11191.9 11214.2 11239.1 4469.8 4657.3 4733.9 4746.1 4757.9 9450.7 9500.6 9501.9 9531.7 9537.1 5135.4 5214.7 5225.8 5233.1 5259.3 4274.8 4285.0 4746.1 4748.6 4805.8 3851.5 3879.9 3933.3 3958.3 3967.4 1270.2 1271.7 1281.0 1282.2 1287.2 753.8 887.7 903.1 904.7 921.3 5835.3 5851.3 5880.2 5963.1 6117.9 820.9 845.8 892.3 892.6 902.5 3789.4 3868.5 3898.7 3901.4 3933.4 9954.6 10130.9 10296.5 10328.3 10340.8 9973.7 9992.0 10114.4 10121.4 10202.3 7460.1 7779.4 7875.6 7922.2 7943.4 5578.4 5608.4 5798.0 5936.0 5942.9 9265.8 9280.0 9357.9 9524.7 9563.5 8597.7 9509.4 9597.6 9610.0 9752.1 6599.1 6805.7 6957.8 7080.4 7224.6 7873.7 8014.5 8031.4 8033.8 8046.9 7203.5 7300.1 7378.6 7472.7 7477.5 7051.8 7165.9 7188.7 7212.5 7235.6 6720.1 6825.2 6847.1 6859.0 6888.7 4817.1 4853.0 4927.0 4938.4 4974.7 4307.5 4709.6 4891.3 4901.0 4905.8 07:52:24 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4471.58 Median:4949.68 stddev:1381.19 (27.9%) stddev-sans-first:1348.97 07:52:24 INFO - PROCESS | 1651 | Values: 291.8 300.4 306.7 308.6 310.5 4343.9 4433.6 4970.0 5524.4 5602.0 4425.0 4567.9 5257.8 5311.6 5446.0 3253.9 4180.5 4181.0 4189.7 4206.1 3476.9 3639.8 4172.9 4210.1 4215.6 3703.3 4042.5 4056.6 4071.4 4099.1 3526.7 3920.5 4042.8 4058.6 4078.5 2789.7 3288.4 3527.4 3539.4 3544.6 4974.8 5004.8 5355.8 5370.3 5427.9 4511.9 4616.5 4657.9 4684.2 4689.9 4565.4 4919.0 5243.0 5456.9 5675.4 3678.4 3735.5 3857.0 3863.5 3867.9 5051.0 6037.6 6053.8 6075.3 6096.4 3659.4 3959.0 3964.0 3973.6 3976.5 3772.5 3796.6 3818.0 3845.1 4029.7 3901.1 4310.7 4361.9 4681.0 4706.2 5297.7 5780.6 5840.3 5904.4 5920.0 1055.9 1068.8 1241.9 1290.7 1303.7 777.3 857.6 864.9 876.9 884.1 4744.4 4935.8 4973.6 5202.3 5208.2 4854.1 5406.6 5421.4 5690.6 5712.1 4807.8 4859.3 5172.5 5198.9 5199.7 2516.0 2720.2 2921.8 2961.9 2972.5 4740.5 5374.2 5584.6 5869.3 6007.5 4721.4 5721.3 5849.8 5849.9 5850.6 4529.8 4987.4 5528.4 5788.7 5790.8 4728.6 4885.7 5320.3 5577.4 5605.7 5320.7 5546.5 5612.0 5630.7 5638.5 4856.7 4946.3 5407.6 5696.7 5834.9 3705.6 3715.9 3751.0 3751.9 3757.3 5042.2 5210.4 5507.8 5527.4 5635.8 4953.1 5229.4 5439.8 5496.9 5513.9 5074.2 5398.4 5402.3 5432.8 5504.0 4599.7 5334.0 5399.6 5454.1 5709.0 5072.7 5791.1 5813.6 5833.8 5834.4 4715.2 4877.0 5259.5 5559.0 5693.3 07:52:24 INFO - PROCESS | 1651 | 07:52:24 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:19643.69 Median:27147.08 stddev:29369.03 (108.2%) stddev-sans-first:29416.62 07:52:24 INFO - PROCESS | 1651 | Values: 604.5 668.8 717.8 722.3 768.6 11184.0 12644.0 13439.9 13574.8 13607.3 12362.8 12670.9 12761.2 12777.2 12779.4 764.6 774.6 781.4 783.9 788.8 622.5 644.4 760.6 774.8 779.5 815.9 828.5 828.8 829.3 830.9 821.2 823.6 827.7 828.0 828.9 599.8 602.3 605.7 607.0 608.6 11482.7 13466.5 13559.8 13952.6 13982.1 12005.6 12318.2 12350.4 12353.1 13550.4 12134.3 12179.4 12285.2 12332.4 12369.8 3317.9 3493.4 3817.0 4392.0 4393.1 69871.0 74355.6 82449.8 84846.6 94716.5 9461.3 9469.5 9624.3 9696.0 9701.6 18808.8 19111.3 19129.4 19182.6 19212.9 7459.8 7470.3 7617.2 7628.0 7911.6 4210.7 4270.7 4291.7 4377.3 4444.4 3241.6 3312.7 3340.7 3340.9 3352.5 609.7 658.5 660.6 661.6 661.6 556.3 568.8 604.9 631.7 640.0 7795.7 8576.9 8632.5 8763.5 9076.0 572.0 619.1 640.4 642.3 650.8 4283.3 4320.2 4339.4 4364.6 4430.1 115379.8 122375.4 122544.4 122940.0 125132.0 65734.0 69654.0 82010.2 106041.5 108928.0 62746.0 65790.3 79672.0 83127.6 83279.9 7193.9 7616.8 7837.2 9443.2 9589.3 43815.8 49849.8 52553.2 53767.7 54511.7 54449.7 56236.7 70420.6 75806.0 77625.8 10090.6 12051.2 12413.8 12547.1 12649.6 13372.2 13692.7 13763.7 13828.4 14305.7 12897.0 13119.5 13280.8 13394.9 13425.7 12702.2 12793.7 12808.8 12994.9 13190.7 12652.3 12662.7 12679.6 12808.4 12884.1 8896.5 9028.9 10211.4 11046.7 11278.0 10927.3 11181.3 11448.5 11510.6 11602.8 07:52:24 INFO - PROCESS | 1651 | -------- Summary: end -------- 07:52:24 INFO - PROCESS | 1651 | 07:52:25 INFO - TEST-INFO | 1651: exit 0 07:52:25 INFO - TEST-OK | dromaeo_css | took 1117463ms 07:52:25 INFO - TEST-START | kraken 07:52:25 INFO - operating with platform_type : mac_ 07:52:25 INFO - Initialising browser for kraken test... 07:52:25 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp_wc8PE/profile http://localhost:49242/getInfo.html) 07:52:28 INFO - PROCESS | 1659 | 2016-12-05 07:52:28.597 plugin-container[1660:10822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a2f, name = 'com.apple.tsm.portname' 07:52:28 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:28 INFO - PROCESS | 1659 | 2016-12-05 07:52:28.599 plugin-container[1660:10822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 07:52:28 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:28 INFO - PROCESS | 1659 | 2016-12-05 07:52:28.599 plugin-container[1660:10822] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:52:28 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 07:52:28 INFO - PROCESS | 1659 | colorDepth:24 07:52:28 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 07:52:28 INFO - PROCESS | 1659 | __metrics 07:52:28 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 07:52:29 INFO - TEST-INFO | 1659: exit 0 07:52:29 INFO - Browser initialized. 07:52:29 INFO - Running cycle 1/1 for kraken test... 07:52:29 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp_wc8PE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:32 INFO - PROCESS | 1661 | 2016-12-05 07:52:32.182 plugin-container[1662:10960] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 07:52:32 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:32 INFO - PROCESS | 1661 | 2016-12-05 07:52:32.183 plugin-container[1662:10960] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 07:52:32 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:32 INFO - PROCESS | 1661 | 2016-12-05 07:52:32.183 plugin-container[1662:10960] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:52:41 INFO - PROCESS | 1661 | RSS: Main: 229416960 07:52:41 INFO - PROCESS | 1661 | 07:52:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 07:52:45 INFO - PROCESS | 1661 | RSS: Main: 241528832 07:52:45 INFO - PROCESS | 1661 | 07:52:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 07:52:48 INFO - PROCESS | 1661 | RSS: Main: 249188352 07:52:48 INFO - PROCESS | 1661 | 07:52:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 07:52:52 INFO - PROCESS | 1661 | RSS: Main: 251179008 07:52:52 INFO - PROCESS | 1661 | 07:52:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 07:52:55 INFO - PROCESS | 1661 | RSS: Main: 252715008 07:52:55 INFO - PROCESS | 1661 | 07:52:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:52:58 INFO - PROCESS | 1661 | RSS: Main: 254328832 07:52:58 INFO - PROCESS | 1661 | 07:53:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 07:53:01 INFO - PROCESS | 1661 | RSS: Main: 258498560 07:53:01 INFO - PROCESS | 1661 | 07:53:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 07:53:05 INFO - PROCESS | 1661 | RSS: Main: 258269184 07:53:05 INFO - PROCESS | 1661 | 07:53:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 07:53:09 INFO - PROCESS | 1661 | RSS: Main: 258293760 07:53:09 INFO - PROCESS | 1661 | 07:53:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:53:11 INFO - PROCESS | 1661 | RSS: Main: 257212416 07:53:11 INFO - PROCESS | 1661 | 07:53:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:53:14 INFO - PROCESS | 1661 | RSS: Main: 257490944 07:53:14 INFO - PROCESS | 1661 | 07:53:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:53:16 INFO - PROCESS | 1661 | RSS: Main: 257318912 07:53:16 INFO - PROCESS | 1661 | 07:53:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:53:20 INFO - PROCESS | 1661 | RSS: Main: 257449984 07:53:20 INFO - PROCESS | 1661 | 07:53:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:53:24 INFO - PROCESS | 1661 | RSS: Main: 257499136 07:53:24 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 07:53:26 INFO - PROCESS | 1661 | RSS: Main: 259358720 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | __start_tp_report 07:53:26 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 07:53:26 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 07:53:26 INFO - PROCESS | 1661 | |i|pagename|runs| 07:53:26 INFO - PROCESS | 1661 | |0;ai-astar;115;121;118;117;119;97;172;173;118;117 07:53:26 INFO - PROCESS | 1661 | |1;audio-beat-detection;151;115;115;151;116;121;119;122;150;124 07:53:26 INFO - PROCESS | 1661 | |2;audio-dft;196;201;198;274;278;198;214;273;205;197 07:53:26 INFO - PROCESS | 1661 | |3;audio-fft;80;62;60;85;62;68;81;83;91;61 07:53:26 INFO - PROCESS | 1661 | |4;audio-oscillator;76;105;123;104;60;58;99;66;76;120 07:53:26 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;91;90;88;133;131;116;139;113;92;87 07:53:26 INFO - PROCESS | 1661 | |6;imaging-darkroom;98;100;145;104;100;154;154;98;99;98 07:53:26 INFO - PROCESS | 1661 | |7;imaging-desaturate;94;92;86;89;89;88;89;91;87;129 07:53:26 INFO - PROCESS | 1661 | |8;json-parse-financial;60;54;66;60;61;61;60;60;61;60 07:53:26 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;70;75;72;92;72;76;88;105;107;109 07:53:26 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;88;98;87;86;83;87;85;89;98;88 07:53:26 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;148;165;163;162;150;156;150;175;218;154 07:53:26 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;172;235;232;154;227;173;233;189;189;195 07:53:26 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;61;73;82;95;91;90;69;68;69;78 07:53:26 INFO - PROCESS | 1661 | __end_tp_report 07:53:26 INFO - PROCESS | 1661 | __start_cc_report 07:53:26 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,991 07:53:26 INFO - PROCESS | 1661 | __end_cc_report 07:53:26 INFO - PROCESS | 1661 | __startTimestamp1480953206652__endTimestamp 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | ------- Summary: start ------- 07:53:26 INFO - PROCESS | 1661 | Number of tests: 14 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:126.70 Median:120.00 stddev:25.05 (20.9%) stddev-sans-first:26.21 07:53:26 INFO - PROCESS | 1661 | Values: 115.0 121.0 118.0 117.0 119.0 97.0 172.0 173.0 118.0 117.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:123.00 stddev:15.65 (12.7%) stddev-sans-first:14.30 07:53:26 INFO - PROCESS | 1661 | Values: 151.0 115.0 115.0 151.0 116.0 121.0 119.0 122.0 150.0 124.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:223.40 Median:209.50 stddev:36.01 (17.2%) stddev-sans-first:36.80 07:53:26 INFO - PROCESS | 1661 | Values: 196.0 201.0 198.0 274.0 278.0 198.0 214.0 273.0 205.0 197.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:73.30 Median:80.50 stddev:11.83 (14.7%) stddev-sans-first:12.30 07:53:26 INFO - PROCESS | 1661 | Values: 80.0 62.0 60.0 85.0 62.0 68.0 81.0 83.0 91.0 61.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:63.00 stddev:24.42 (38.8%) stddev-sans-first:25.46 07:53:26 INFO - PROCESS | 1661 | Values: 76.0 105.0 123.0 104.0 60.0 58.0 99.0 66.0 76.0 120.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:108.00 Median:87.50 stddev:20.85 (23.8%) stddev-sans-first:21.19 07:53:26 INFO - PROCESS | 1661 | Values: 91.0 90.0 88.0 133.0 131.0 116.0 139.0 113.0 92.0 87.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:115.00 Median:126.00 stddev:25.02 (19.9%) stddev-sans-first:25.78 07:53:26 INFO - PROCESS | 1661 | Values: 98.0 100.0 145.0 104.0 100.0 154.0 154.0 98.0 99.0 98.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:89.00 stddev:12.73 (14.3%) stddev-sans-first:13.50 07:53:26 INFO - PROCESS | 1661 | Values: 94.0 92.0 86.0 89.0 89.0 88.0 89.0 91.0 87.0 129.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:60.50 stddev:2.87 (4.7%) stddev-sans-first:3.04 07:53:26 INFO - PROCESS | 1661 | Values: 60.0 54.0 66.0 60.0 61.0 61.0 60.0 60.0 61.0 60.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:86.60 Median:73.50 stddev:15.76 (21.4%) stddev-sans-first:15.53 07:53:26 INFO - PROCESS | 1661 | Values: 70.0 75.0 72.0 92.0 72.0 76.0 88.0 105.0 107.0 109.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:88.00 stddev:5.09 (5.8%) stddev-sans-first:5.39 07:53:26 INFO - PROCESS | 1661 | Values: 88.0 98.0 87.0 86.0 83.0 87.0 85.0 89.0 98.0 88.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:164.10 Median:162.50 stddev:20.70 (12.7%) stddev-sans-first:21.12 07:53:26 INFO - PROCESS | 1661 | Values: 148.0 165.0 163.0 162.0 150.0 156.0 150.0 175.0 218.0 154.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.90 Median:211.00 stddev:29.75 (14.1%) stddev-sans-first:29.79 07:53:26 INFO - PROCESS | 1661 | Values: 172.0 235.0 232.0 154.0 227.0 173.0 233.0 189.0 189.0 195.0 07:53:26 INFO - PROCESS | 1661 | 07:53:26 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.60 Median:80.00 stddev:11.51 (14.4%) stddev-sans-first:10.53 07:53:26 INFO - PROCESS | 1661 | Values: 61.0 73.0 82.0 95.0 91.0 90.0 69.0 68.0 69.0 78.0 07:53:26 INFO - PROCESS | 1661 | -------- Summary: end -------- 07:53:26 INFO - PROCESS | 1661 | 07:53:27 INFO - TEST-INFO | 1661: exit 0 07:53:27 INFO - TEST-OK | kraken | took 62123ms 07:53:27 INFO - SUITE-END | took 1179s 07:53:27 INFO - Completed test suite (00:19:39) 07:53:27 INFO - javascript benchmark 07:53:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7104.193201666701, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3273.590801149856, 3335.5663763710713, 3382.8477718502663, 3391.8304084795786, 3408.4716868885325, 5700.800471983482, 5703.543716502681, 5719.370869204389, 5768.134779783024, 5776.566757493184, 5731.426857314266, 5773.595848290622, 5794.855128621786, 5817.767289308433, 5835.35811060783, 5216.113260745663, 5255.5795536357, 5261.815836445725, 5318.547923426494, 5326.440723724011, 5241.842744717665, 5299.311089558352, 5334.359876814788, 5359.5712343012465, 5388.811391601294, 5092.00705862357, 5159.277701121846, 5234.42421333653, 5301.522862942354, 5305.575553955713, 5078.61910356721, 5128.871778205575, 5137.3064636274, 5143.897122057538, 5211.218317252443, 4973.0, 5075.959428317223, 5124.5131712487255, 5161.587073034186, 5207.427183009866, 4728.770519664912, 5689.317281926154, 5689.6586204827845, 5753.021986262345, 5777.826665200008, 6220.973539365999, 6256.343083976208, 6266.373362663743, 6279.403456671655, 6309.747610095591, 5433.239346491494, 5490.231367608538, 6204.6587437690905, 6235.376462353774, 6270.905936410957, 5684.090545512699, 5762.366139724627, 5789.305283366023, 5805.245318108618, 5874.412558744091, 5189.792408303701, 6290.402411770874, 6311.368863113744, 6313.96843015782, 6321.494280457552, 6106.786262480792, 6192.380761923771, 6204.162438070893, 6220.377962203744, 6251.78118765841, 6072.726727297282, 6083.330833608299, 6142.539309551712, 6262.655553944577, 6297.4647154992335, 5458.536024437888, 6110.984061328612, 6112.205413296332, 6144.709610981655, 6166.321704612489, 6246.781362652377, 6254.3745625437095, 6270.655113968775, 6274.090256912722, 6285.340039295899, 3977.960220397817, 4933.580645645088, 4971.1797553403285, 4987.0, 4996.7001979881325, 4384.671149663788, 4388.978055109704, 4407.757573333497, 4436.82252709888, 4499.460064792181, 4820.662553621283, 5284.810917543598, 5657.943420565742, 5866.2373891393845, 5971.850703732442, 6160.507159427235, 6175.96912015446, 6182.165407669997, 6189.381061893775, 6281.0, 5873.089671100986, 5890.263717035371, 5930.466258036798, 5956.642601443928, 5962.1056841473255, 5271.762770675329, 5323.600729945269, 5337.6797392156595, 5400.540954018952, 5441.809536666197, 6252.0, 6255.655938923403, 6266.717997690206, 6269.96865015672, 6276.592021518587, 6190.659513726789, 6256.0, 6286.74853005893, 6314.305426403183, 6317.652529110759, 6362.968185159045, 6367.140436041075, 6375.5218358624215, 6382.744690212339, 6389.744410223539, 6012.398760123952, 6124.319907527481, 6263.937360626335, 6267.0, 6283.622982621057, 6178.320384757763, 6219.533534984804, 6232.844178895564, 6252.812415627538, 6269.7178626962805, 6128.785492507741, 6171.9074213887725, 6202.193714817045, 6205.565610407409, 6211.472024877936, 6108.938910610837, 6110.694465276807, 6187.257529096537, 6187.690615469299, 6229.314775374614, 4914.582260507754, 5846.152307915243, 5909.054551271776, 5909.320428150818, 5911.970440147772, 6058.969705151446, 6139.447449729546, 6160.229971253593, 6185.072239164161, 6198.690065496798, 5908.113782932594, 5917.437843404869, 5981.192539007352, 5984.9401505986125, 5996.640201587918], "value": 5680.599107271823, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.9651989522265, 514.2466286889706, 523.4739087217346, 524.9815358205082, 557.8744782423955, 32220.355592888158, 37261.81369093157, 38230.61769382306, 39396.212075758536, 39435.40846887299, 40179.0, 40923.795381023054, 41059.79470102653, 41134.382984255186, 41160.765177044734, 9089.727308180765, 9173.95413022934, 9406.2945279104, 9415.246780257597, 9419.434833909952, 9244.121808428188, 9338.439693618404, 9442.339036267464, 9445.622175112987, 9457.62169513222, 9272.536373181347, 9308.255339572817, 9398.436093834393, 9437.811243775155, 9461.148496635302, 9319.487428191447, 9403.341766076377, 9405.388649737779, 9408.482533460658, 9436.528173591361, 9296.535173241311, 9318.487483188423, 9411.858822117674, 9429.387089839138, 9457.810843783085, 38580.80709596462, 38685.80657096697, 38828.0, 39191.21617567632, 39284.41073383902, 3433.8454769535433, 3449.5170676105367, 3451.9309613807836, 3541.8583256669704, 3672.3573374659327, 28971.710282897322, 29705.70294297051, 30007.549886751716, 30403.239919001982, 30407.847960760053, 3111.8132912025126, 3169.3819705157503, 3209.3902158590026, 3274.1487213324467, 3285.2608163163336, 390539.0, 500116.0, 501012.0, 501910.0, 502891.99999999633, 60314.69842650759, 60566.0, 60591.0, 60608.393916061155, 60706.69646651739, 62553.68723156355, 62618.0, 62711.0, 62985.685071575266, 63205.68397157985, 15638.296276667577, 15967.920160399124, 16142.35430582787, 16171.867969242041, 16195.109268990083, 12180.390980451119, 12337.568185113478, 12348.938255308845, 12428.13003089775, 12433.0, 13679.589612311647, 15812.130332831804, 16833.242504087346, 17109.743353849703, 17120.315187392614, 6122.66325352101, 7142.464315176278, 7181.6409179540815, 7205.2434494378385, 7253.347198752138, 871.7166920750655, 927.8515437530098, 965.75856035991, 978.9265805064649, 1066.989330106689, 26056.86971565168, 27768.72231277702, 29736.0, 29773.404531909673, 30262.84868575643, 904.7421484876778, 975.2246963663904, 1026.9127124194379, 1039.740064983754, 1044.5717255925035, 13485.730285394433, 15000.62498437526, 15410.691786164438, 15516.0, 15649.061056336655, 647098.0, 651336.0, 653654.0, 654194.0, 655710.7214463897, 55837.0, 56699.71650141723, 57399.426005739406, 57478.7126064367, 57881.0, 1323.1597935311147, 1341.9329033548283, 1361.5779108476404, 1362.1146254934174, 1368.9726205475836, 28358.85820570884, 28594.714052859206, 28990.7100928988, 29105.41789164247, 29494.0, 48066.0, 48956.75521622369, 49349.75325123352, 49464.50535494599, 49520.50479495303, 597976.0, 600209.9989500025, 601003.0, 601422.0, 603421.0, 38410.61589384182, 38930.6106938927, 39295.0, 39407.40888886726, 39482.60517394904, 45459.31811022771, 45578.088438231716, 46068.769656151504, 46472.0, 46589.3011604833, 38137.0, 38452.80773596114, 39167.804160979016, 39509.40735888907, 39543.45250296253, 34078.65921340854, 37928.241435171694, 38017.85946421612, 38227.61772382352, 39127.60872391354, 36890.893273201844, 37043.814780925924, 37705.62294377021, 37995.43006854844, 39129.80435097806, 29314.85342573359, 31638.841805790824, 34315.65684343225, 34672.0, 35005.299894001466, 25005.74994250107, 25230.62154067727, 25499.10753123706, 25731.742682572934, 26901.0], "value": 19775.589809974044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.6442171298424, 372.35210733324027, 375.60936625909045, 384.3120813743399, 398.82651046794626, 18924.05379731015, 19298.807011929875, 19576.608467830636, 19698.507537311576, 19858.007099644998, 18943.81056189441, 19157.90421047893, 19502.609947801036, 19963.10166042528, 20047.699284510745, 4993.6005119590345, 5268.262443257938, 5430.8913821723545, 5432.266644003049, 5856.853578660546, 5697.831944451398, 5760.971195144019, 5786.566007549451, 5810.941890581083, 5818.621789583684, 5348.893022139555, 5538.861528461781, 5708.628939118965, 5732.7133643317875, 5855.063189889618, 5852.092925596539, 5875.2949646042325, 5913.733881975321, 5914.142449344821, 5926.555508336849, 4975.651704380694, 5180.378354597435, 5198.792048318062, 5236.057509648262, 5250.81622143227, 10257.512660664162, 10364.429956352398, 12894.871051289461, 13119.868801312055, 13255.536056237985, 2037.2665840297482, 2152.257471172443, 2277.1802151225547, 2295.231097582295, 2321.2107883319754, 9214.723558293263, 10379.325343852626, 10820.08029317509, 12332.890039896452, 14324.498642547462, 1597.752348386002, 1815.827496387841, 1900.8479321654233, 1912.693968964959, 1930.1314408516084, 105078.94921050845, 113587.2961905572, 116780.41609791896, 116989.0, 119041.0, 11252.381119038368, 11702.361669366295, 11719.179657423898, 11758.647240582795, 11901.285922844483, 11686.766264674829, 14443.0, 14657.340419680926, 14675.266236688336, 14689.853101468849, 6976.860462790716, 8438.873416898752, 8477.406581539233, 8579.313654907592, 8601.870971935426, 8093.797655058553, 8245.175482451707, 8251.876221856677, 8313.833723325499, 8367.121452247547, 3983.9044262827956, 4313.460817397825, 4403.251447253974, 4543.204939135638, 4600.171969045604, 1655.809581898073, 1670.6324608586092, 1898.1363479616741, 1998.7701414337537, 2001.6497113005166, 860.0625318402971, 882.4484697064335, 926.893407258174, 927.888653361601, 960.6685693435893, 19819.0, 20359.287424940056, 20469.0, 20486.59026819455, 20787.168513259603, 854.1927878155208, 909.3589019741199, 939.9671011514564, 1000.6397696829135, 1033.1403473747225, 10358.378497290187, 10511.474426278655, 10515.84226236607, 10537.473126343806, 10552.208584356204, 571520.0, 582972.0, 583504.0, 583774.0, 585080.0, 76644.0, 97353.51323243338, 108674.0, 112720.43639781748, 122459.0, 934.9065093490597, 953.6328513522344, 963.1524258652481, 970.3256236915418, 980.6910823090847, 14311.856881431053, 18104.637907242046, 18965.810341896406, 18975.90512047431, 19109.14008869632, 49898.253561126636, 53938.0, 54177.56200971045, 56958.14562781645, 58747.11879321899, 77705.2229477698, 79810.20189798028, 80499.59750201211, 82136.58931705303, 83752.58123709343, 7010.649467526705, 7874.409419293462, 7975.4018448615425, 8022.719204827803, 8028.0, 10531.473426328806, 12511.812322815347, 12660.936695316464, 12702.745945080862, 12886.355682216039, 9052.864207036768, 10612.840807387742, 10793.62222322215, 10806.189535785004, 11091.72270693239, 10658.73353166146, 10697.037266646039, 10930.344179349264, 10975.725606859893, 11021.669349919195, 8792.516411597424, 9239.72280831576, 9610.375325603813, 9700.417974921527, 10969.945150274516, 6049.546284028627, 7300.342969132804, 7425.071866016748, 7442.06974128234, 7514.549127052176, 8415.621297041525, 10811.189160812813, 10998.0, 11087.113030957342, 11228.045616122394], "value": 9062.275579198922, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.885939695126, 1440.2510694438893, 1591.8965267257634, 1728.3777839977597, 1774.8668849836265, 7621.199774023717, 7851.175626559215, 8166.510009399432, 8609.35429842762, 8627.999999999993, 7845.744680851065, 7878.172791856859, 8090.797730056737, 8422.0, 8479.109693482173, 2294.4608017115966, 2323.337848713113, 2357.7642235776457, 2361.5749165150264, 2370.0, 2345.495718420539, 2378.2389635316704, 2397.4845408237225, 2402.8318017738766, 2405.518896220754, 2339.894704738291, 2347.260612906929, 2361.6103342948395, 2374.5844477216506, 2381.071382160959, 2340.3330050935488, 2346.7066616672914, 2363.976360236393, 2365.042157926043, 2369.5024044950583, 2359.3511784259467, 2373.3117395955323, 2381.297517232403, 2387.462820865309, 2390.964135537968, 8482.448640838325, 8490.617922193516, 8534.274586660142, 8551.273141782956, 8558.529280889548, 5140.383154021504, 5220.060389129955, 5275.103232450492, 5282.471752824726, 5285.973570132163, 3476.7392445566684, 3495.4756786481976, 3525.083478295636, 3556.7332450066347, 3573.2674801665858, 1036.2694300518117, 1043.7234132954773, 1093.103655002898, 1107.1087774341652, 1108.5288752280248, 11038.779224415546, 11160.995510404022, 11191.888081119247, 11214.215004949658, 11239.100871930226, 4469.821207151743, 4657.301404789241, 4733.928991065137, 4746.098241334135, 4757.881052973703, 9450.669226577036, 9500.572474238676, 9501.857472137923, 9531.714048578553, 9537.093976072261, 5135.38375394955, 5214.713190774468, 5225.764840582183, 5233.110371236899, 5259.342582177228, 4274.764887931194, 4284.978575107105, 4746.145693775154, 4748.62011039123, 4805.8077676892535, 3851.4607954886337, 3879.941800872989, 3933.331333673282, 3958.327084395602, 3967.444557761916, 1270.2188154285047, 1271.6947932496134, 1280.9679758006125, 1282.2498838179583, 1287.1633438265014, 753.773867839646, 887.689308741948, 903.1059251341125, 904.7128304728654, 921.3320342751585, 5835.270591176103, 5851.268591426072, 5880.206172166702, 5963.105534169822, 6117.877642447126, 820.8754007010315, 845.7927807687033, 892.3352102683413, 892.5894088719132, 902.4540153207215, 3789.3558095123894, 3868.477755502971, 3898.7075969302414, 3901.4147877818555, 3933.3903244997073, 9954.601815927283, 10130.898691013143, 10296.485175741183, 10328.328658637165, 10340.844887326695, 9973.650922217688, 9992.0, 10114.393136411838, 10121.443320617436, 10202.336848104998, 7460.142083660448, 7779.3776497880035, 7875.56684382353, 7922.207779222032, 7943.44395892282, 5578.386377498391, 5608.439156084359, 5798.0143375627085, 5936.020556608111, 5942.910856337072, 9265.768355791159, 9279.953600231956, 9357.906420935704, 9524.666636667684, 9563.474008929576, 8597.656093756179, 9509.381890177116, 9597.560365944884, 9609.951950240204, 9752.073553012453, 6599.109120268702, 6805.693743781443, 6957.826054348479, 7080.398166155728, 7224.638768061681, 7873.724419645285, 8014.519128852287, 8031.437799353989, 8033.799155020937, 8046.919530804851, 7203.531770434905, 7300.087489063867, 7378.631068446663, 7472.738454154079, 7477.476576639583, 7051.788446346618, 7165.856682866418, 7188.7124515020905, 7212.495125341386, 7235.565866048054, 6720.092787473628, 6825.180978282638, 6847.1098757163245, 6859.039734437083, 6888.690008949509, 4817.108834865561, 4853.029394121119, 4926.975365123151, 4938.407391113089, 4974.701517908937, 4307.504636966789, 4709.599684026759, 4891.26631005352, 4901.044296362175, 4905.828296009622], "value": 4471.546624601024, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.7913691710427, 300.3887089772314, 306.74386886949395, 308.64659964340814, 310.469097842689, 4343.9348409773875, 4433.578810013051, 4970.042824519414, 5524.419935906727, 5602.011285993475, 4425.0, 4567.949371644516, 5257.789688412459, 5311.601629877755, 5445.9727701361535, 3253.8535765890592, 4180.456540649711, 4180.979095104521, 4189.706720529564, 4206.137741762931, 3476.922154132227, 3639.8180090995475, 4172.852465571962, 4210.094829611633, 4215.620594146526, 3703.2778608171416, 4042.4542686737354, 4056.574059723731, 4071.42999980003, 4099.052949848828, 3526.682598566141, 3920.509936257982, 4042.818073186699, 4058.5941405859176, 4078.4909583454014, 2789.679186893513, 3288.358770039843, 3527.3650742866275, 3539.4159963605975, 3544.556930383702, 4974.825881094144, 5004.824831130929, 5355.7857685692525, 5370.275012873251, 5427.945720542783, 4511.864644060651, 4616.53834616539, 4657.930131048037, 4684.243408721695, 4689.929651055237, 4565.420537396393, 4918.9754051229875, 5242.951409718072, 5456.890862182774, 5675.404082571312, 3678.44823276508, 3735.4957080794015, 3856.9614303857165, 3863.459115723801, 3867.864624738149, 5051.0, 6037.577369584131, 6053.793737385845, 6075.27096748393, 6096.390360963913, 3659.3779057560278, 3959.0109467351963, 3963.96936796435, 3973.562908080109, 3976.483057202603, 3772.490713753663, 3796.551947954694, 3817.9809100954317, 3845.134844659929, 4029.7179197456485, 3901.132667489393, 4310.676699247569, 4361.862687283272, 4680.976595117071, 4706.247000479907, 5297.708626025528, 5780.595358324961, 5840.29916410025, 5904.409559044061, 5919.970400147971, 1055.9229585822418, 1068.7648717282186, 1241.8696036916174, 1290.6837824733, 1303.7131830997166, 777.2848978939277, 857.5626430520355, 864.8627055422099, 876.9473831570126, 884.1467983396054, 4744.40160610483, 4935.8025678972435, 4973.577245934136, 5202.3497062867145, 5208.166693329125, 4854.101991131652, 5406.648567843077, 5421.403645598981, 5690.630109042899, 5712.057510510802, 4807.78364973577, 4859.319695242669, 5172.525830138393, 5198.896022079537, 5199.686139667811, 2515.9748402515743, 2720.1567514070703, 2921.7954743167775, 2961.8822541882228, 2972.539256415259, 4740.478547359788, 5374.184329126364, 5584.6038490377405, 5869.325027621793, 6007.519398448114, 4721.379634302994, 5721.284839395075, 5849.830033993218, 5849.912251316234, 5850.619709718854, 4529.750863702528, 4987.376577927759, 5528.364238112588, 5788.710564471759, 5790.7973220937065, 4728.645351598575, 4885.682430641997, 5320.255164276927, 5577.44225577439, 5605.673645437261, 5320.707361095177, 5546.500814926676, 5612.0, 5630.718464076862, 5638.5489160867855, 4856.68431551948, 4946.258061290835, 5407.594025553463, 5696.718238396494, 5834.920539700169, 3705.5923848377197, 3715.851365945332, 3750.981245093757, 3751.9249615008093, 3757.28611563802, 5042.218456139304, 5210.436868939379, 5507.779688812563, 5527.364353099445, 5635.7601327707835, 4953.108440480748, 5229.424763276113, 5439.782408703608, 5496.917546236889, 5513.944860551504, 5074.162763144188, 5398.433164517669, 5402.297701298806, 5432.809851655173, 5503.981763373788, 4599.701019433726, 5334.013207556614, 5399.568034557305, 5454.063807348492, 5709.0, 5072.746362681925, 5791.059994901755, 5813.593048486566, 5833.79581714638, 5834.38738932406, 4715.180703630071, 4876.975615121902, 5259.47405259471, 5558.972205138948, 5693.28833895763], "value": 4026.4318068961015, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.5284677951199, 668.7859884836851, 717.802604283822, 722.3174100475053, 768.565760345405, 11184.0, 12644.0, 13439.93280033601, 13574.796378054338, 13607.251601161932, 12362.814557781641, 12670.873291267084, 12761.170523915938, 12777.233365998047, 12779.361031948389, 764.5641984069083, 774.5817258680306, 781.4373650971291, 783.9412044096687, 788.7830846517196, 622.4553515673786, 644.3974883483934, 760.5854809129038, 774.7714424244862, 779.5283853268776, 815.9469634473741, 828.5401602110826, 828.7679449754079, 829.3075282139421, 830.8670612701969, 821.1788211788212, 823.5964377455003, 827.735124760077, 827.954462504562, 828.9212524810133, 599.8260504453704, 602.340437221242, 605.7486143250545, 607.0469363099935, 608.6013661052018, 11482.655520334403, 13466.461341546326, 13559.796603050961, 13952.58142255734, 13982.091164074405, 12005.579804706793, 12318.199317044453, 12350.44423000967, 12353.135280530367, 13550.390232439564, 12134.271943683407, 12179.391030448442, 12285.201461904948, 12332.445039973223, 12369.75260494794, 3317.9004629861142, 3493.441049432104, 3817.0, 4391.956080439187, 4393.077453734688, 69870.95193572101, 74355.62822185854, 82449.76325355124, 84846.57576712077, 94716.52641736747, 9461.290403219786, 9469.526523673789, 9624.278179136592, 9696.030396960248, 9701.611935522642, 18808.81191188098, 19111.331103411314, 19129.426117216506, 19182.616347672967, 19212.903935480233, 7459.776206713807, 7470.252974702595, 7617.238276172338, 7628.0464941882265, 7911.564863932539, 4210.7473551587, 4270.7010509264055, 4291.721038132511, 4377.255866502702, 4444.400005999213, 3241.5785947827103, 3312.685294896981, 3340.6993370596765, 3340.883069092619, 3352.547406100145, 609.7195290166574, 658.4633523678187, 660.5871330418488, 661.5633681770008, 661.5964261800298, 556.290729320112, 568.8037627018672, 604.8911195984678, 631.7188850961278, 640.0303540136697, 7795.727149549739, 8576.914230857736, 8632.525211113449, 8763.502401815453, 9075.954620226988, 571.9714014299268, 619.127030886448, 640.3980258556942, 642.2870613618879, 650.8340373204898, 4283.336082907154, 4320.179165958458, 4339.392485052095, 4364.5635436456105, 4430.13612345596, 115379.84620153859, 122375.38812306059, 122544.38727806482, 122940.0, 125132.0, 65734.0, 69654.0, 82010.21703705977, 106041.46979265055, 108928.0, 62746.0, 65790.34209657938, 79672.00819979547, 83127.5843620778, 83279.91800205043, 7193.928060719326, 7616.809579760328, 7837.216278372117, 9443.197328227177, 9589.280803939593, 43815.78092109646, 49849.75375615493, 52553.21170182527, 53767.731161343945, 54511.72744136254, 54449.72775136099, 56236.718816407294, 70420.64789676019, 75806.0, 77625.83561246473, 10090.646827361006, 12051.216670916363, 12413.813792792935, 12547.121701480808, 12649.557265495663, 13372.197668139554, 13692.726145476836, 13763.724725505634, 13828.377723002288, 14305.713885722436, 12897.0, 13119.540816071392, 13280.80078798838, 13394.93302533481, 13425.66435839112, 12702.237865728086, 12793.744125117631, 12808.807867881802, 12994.895433888225, 13190.73618527605, 12652.304123273309, 12662.683432913882, 12679.556215532411, 12808.423620937267, 12884.098113131991, 8896.510691912006, 9028.864567031631, 10211.387316761018, 11046.723831904266, 11278.0, 10927.289726167774, 11181.273217240854, 11448.484818183035, 11510.59712910044, 11602.76794464123], "value": 7012.989305248897, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1634.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 121.0, 118.0, 117.0, 119.0, 97.0, 172.0, 173.0, 118.0, 117.0], "value": 126.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 115.0, 151.0, 116.0, 121.0, 119.0, 122.0, 150.0, 124.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 198.0, 274.0, 278.0, 198.0, 214.0, 273.0, 205.0, 197.0], "value": 223.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 62.0, 60.0, 85.0, 62.0, 68.0, 81.0, 83.0, 91.0, 61.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 105.0, 123.0, 104.0, 60.0, 58.0, 99.0, 66.0, 76.0, 120.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 88.0, 133.0, 131.0, 116.0, 139.0, 113.0, 92.0, 87.0], "value": 108.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 145.0, 104.0, 100.0, 154.0, 154.0, 98.0, 99.0, 98.0], "value": 115.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 86.0, 89.0, 89.0, 88.0, 89.0, 91.0, 87.0, 129.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 54.0, 66.0, 60.0, 61.0, 61.0, 60.0, 60.0, 61.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 72.0, 92.0, 72.0, 76.0, 88.0, 105.0, 107.0, 109.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 98.0, 87.0, 86.0, 83.0, 87.0, 85.0, 89.0, 98.0, 88.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 165.0, 163.0, 162.0, 150.0, 156.0, 150.0, 175.0, 218.0, 154.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 235.0, 232.0, 154.0, 227.0, 173.0, 233.0, 189.0, 189.0, 195.0], "value": 199.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 73.0, 82.0, 95.0, 91.0, 90.0, 69.0, 68.0, 69.0, 78.0], "value": 77.6, "unit": "score"}]}]} 07:53:27 INFO - Return code: 0 07:53:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:53:27 ERROR - # TBPL SUCCESS # 07:53:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:27 INFO - 2016-12-05 07:53:27.990 screenresolution[1666:11352] starting screenresolution argv=screenresolution get 07:53:27 INFO - 2016-12-05 07:53:27.993 screenresolution[1666:11352] Display 0: 1600x1200x32@60 07:53:28 INFO - 2016-12-05 07:53:27.999 screenresolution[1667:11356] starting screenresolution argv=screenresolution list 07:53:28 INFO - Available Modes on Display 0 07:53:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:28 INFO - Intel Iris: 07:53:28 INFO - Chipset Model: Intel Iris 07:53:28 INFO - Type: GPU 07:53:28 INFO - Bus: Built-In 07:53:28 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:28 INFO - Vendor: Intel (0x8086) 07:53:28 INFO - Device ID: 0x0a2e 07:53:28 INFO - Revision ID: 0x0009 07:53:28 INFO - Displays: 07:53:28 INFO - Display: 07:53:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:28 INFO - Display Serial Number: 1600x1200 60 07:53:28 INFO - Main Display: Yes 07:53:28 INFO - Mirror: Off 07:53:28 INFO - Online: Yes 07:53:28 INFO - Rotation: Supported 07:53:28 INFO - Return code: 0 07:53:28 INFO - Running post-action listener: _resource_record_post_action 07:53:28 INFO - [mozharness: 2016-12-05 15:53:28.243919Z] Finished run-tests step (success) 07:53:28 INFO - Running post-run listener: _resource_record_post_run 07:53:28 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33300992; Write bytes: 661237248; Read time: 209; Write time: 1917 07:53:28 INFO - TinderboxPrint: CPU usage
54.4% 07:53:28 INFO - TinderboxPrint: I/O read bytes / time
33,300,992 / 209 07:53:28 INFO - TinderboxPrint: I/O write bytes / time
661,237,248 / 1,917 07:53:28 INFO - TinderboxPrint: CPU idle
2,211.5 (45.6%) 07:53:28 INFO - TinderboxPrint: CPU system
980.2 (20.2%) 07:53:28 INFO - TinderboxPrint: CPU user
1,657.9 (34.2%) 07:53:28 INFO - TinderboxPrint: Swap in / out
378,843,136 / 0 07:53:28 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 349706752; Write bytes: 351200256; Read time: 27769; Write time: 739 07:53:28 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30118400; Write bytes: 308697600; Read time: 184; Write time: 1171 07:53:28 INFO - Running post-run listener: _upload_blobber_files 07:53:28 INFO - Blob upload gear active. 07:53:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:53:28 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 07:53:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:53:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:53:29 INFO - (blobuploader) - INFO - Open directory for files ... 07:53:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:53:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:53:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:30 INFO - (blobuploader) - INFO - Done attempting. 07:53:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:53:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:53:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:31 INFO - (blobuploader) - INFO - Done attempting. 07:53:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:53:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:53:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:34 INFO - (blobuploader) - INFO - Done attempting. 07:53:34 INFO - (blobuploader) - INFO - Iteration through files over. 07:53:34 INFO - Return code: 0 07:53:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:53:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:53:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06b81eab9d164a571a6d59b5fbf1a7abd636bca2df03c491e17a20d3a7ff0b1d7c538b425d76f139cfe17accbdb3e391913b857d37ba7b2bacda9a836d4933fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9a72d6ed9ff28f8136127586af55982b3b344e54d0ed98f50774c52185d8bd28040ea9f523845b592c35cea201460da452bd88365229befdba9e05f8f6ff929", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2bf8c887c2952c1c517dc7d46517383337b54ea0e035eb0ab38d2ae33e1cfdf56a4be1599dcfa6ba4b4824b69098ef207292dcb6e50552e1c55a1576398ac0c"} 07:53:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:53:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:53:34 INFO - Contents: 07:53:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06b81eab9d164a571a6d59b5fbf1a7abd636bca2df03c491e17a20d3a7ff0b1d7c538b425d76f139cfe17accbdb3e391913b857d37ba7b2bacda9a836d4933fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9a72d6ed9ff28f8136127586af55982b3b344e54d0ed98f50774c52185d8bd28040ea9f523845b592c35cea201460da452bd88365229befdba9e05f8f6ff929", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2bf8c887c2952c1c517dc7d46517383337b54ea0e035eb0ab38d2ae33e1cfdf56a4be1599dcfa6ba4b4824b69098ef207292dcb6e50552e1c55a1576398ac0c"} 07:53:34 INFO - Running post-run listener: copy_logs_to_upload_dir 07:53:34 INFO - Copying logs to upload dir... 07:53:34 INFO - mkdir: /builds/slave/test/build/upload/logs 07:53:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.111544 ========= master_lag: 2.40 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-05 07:53:36.435531) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-05 07:53:36.438320) ========= 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.R9E3chBSez/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.gzwZSHiIlx/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06b81eab9d164a571a6d59b5fbf1a7abd636bca2df03c491e17a20d3a7ff0b1d7c538b425d76f139cfe17accbdb3e391913b857d37ba7b2bacda9a836d4933fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9a72d6ed9ff28f8136127586af55982b3b344e54d0ed98f50774c52185d8bd28040ea9f523845b592c35cea201460da452bd88365229befdba9e05f8f6ff929", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2bf8c887c2952c1c517dc7d46517383337b54ea0e035eb0ab38d2ae33e1cfdf56a4be1599dcfa6ba4b4824b69098ef207292dcb6e50552e1c55a1576398ac0c"} build_url:https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/DmEvLPwlSyiQls-Hv-KWMw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06b81eab9d164a571a6d59b5fbf1a7abd636bca2df03c491e17a20d3a7ff0b1d7c538b425d76f139cfe17accbdb3e391913b857d37ba7b2bacda9a836d4933fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9a72d6ed9ff28f8136127586af55982b3b344e54d0ed98f50774c52185d8bd28040ea9f523845b592c35cea201460da452bd88365229befdba9e05f8f6ff929", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b2bf8c887c2952c1c517dc7d46517383337b54ea0e035eb0ab38d2ae33e1cfdf56a4be1599dcfa6ba4b4824b69098ef207292dcb6e50552e1c55a1576398ac0c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-05 07:53:36.474374) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 07:53:36.474705) ========= 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.R9E3chBSez/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.gzwZSHiIlx/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.005299 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 07:53:36.528157) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 07:53:36.528510) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 07:53:36.528857) ========= ========= Total master_lag: 2.54 =========