builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0226 starttime: 1479554533.22 results: success (0) buildid: 20161119004003 builduid: 060f863f9fd447e3a08e85078a58eb3a revision: 48f5a1870ec3974ddf717576e6e54d345e476677 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:13.217769) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:13.218215) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:13.218493) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:13.244580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:13.245000) ========= 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.z3AYUogbJS/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.t8I3Q0v08X/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-19 03:22:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 173M=0s 2016-11-19 03:22:13 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.516128 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:13.784146) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:13.784533) ========= 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.z3AYUogbJS/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.t8I3Q0v08X/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.024734 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:13.832879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 03:22:13.833246) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 48f5a1870ec3974ddf717576e6e54d345e476677 --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 48f5a1870ec3974ddf717576e6e54d345e476677 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.z3AYUogbJS/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.t8I3Q0v08X/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-19 03:22:13,935 truncating revision to first 12 chars 2016-11-19 03:22:13,935 Setting DEBUG logging. 2016-11-19 03:22:13,936 attempt 1/10 2016-11-19 03:22:13,936 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/48f5a1870ec3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-19 03:22:14,909 unpacking tar archive at: mozilla-aurora-48f5a1870ec3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.255961 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 03:22:15.113043) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:15.113368) ========= script_repo_revision: 48f5a1870ec3974ddf717576e6e54d345e476677 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:15.114168) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:15.114478) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 03:22:15.133145) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-19 03:22:15.133499) ========= /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.z3AYUogbJS/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.t8I3Q0v08X/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:22:15 INFO - MultiFileLogger online at 20161119 03:22:15 in /builds/slave/test 03:22:15 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 03:22:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:22:15 INFO - {'append_to_log': False, 03:22:15 INFO - 'base_work_dir': '/builds/slave/test', 03:22:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:22:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:22:15 INFO - 'branch': 'Mozilla-Aurora', 03:22:15 INFO - 'buildbot_json_path': 'buildprops.json', 03:22:15 INFO - 'config_files': ('talos/mac_config.py',), 03:22:15 INFO - 'default_actions': ('clobber', 03:22:15 INFO - 'read-buildbot-config', 03:22:15 INFO - 'download-and-extract', 03:22:15 INFO - 'populate-webroot', 03:22:15 INFO - 'create-virtualenv', 03:22:15 INFO - 'install', 03:22:15 INFO - 'run-tests'), 03:22:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:22:15 INFO - 'download_minidump_stackwalk': True, 03:22:15 INFO - 'download_symbols': 'ondemand', 03:22:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:22:15 INFO - 'tooltool.py': '/tools/tooltool.py', 03:22:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:22:15 INFO - '/tools/misc-python/virtualenv.py')}, 03:22:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:22:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:22:15 INFO - 'installer_path': 'installer.exe', 03:22:15 INFO - 'log_level': 'info', 03:22:15 INFO - 'log_name': 'talos', 03:22:15 INFO - 'log_to_console': True, 03:22:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:22:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:22:15 INFO - 'opt_config_files': (), 03:22:15 INFO - 'pip_index': False, 03:22:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:22:15 INFO - 'cmd': ('bash', 03:22:15 INFO - '-c', 03:22:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:22:15 INFO - 'enabled': True, 03:22:15 INFO - 'halt_on_failure': False, 03:22:15 INFO - 'name': 'check_screen_resolution'},), 03:22:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:22:15 INFO - 'cmd': ('bash', 03:22:15 INFO - '-c', 03:22:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:22:15 INFO - 'enabled': True, 03:22:15 INFO - 'halt_on_failure': False, 03:22:15 INFO - 'name': 'check_screen_resolution'},), 03:22:15 INFO - 'run_cmd_checks_enabled': True, 03:22:15 INFO - 'sps_profile': False, 03:22:15 INFO - 'sps_profile_interval': 0, 03:22:15 INFO - 'suite': 'dromaeojs-e10s', 03:22:15 INFO - 'system_bits': '32', 03:22:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:22:15 INFO - 'title': 't-yosemite-r7-0226', 03:22:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:22:15 INFO - 'use_talos_json': True, 03:22:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:22:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:22:15 INFO - 'work_dir': 'build'} 03:22:15 INFO - [mozharness: 2016-11-19 11:22:15.342460Z] Running clobber step. 03:22:15 INFO - Running pre-action listener: _resource_record_pre_action 03:22:15 INFO - Running main action method: clobber 03:22:15 INFO - rmtree: /builds/slave/test/build 03:22:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:22:17 INFO - Running post-action listener: _resource_record_post_action 03:22:17 INFO - [mozharness: 2016-11-19 11:22:17.931802Z] Finished clobber step (success) 03:22:17 INFO - [mozharness: 2016-11-19 11:22:17.931877Z] Running read-buildbot-config step. 03:22:17 INFO - Running pre-action listener: _resource_record_pre_action 03:22:17 INFO - Running main action method: read_buildbot_config 03:22:17 INFO - Using buildbot properties: 03:22:17 INFO - { 03:22:17 INFO - "project": "", 03:22:17 INFO - "product": "firefox", 03:22:17 INFO - "script_repo_revision": "production", 03:22:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:22:17 INFO - "repository": "", 03:22:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:22:17 INFO - "buildid": "20161119004003", 03:22:17 INFO - "stage_platform": "macosx64", 03:22:17 INFO - "basedir": "/builds/slave/test", 03:22:17 INFO - "nightly_build": "True", 03:22:17 INFO - "buildnumber": 30, 03:22:17 INFO - "slavename": "t-yosemite-r7-0226", 03:22:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 03:22:17 INFO - "platform": "yosemite_r7", 03:22:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:22:17 INFO - "branch": "mozilla-aurora", 03:22:17 INFO - "repo_path": "releases/mozilla-aurora", 03:22:17 INFO - "revision": "48f5a1870ec3974ddf717576e6e54d345e476677", 03:22:17 INFO - "pgo_build": "False", 03:22:17 INFO - "builduid": "060f863f9fd447e3a08e85078a58eb3a", 03:22:17 INFO - "slavebuilddir": "test" 03:22:17 INFO - } 03:22:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:22:17 INFO - Running post-action listener: _resource_record_post_action 03:22:17 INFO - [mozharness: 2016-11-19 11:22:17.937654Z] Finished read-buildbot-config step (success) 03:22:17 INFO - [mozharness: 2016-11-19 11:22:17.937854Z] Running download-and-extract step. 03:22:17 INFO - Running pre-action listener: _resource_record_pre_action 03:22:17 INFO - Running main action method: download_and_extract 03:22:17 INFO - mkdir: /builds/slave/test/build/tests 03:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:17 INFO - trying https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:22:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/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 03:22:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/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 03:22:19 INFO - Downloaded 1199 bytes. 03:22:19 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:22:19 INFO - Using the following test package requirements: 03:22:19 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:22:19 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:19 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:22:19 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:19 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:22:19 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:19 INFO - u'jsshell-mac.zip'], 03:22:19 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:19 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:22:19 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:22:19 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:19 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:22:19 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:19 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:22:19 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:19 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:22:19 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:19 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:22:19 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:22:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:22:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:22:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:22:19 INFO - Http code: 200 03:22:19 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 03:22:19 INFO - x-amz-cf-id: IOn8Lo8thCSLBvVU6PVGKFQsqd6igBWy1I_UnL49N_Dxln26oR5gpQ== 03:22:19 INFO - x-amz-version-id: gwTtAtzt2oN51OfUENwI4XsdsojpoRrP 03:22:20 INFO - Expected file size: 16274978 03:22:20 INFO - Obtained file size: 16274978 03:22:27 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 03:22:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:22:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:22:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:22:27 INFO - Http code: 200 03:22:27 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 03:22:27 INFO - x-amz-cf-id: 2Uj7_90paxdpokct4d_mdpMdE1_JlO80_5sQ_Bef7FU2GG7im-AglA== 03:22:27 INFO - x-amz-version-id: gwTtAtzt2oN51OfUENwI4XsdsojpoRrP 03:22:27 INFO - Expected file size: 16274978 03:22:27 INFO - Obtained file size: 16274978 03:22:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:22:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:22:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:22:34 INFO - Http code: 200 03:22:34 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 03:22:34 INFO - x-amz-cf-id: l0g34uHTpmINWon64-OoqyYbmXUIBIKETKbYtbKVOHIj-Nknkmt8ww== 03:22:34 INFO - x-amz-version-id: vgdCCsRjMgeTA6xkEBylwMKRES6E6kFI 03:22:35 INFO - Expected file size: 14409038 03:22:35 INFO - Obtained file size: 14409038 03:22:35 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')]} 03:22:35 INFO - trying https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:22:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:22:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/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 03:22:38 INFO - Downloaded 114481502 bytes. 03:22:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:22:38 INFO - mkdir: /builds/slave/test/properties 03:22:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:22:38 INFO - Writing to file /builds/slave/test/properties/build_url 03:22:38 INFO - Contents: 03:22:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:22:39 INFO - Running post-action listener: _resource_record_post_action 03:22:39 INFO - Running post-action listener: set_extra_try_arguments 03:22:39 INFO - [mozharness: 2016-11-19 11:22:39.820185Z] Finished download-and-extract step (success) 03:22:39 INFO - [mozharness: 2016-11-19 11:22:39.820307Z] Running populate-webroot step. 03:22:39 INFO - Running pre-action listener: _resource_record_pre_action 03:22:39 INFO - Running main action method: populate_webroot 03:22:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:22:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:22:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:22:39 INFO - '--tppagecycles', 03:22:39 INFO - '7'], 03:22:39 INFO - 'tests': ['tsvgm']}}, 03:22:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:22:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:22:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:22:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:22:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:39 INFO - 'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:22:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:22:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:39 INFO - 'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['damp', 'tps']}, 03:22:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:39 INFO - 'tests': ['damp', 'tps']}, 03:22:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['dromaeo_dom']}, 03:22:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:22:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['basic_compositor_video']}, 03:22:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:22:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['a11yr', 03:22:39 INFO - 'ts_paint', 03:22:39 INFO - 'tpaint', 03:22:39 INFO - 'sessionrestore', 03:22:39 INFO - 'sessionrestore_no_auto_restore', 03:22:39 INFO - 'tabpaint']}, 03:22:39 INFO - 'other-e10s': {'tests': ['a11yr', 03:22:39 INFO - 'ts_paint', 03:22:39 INFO - 'tpaint', 03:22:39 INFO - 'sessionrestore', 03:22:39 INFO - 'sessionrestore_no_auto_restore', 03:22:39 INFO - 'tabpaint']}, 03:22:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:22:39 INFO - 'ts_paint', 03:22:39 INFO - 'tpaint', 03:22:39 INFO - 'sessionrestore', 03:22:39 INFO - 'sessionrestore_no_auto_restore', 03:22:39 INFO - 'tabpaint']}, 03:22:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:22:39 INFO - 'ts_paint', 03:22:39 INFO - 'tpaint', 03:22:39 INFO - 'sessionrestore', 03:22:39 INFO - 'sessionrestore_no_auto_restore', 03:22:39 INFO - 'tabpaint']}, 03:22:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['a11yr', 03:22:39 INFO - 'ts_paint', 03:22:39 INFO - 'tpaint', 03:22:39 INFO - 'sessionrestore', 03:22:39 INFO - 'sessionrestore_no_auto_restore', 03:22:39 INFO - 'tabpaint']}, 03:22:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['a11yr', 03:22:39 INFO - 'ts_paint', 03:22:39 INFO - 'tpaint', 03:22:39 INFO - 'sessionrestore', 03:22:39 INFO - 'sessionrestore_no_auto_restore', 03:22:39 INFO - 'tabpaint']}, 03:22:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['tsvgx', 03:22:39 INFO - 'tsvgr_opacity', 03:22:39 INFO - 'tart', 03:22:39 INFO - 'tscrollx', 03:22:39 INFO - 'cart']}, 03:22:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:22:39 INFO - 'tsvgr_opacity', 03:22:39 INFO - 'tart', 03:22:39 INFO - 'tscrollx', 03:22:39 INFO - 'cart']}, 03:22:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:39 INFO - 'talos_options': ['--disable-e10s'], 03:22:39 INFO - 'tests': ['tp5o']}, 03:22:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:39 INFO - 'tests': ['tp5o']}, 03:22:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:39 INFO - 'talos_options': ['--disable-e10s', 03:22:39 INFO - '--xperf_path', 03:22:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:22:39 INFO - 'tests': ['tp5n']}, 03:22:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:39 INFO - 'talos_options': ['--xperf_path', 03:22:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:22:39 INFO - 'tests': ['tp5n']}}, 03:22:39 INFO - 'talos.zip': {'path': '', 03:22:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:22:39 INFO - Running post-action listener: _resource_record_post_action 03:22:39 INFO - [mozharness: 2016-11-19 11:22:39.838824Z] Finished populate-webroot step (success) 03:22:39 INFO - [mozharness: 2016-11-19 11:22:39.838933Z] Running create-virtualenv step. 03:22:39 INFO - Running pre-action listener: _resource_record_pre_action 03:22:39 INFO - Running main action method: create_virtualenv 03:22:39 INFO - Creating virtualenv /builds/slave/test/build/venv 03:22:39 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 03:22:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:22:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:22:39 INFO - Using real prefix '/tools/python27' 03:22:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:22:40 INFO - Installing distribute.............................................................................................................................................................................................done. 03:22:43 INFO - Installing pip.................done. 03:22:43 INFO - Return code: 0 03:22:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:22:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:22:43 INFO - Reading from file tmpfile_stdout 03:22:43 INFO - Output received: 03:22:43 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) 03:22:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:43 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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:22:43 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 03:22:43 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 03:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:43 INFO - 'HOME': '/Users/cltbld', 03:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:43 INFO - 'LOGNAME': 'cltbld', 03:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:43 INFO - 'MOZ_NO_REMOTE': '1', 03:22:43 INFO - 'NO_EM_RESTART': '1', 03:22:43 INFO - 'PAGER': '/bin/cat', 03:22:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:43 INFO - 'PWD': '/builds/slave/test', 03:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:43 INFO - 'SHELL': '/bin/bash', 03:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:22:43 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:43 INFO - 'USER': 'cltbld', 03:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:43 INFO - 'XPC_FLAGS': '0x0', 03:22:43 INFO - 'XPC_SERVICE_NAME': '0', 03:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:44 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 03:22:44 INFO - Cleaning up... 03:22:44 INFO - Return code: 0 03:22:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:22:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:44 INFO - 'HOME': '/Users/cltbld', 03:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:44 INFO - 'LOGNAME': 'cltbld', 03:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:44 INFO - 'MOZ_NO_REMOTE': '1', 03:22:44 INFO - 'NO_EM_RESTART': '1', 03:22:44 INFO - 'PAGER': '/bin/cat', 03:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:44 INFO - 'PWD': '/builds/slave/test', 03:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:44 INFO - 'SHELL': '/bin/bash', 03:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:22:44 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:44 INFO - 'USER': 'cltbld', 03:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:44 INFO - 'XPC_FLAGS': '0x0', 03:22:44 INFO - 'XPC_SERVICE_NAME': '0', 03:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:44 INFO - Downloading/unpacking psutil>=3.1.1 03:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:22:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:48 INFO - Installing collected packages: psutil 03:22:48 INFO - Running setup.py install for psutil 03:22:48 INFO - building 'psutil._psutil_osx' extension 03:22:48 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 03:22:48 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 03:22:48 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 03:22:49 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 03:22:49 INFO - building 'psutil._psutil_posix' extension 03:22:49 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 03:22:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:22:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:22:49 INFO - ^ 03:22:49 INFO - 1 warning generated. 03:22:49 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 03:22:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:49 INFO - Successfully installed psutil 03:22:49 INFO - Cleaning up... 03:22:49 INFO - Return code: 0 03:22:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:22:49 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')]} 03:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:49 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')]} 03:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:49 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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:22:49 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 03:22:49 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 03:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:49 INFO - 'HOME': '/Users/cltbld', 03:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:49 INFO - 'LOGNAME': 'cltbld', 03:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:49 INFO - 'MOZ_NO_REMOTE': '1', 03:22:49 INFO - 'NO_EM_RESTART': '1', 03:22:49 INFO - 'PAGER': '/bin/cat', 03:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:49 INFO - 'PWD': '/builds/slave/test', 03:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:49 INFO - 'SHELL': '/bin/bash', 03:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:22:49 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:49 INFO - 'USER': 'cltbld', 03:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:49 INFO - 'XPC_FLAGS': '0x0', 03:22:49 INFO - 'XPC_SERVICE_NAME': '0', 03:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:22:49 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 03:22:49 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 03:22:49 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 03:22:49 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 03:22:49 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 03:22:49 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 03:22:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:22:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:22:52 INFO - Installing collected packages: mozsystemmonitor 03:22:52 INFO - Running setup.py install for mozsystemmonitor 03:22:52 INFO - Successfully installed mozsystemmonitor 03:22:52 INFO - Cleaning up... 03:22:52 INFO - Return code: 0 03:22:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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', '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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:22: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:22: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 jsonschema==2.5.1 03:22:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:22:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:52 INFO - 'HOME': '/Users/cltbld', 03:22:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:52 INFO - 'LOGNAME': 'cltbld', 03:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:52 INFO - 'MOZ_NO_REMOTE': '1', 03:22:52 INFO - 'NO_EM_RESTART': '1', 03:22:52 INFO - 'PAGER': '/bin/cat', 03:22:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:52 INFO - 'PWD': '/builds/slave/test', 03:22:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:52 INFO - 'SHELL': '/bin/bash', 03:22:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:22:52 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:22:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:52 INFO - 'USER': 'cltbld', 03:22:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:52 INFO - 'XPC_FLAGS': '0x0', 03:22:52 INFO - 'XPC_SERVICE_NAME': '0', 03:22:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:52 INFO - Downloading/unpacking jsonschema==2.5.1 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:22:55 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 03:22:55 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 03:22:55 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 03:22:55 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 03:22:55 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 03:22:55 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 03:22:55 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:55 INFO - warning: no files found matching '*.txt' 03:22:55 INFO - no previously-included directories found matching 'build' 03:22:55 INFO - no previously-included directories found matching 'dist' 03:22:55 INFO - no previously-included directories found matching '.git*' 03:22:55 INFO - Installing collected packages: jsonschema, functools32 03:22:55 INFO - Running setup.py install for functools32 03:22:56 INFO - warning: no files found matching '*.txt' 03:22:56 INFO - no previously-included directories found matching 'build' 03:22:56 INFO - no previously-included directories found matching 'dist' 03:22:56 INFO - no previously-included directories found matching '.git*' 03:22:56 INFO - Successfully installed jsonschema functools32 03:22:56 INFO - Cleaning up... 03:22:56 INFO - Return code: 0 03:22:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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', '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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:22: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:22: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 functools32==3.2.3-2 03:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:56 INFO - 'HOME': '/Users/cltbld', 03:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:56 INFO - 'LOGNAME': 'cltbld', 03:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:56 INFO - 'MOZ_NO_REMOTE': '1', 03:22:56 INFO - 'NO_EM_RESTART': '1', 03:22:56 INFO - 'PAGER': '/bin/cat', 03:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:56 INFO - 'PWD': '/builds/slave/test', 03:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:56 INFO - 'SHELL': '/bin/bash', 03:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:22:56 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:56 INFO - 'USER': 'cltbld', 03:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:56 INFO - 'XPC_FLAGS': '0x0', 03:22:56 INFO - 'XPC_SERVICE_NAME': '0', 03:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:22:56 INFO - Cleaning up... 03:22:56 INFO - Return code: 0 03:22:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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', '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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:22: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:22: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 blobuploader==1.2.4 03:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:56 INFO - 'HOME': '/Users/cltbld', 03:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:56 INFO - 'LOGNAME': 'cltbld', 03:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:56 INFO - 'MOZ_NO_REMOTE': '1', 03:22:56 INFO - 'NO_EM_RESTART': '1', 03:22:56 INFO - 'PAGER': '/bin/cat', 03:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:56 INFO - 'PWD': '/builds/slave/test', 03:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:56 INFO - 'SHELL': '/bin/bash', 03:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:22:56 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:56 INFO - 'USER': 'cltbld', 03:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:56 INFO - 'XPC_FLAGS': '0x0', 03:22:56 INFO - 'XPC_SERVICE_NAME': '0', 03:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:56 INFO - Downloading/unpacking blobuploader==1.2.4 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:23:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:23: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 03:23: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 03:23: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 03:23: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 03:23: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 03:23: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 03:23:00 INFO - Downloading docopt-0.6.1.tar.gz 03:23:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:23:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:23:00 INFO - Running setup.py install for blobuploader 03:23:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:23:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:23:00 INFO - Running setup.py install for requests 03:23:01 INFO - Running setup.py install for docopt 03:23:01 INFO - Successfully installed blobuploader requests docopt 03:23:01 INFO - Cleaning up... 03:23:01 INFO - Return code: 0 03:23:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:23:01 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')]} 03:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:01 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')]} 03:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:01 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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:23:01 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 03:23:01 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 03:23:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:23:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:01 INFO - 'HOME': '/Users/cltbld', 03:23:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:01 INFO - 'LOGNAME': 'cltbld', 03:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:01 INFO - 'MOZ_NO_REMOTE': '1', 03:23:01 INFO - 'NO_EM_RESTART': '1', 03:23:01 INFO - 'PAGER': '/bin/cat', 03:23:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:01 INFO - 'PWD': '/builds/slave/test', 03:23:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:01 INFO - 'SHELL': '/bin/bash', 03:23:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:23:01 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:23:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:01 INFO - 'USER': 'cltbld', 03:23:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:01 INFO - 'XPC_FLAGS': '0x0', 03:23:01 INFO - 'XPC_SERVICE_NAME': '0', 03:23:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:23:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-LQ2w76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:23:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-3RW2DZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:23:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-aSiXJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:23:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-m_f7qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-s9BSaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-kXXgXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-4ZRIgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-TYWoTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-TfUzMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-QJTOzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-nf3ew3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-8A0_Sl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:23:02 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-SMUuiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:23:03 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-eaiTBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:03 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-aAyXwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:23:03 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-bV0_7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:23:03 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-QRJ3cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:23:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:23:03 INFO - Running setup.py install for manifestparser 03:23:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:23:03 INFO - Running setup.py install for mozcrash 03:23:03 INFO - Running setup.py install for mozdebug 03:23:03 INFO - Running setup.py install for mozdevice 03:23:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Running setup.py install for mozfile 03:23:04 INFO - Running setup.py install for mozhttpd 03:23:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Running setup.py install for mozinfo 03:23:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Running setup.py install for mozInstall 03:23:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Running setup.py install for mozleak 03:23:04 INFO - Running setup.py install for mozlog 03:23:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:23:04 INFO - Running setup.py install for moznetwork 03:23:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:23:05 INFO - Running setup.py install for mozprocess 03:23:05 INFO - Running setup.py install for mozprofile 03:23:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:23:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:23:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:23:05 INFO - Running setup.py install for mozrunner 03:23:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:23:05 INFO - Running setup.py install for mozscreenshot 03:23:05 INFO - Running setup.py install for moztest 03:23:05 INFO - Running setup.py install for mozversion 03:23:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:23:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:23:06 INFO - Cleaning up... 03:23:06 INFO - Return code: 0 03:23:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:06 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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:23:06 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 03:23:06 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 03:23:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:23:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:06 INFO - 'HOME': '/Users/cltbld', 03:23:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:06 INFO - 'LOGNAME': 'cltbld', 03:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:06 INFO - 'MOZ_NO_REMOTE': '1', 03:23:06 INFO - 'NO_EM_RESTART': '1', 03:23:06 INFO - 'PAGER': '/bin/cat', 03:23:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:06 INFO - 'PWD': '/builds/slave/test', 03:23:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:06 INFO - 'SHELL': '/bin/bash', 03:23:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:23:06 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:23:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:06 INFO - 'USER': 'cltbld', 03:23:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:06 INFO - 'XPC_FLAGS': '0x0', 03:23:06 INFO - 'XPC_SERVICE_NAME': '0', 03:23:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:23:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-68cTgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:23:06 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)) 03:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:23:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-soLGYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:23:06 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)) 03:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:23:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-IRXSUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:23:06 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)) 03:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:23:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-VrCFPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:23:06 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)) 03:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:23:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-wFWFse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:23:06 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)) 03:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:23:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-jARVyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:23:06 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)) 03:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:23:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-BLqvMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-sQHFa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-AkKoCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-PeUAGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-SINZp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-NF1S1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ECEone-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-sRX7bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-oc5dv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:07 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)) 03:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:23:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-P__gbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:23:08 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)) 03:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:23:08 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-xDzYAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:23:08 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)) 03:23:08 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)) 03:23:08 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)) 03:23:08 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)) 03:23:08 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)) 03:23:08 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)) 03:23:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:23:08 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 03:23:08 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 03:23:08 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 03:23:08 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 03:23:08 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 03:23:08 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 03:23:10 INFO - Downloading blessings-1.6.tar.gz 03:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:23:10 INFO - Installing collected packages: blessings 03:23:10 INFO - Running setup.py install for blessings 03:23:10 INFO - Successfully installed blessings 03:23:10 INFO - Cleaning up... 03:23:11 INFO - Return code: 0 03:23:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:23:11 INFO - Reading from file tmpfile_stdout 03:23:11 INFO - Current package versions: 03:23:11 INFO - blessings == 1.6 03:23:11 INFO - blobuploader == 1.2.4 03:23:11 INFO - docopt == 0.6.1 03:23:11 INFO - functools32 == 3.2.3-2 03:23:11 INFO - jsonschema == 2.5.1 03:23:11 INFO - manifestparser == 1.1 03:23:11 INFO - mozInstall == 1.12 03:23:11 INFO - mozcrash == 1.0 03:23:11 INFO - mozdebug == 0.1 03:23:11 INFO - mozdevice == 0.48 03:23:11 INFO - mozfile == 1.2 03:23:11 INFO - mozhttpd == 0.7 03:23:11 INFO - mozinfo == 0.9 03:23:11 INFO - mozleak == 0.1 03:23:11 INFO - mozlog == 3.4 03:23:11 INFO - moznetwork == 0.27 03:23:11 INFO - mozprocess == 0.23 03:23:11 INFO - mozprofile == 0.28 03:23:11 INFO - mozrunner == 6.13 03:23:11 INFO - mozscreenshot == 0.1 03:23:11 INFO - mozsystemmonitor == 0.3 03:23:11 INFO - moztest == 0.7 03:23:11 INFO - mozversion == 1.4 03:23:11 INFO - psutil == 3.1.1 03:23:11 INFO - requests == 1.2.3 03:23:11 INFO - wsgiref == 0.1.2 03:23:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:11 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 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:23:11 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 03:23:11 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 03:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:11 INFO - 'HOME': '/Users/cltbld', 03:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:11 INFO - 'LOGNAME': 'cltbld', 03:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:11 INFO - 'MOZ_NO_REMOTE': '1', 03:23:11 INFO - 'NO_EM_RESTART': '1', 03:23:11 INFO - 'PAGER': '/bin/cat', 03:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:11 INFO - 'PWD': '/builds/slave/test', 03:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:11 INFO - 'SHELL': '/bin/bash', 03:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:23:11 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:11 INFO - 'USER': 'cltbld', 03:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:11 INFO - 'XPC_FLAGS': '0x0', 03:23:11 INFO - 'XPC_SERVICE_NAME': '0', 03:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 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)) 03:23:11 INFO - Cleaning up... 03:23:11 INFO - Return code: 0 03:23:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a93490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a09570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848b588960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, '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.z3AYUogbJS/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.t8I3Q0v08X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 03:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:11 INFO - 'HOME': '/Users/cltbld', 03:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:11 INFO - 'LOGNAME': 'cltbld', 03:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:11 INFO - 'MOZ_NO_REMOTE': '1', 03:23:11 INFO - 'NO_EM_RESTART': '1', 03:23:11 INFO - 'PAGER': '/bin/cat', 03:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:11 INFO - 'PWD': '/builds/slave/test', 03:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:11 INFO - 'SHELL': '/bin/bash', 03:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:23:11 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:11 INFO - 'USER': 'cltbld', 03:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:11 INFO - 'XPC_FLAGS': '0x0', 03:23:11 INFO - 'XPC_SERVICE_NAME': '0', 03:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:23:11 INFO - Cleaning up... 03:23:11 INFO - Return code: 0 03:23:11 INFO - Running post-action listener: _resource_record_post_action 03:23:11 INFO - Running post-action listener: _start_resource_monitoring 03:23:11 INFO - Starting resource monitoring. 03:23:11 INFO - [mozharness: 2016-11-19 11:23:11.913986Z] Finished create-virtualenv step (success) 03:23:11 INFO - [mozharness: 2016-11-19 11:23:11.915040Z] Running install step. 03:23:11 INFO - Running pre-action listener: _resource_record_pre_action 03:23:11 INFO - Running main action method: install 03:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:23:12 INFO - Reading from file tmpfile_stdout 03:23:12 INFO - Detecting whether we're running mozinstall >=1.0... 03:23:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:23:12 INFO - Reading from file tmpfile_stdout 03:23:12 INFO - Output received: 03:23:12 INFO - Usage: mozinstall [options] installer 03:23:12 INFO - Options: 03:23:12 INFO - -h, --help show this help message and exit 03:23:12 INFO - -d DEST, --destination=DEST 03:23:12 INFO - Directory to install application into. [default: 03:23:12 INFO - "/builds/slave/test"] 03:23:12 INFO - --app=APP Application being installed. [default: firefox] 03:23:12 INFO - mkdir: /builds/slave/test/build/application 03:23:12 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'] 03:23:12 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 03:23:44 INFO - Reading from file tmpfile_stdout 03:23:44 INFO - Output received: 03:23:44 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:23:44 INFO - Running post-action listener: _resource_record_post_action 03:23:44 INFO - [mozharness: 2016-11-19 11:23:44.798236Z] Finished install step (success) 03:23:44 INFO - [mozharness: 2016-11-19 11:23:44.798387Z] Running run-tests step. 03:23:44 INFO - Running pre-action listener: _resource_record_pre_action 03:23:44 INFO - Running main action method: run_tests 03:23:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:23:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:23:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:23:44 INFO - 2016-11-19 03:23:44.812 screenresolution[1637:9924] starting screenresolution argv=screenresolution get 03:23:44 INFO - 2016-11-19 03:23:44.815 screenresolution[1637:9924] Display 0: 1600x1200x32@60 03:23:44 INFO - 2016-11-19 03:23:44.821 screenresolution[1638:9928] starting screenresolution argv=screenresolution list 03:23:44 INFO - Available Modes on Display 0 03:23:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:23:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:23:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:23:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:23:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:23:45 INFO - Intel Iris: 03:23:45 INFO - Chipset Model: Intel Iris 03:23:45 INFO - Type: GPU 03:23:45 INFO - Bus: Built-In 03:23:45 INFO - VRAM (Dynamic, Max): 1536 MB 03:23:45 INFO - Vendor: Intel (0x8086) 03:23:45 INFO - Device ID: 0x0a2e 03:23:45 INFO - Revision ID: 0x0009 03:23:45 INFO - Displays: 03:23:45 INFO - Display: 03:23:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:23:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:23:45 INFO - Display Serial Number: 1600x1200 60 03:23:45 INFO - Main Display: Yes 03:23:45 INFO - Mirror: Off 03:23:45 INFO - Online: Yes 03:23:45 INFO - Rotation: Supported 03:23:45 INFO - Return code: 0 03:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:23:45 INFO - Python 2.7.3 03:23:45 INFO - Return code: 0 03:23:45 INFO - grabbing minidump binary from tooltool 03:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a7d30>, '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 0x10770ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10789fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:23:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:23:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:23:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:23:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:23:45 INFO - Return code: 0 03:23:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:23:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:23:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:23:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:23:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:23:45 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/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 03:23:45 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/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --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 03:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z3AYUogbJS/Render', 03:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:45 INFO - 'HOME': '/Users/cltbld', 03:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:45 INFO - 'LOGNAME': 'cltbld', 03:23:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:23:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:45 INFO - 'MOZ_NO_REMOTE': '1', 03:23:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:23:45 INFO - 'NO_EM_RESTART': '1', 03:23:45 INFO - 'PAGER': '/bin/cat', 03:23:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:45 INFO - 'PWD': '/builds/slave/test', 03:23:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:45 INFO - 'SHELL': '/bin/bash', 03:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t8I3Q0v08X/Listeners', 03:23:45 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 03:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:45 INFO - 'USER': 'cltbld', 03:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:45 INFO - 'XPC_FLAGS': '0x0', 03:23:45 INFO - 'XPC_SERVICE_NAME': '0', 03:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:45 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/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 03:23:45 INFO - mozversion application_buildid: 20161119004003 03:23:45 INFO - mozversion application_changeset: 48f5a1870ec3974ddf717576e6e54d345e476677 03:23:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:23:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:23:45 INFO - mozversion application_name: Firefox 03:23:45 INFO - mozversion application_remotingname: firefox-dev 03:23:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:23:45 INFO - mozversion application_vendor: Mozilla 03:23:45 INFO - mozversion application_version: 52.0a2 03:23:45 INFO - mozversion platform_buildid: 20161119004003 03:23:45 INFO - mozversion platform_changeset: 48f5a1870ec3974ddf717576e6e54d345e476677 03:23:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:23:45 INFO - mozversion platform_version: 52.0a2 03:23:45 INFO - using testdate: 1479554625 03:23:45 INFO - actual date: 1479554625 03:23:45 INFO - starting webserver on 'localhost:49241' 03:23:45 INFO - SUITE-START | Running 2 tests 03:23:45 INFO - TEST-START | dromaeo_css 03:23:45 INFO - operating with platform_type : mac_ 03:23:45 INFO - Initialising browser for dromaeo_css test... 03:23:45 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpdkV38Z/profile http://localhost:49241/getInfo.html) 03:23:47 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 03:23:48 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 03:23:48 INFO - PROCESS | 1648 | colorDepth:24 03:23:48 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 03:23:48 INFO - PROCESS | 1648 | __metrics 03:23:48 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 03:23:49 INFO - TEST-INFO | 1648: exit 0 03:23:49 INFO - Browser initialized. 03:23:49 INFO - Running cycle 1/1 for dromaeo_css test... 03:23:49 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpdkV38Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:24:01 INFO - PROCESS | 1654 | RSS: Main: 223825920 03:24:01 INFO - PROCESS | 1654 | 03:24:51 INFO - PROCESS | 1654 | 1479554691157 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] 03:27:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 03:27:07 INFO - PROCESS | 1654 | RSS: Main: 233648128 03:27:07 INFO - PROCESS | 1654 | 03:30:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 03:30:09 INFO - PROCESS | 1654 | RSS: Main: 244834304 03:30:09 INFO - PROCESS | 1654 | 03:33:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 03:33:12 INFO - PROCESS | 1654 | RSS: Main: 247726080 03:33:12 INFO - PROCESS | 1654 | 03:36:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 03:36:15 INFO - PROCESS | 1654 | RSS: Main: 247009280 03:36:15 INFO - PROCESS | 1654 | 03:39:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 03:39:18 INFO - PROCESS | 1654 | RSS: Main: 250580992 03:39:18 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 03:42:21 INFO - PROCESS | 1654 | RSS: Main: 252358656 03:42:21 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | __start_tp_report 03:42:21 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 03:42:21 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 03:42:21 INFO - PROCESS | 1654 | |i|pagename|runs| 03:42:21 INFO - PROCESS | 1654 | |0;dojo.html;2991.371811919497;3376.763626546143;3438.5186073949653;3444.0873168610337;3457.325821464814;5676.176954341623;5753.741081651326;5762.423757624236;5763.9711801441035;5778.884422311551;5746.540276777858;5758.942410575893;5846.093855452401;5850.941490585093;5891.764329426828;4042;5242.973785131069;5249.976254630339;5299.708516031616;5315.096433606296;4686.46105697844;5339.41266460689;5347.171188465794;5347.491988261109;5359.919601205985;5196.220566914955;5206.531412172903;5245.763940622681;5330;5355.6251062425645;3901.200253947934;4401.427814384109;5254.710990895499;5324.068288049576;5371.221172929942;3993.8602148924647;4699.718016918961;5204.583633309327;5245.711485868275;5259.0796610593;5104.515071068243;5502.147167189092;5802.361740208616;5823.6505809651135;5882.2353094097925;5250.474952504719;6116.602420842631;6128.846778830521;6131.162586540582;6198.969005154945;6135.569531795964;6165.722542485599;6169.598976066586;6173.938260617381;6184.5980011299125;5922.703864806828;5937.346891841894;5942.970285148546;5987.9401205987815;5990.371011043865;6108.083787431832;6140.074827791203;6182.814515564541;6201.96899015502;6273.654948977759;6072.423119803611;6074.787382441594;6118.847028824226;6124.899720463051;6140.944270905864;6130.846728831726;6154.3845615438095;6171.691415429211;6181.320054793969;6198.132261483396;6055.6063855849225;6103.201958139785;6113.877722445615;6117;6134.171886795315;6084.056971169476;6107;6115.483736156547;6126.387361263927;6132.969335153385;4943.036107958986;4951.777170027357;4988.102141614544;4997.425296090996;5008.348914641147;4439.178751930838;4485.192665320274;4498.235299999008;4505.752183629932;4583;5804.79683211094;5892.941070589325;5904.114382842608;6021.006533921942;6050.878982420415;6108.493847774667;6114.358196333954;6131.601445906002;6143.296949342275;6217.533684973645;5087.847364579068;5437.809676661298;5830.883382332415;5875.500582450454;5997.100434934795;5334.11987022137;5398.703071331036;5403.486668766461;5407.864803379868;5433.6196466247775;6243.157173781481;6328.430441260309;6332.430081292706;6337.17616709834;6344.206974128234;6079.969600151971;6176.752929882934;6182.381761823782;6186.1648677427975;6193;6133.395871484143;6180.289266734203;6190.845228869314;6192.752289908533;6193.102000209854;6131.908021379772;6145.955187618027;6160.476359509313;6161.353057928853;6161.876762464815;6133.662648554194;6170.50635949114;6172.197614310111;6193.659348735863;6200.875982480416;4778.685861388146;4953.603711703127;5958.106284057426;6032.939670603413;6079.209702738615;6068.787592434243;6074.362191969956;6075.726592303446;6085.695715214133;6115.113308570321;4660.533946605312;5029.874253143701;5899.321578018583;5974.2233509643465;6059.394060593905;6005.219321488178;6057.757689692363;6088.36072212429;6118.908216376669;6128.019516877278;4524.479684836286;5021.196608542616;5908.763649453974;5960.165576819336;6008.969955150196 03:42:21 INFO - PROCESS | 1654 | |1;ext.html;446.83020452228146;468.8780916961589;505.17908398851864;513.2890946039734;515.3377909386438;37554.81222593887;39352.409713854315;39421.01447463815;39470.80264598677;39649.60350396495;40534;40865.387019194735;40915.59084409155;40973.79513102438;41010.58989410105;8294.253517183452;9302.627894884214;9475.289353298496;9491.857622135674;9506.239500839916;9423.575939082724;9496.050394960517;9523.523823808782;9527.571259293314;9529.857052144223;9496.66761663342;9511.57197926095;9531.046895310483;9541;9555.331126821126;9410.62357505699;9529.04709529045;9531.142197202285;9536.952315238448;9542.856857147148;9360.297977651704;9456.385334953206;9466.19537339327;9475.952620236854;9478.573464194127;38327.42508862369;38691.41962870559;38781;38901;38936;3325.7671962962604;3333.399988002159;3529.9117522061897;3795.8671446499516;3829.5213098362706;28342.858285708644;29159.854200729074;29267.853660731562;29499;30116.24709382261;3200.7119359257777;3214.9839250803598;3228.1122691260093;3228.596425446819;3306.6032076150777;467575;468342;469782.6510867458;470353;470728;55011.17483237755;55399;55481.72259138679;55497;55513;57622.847543048905;57654;58053.4194658048;58242.12636810451;58857.70571147117;15269;15421.228938553028;15440.536783896501;15450.922745386426;15503.612409689847;11893.226940248856;11948.701282467833;11998.700032499082;12009.759804804029;12011.33937633421;13789;14825;14828.258587070819;14849.257537123101;14976.47582334613;5793.971030144907;5906.409359064145;6215.844603884849;6308.968455157695;6309.842253943688;848.5841937450729;972.562346943864;979.5591983607405;1023.4422239879284;1047.418682631147;23759.40601484942;28935;29470.557941630894;29527.966521172086;29714.108576742732;958.9978472496258;962.831504486712;974.6452431120606;1035.1304903880625;1099.0603034405628;14696.853031469762;15070.547883563511;15143.242837858063;15343.846561534241;15418;499473.50263248454;582987;622916.88541557;631105;632064;55251;55264.44735552673;55406.72296638491;55450;55500.16749748757;1251.28676654308;1341.121565374681;1359.592122363287;1368.2337890781193;1368.2406264523086;28160.577591335736;28797.71202288034;28998.985035523656;29142;29414;40839.7958010208;47596.810079748284;48060.519394805604;48084.03831923272;48420.51579484301;585775;590478;591553;592102;594914;37784.811075944446;38176.618233818415;38422.078896055646;38466.80766596261;38512.42231366588;44108.779456102515;44512.88717782081;45461.31808022948;45592;45831.54168458273;32401.51397729083;36038.45942310815;37182.12035521137;37679.246415071;38580.614193857706;36855.44716829196;36926.446103307935;37427.251454971294;37536.43695344627;37688;30705.92529261465;35253;35837.46243806397;36225.818870905474;36328.81835590805;27971.720282796912;30830.691693082783;32828.015159545255;32887.8489252875;32956.50565241571;24727;24798;24809.62785558182;24973.125940591992;25249.621255680813 03:42:21 INFO - PROCESS | 1654 | |2;jquery.html;347.9773814702045;366.29305440499843;378.9242151569686;383.9212961342925;394.53839008360217;10956.506957186926;11362.545498180074;11497.42512874357;11528.423578821046;18658.25366985319;18716.625667486675;20614;20703.792962070376;20840.47898802531;21632.35102946914;5876.001079816441;5984.79053233138;6222.004479283316;6802.761903333384;6840.144981877265;4509.909801803962;6416.679166041679;7252.528585641943;7613.390928725717;7672.309492145706;6523.4455071319;7381.040464739576;7637.427192960522;7729.690812367498;7733.9226607734045;7321.414286857039;7584.127825300078;7658.195889431585;7670.348020418243;7678.462507624468;6336.936630633681;7390.8891366629005;7448.180700122982;7450.813729656802;7485.401167906609;21147.25984590547;26824.195274141806;26987.785549650118;27361.589576156373;27398.315042124108;2385.1771138957033;2395.9880200598886;2435.3546310227803;2500.524900268943;2634.183403145031;15838.920805396014;16908.90092144007;17073.658526829517;17106.828931710646;17253;1968.7637483501928;2086.384516567616;2086.968695469554;2113.95772084559;2128.88291143987;95961;146093;146776;148512.2574387132;148991.25504372516;21349.893250533958;21694.45763855916;21978.340649780534;22009.66985495219;22042.33872983813;34446.48330275048;34537.30925381478;34953.65046349555;35108.648913511046;35362.11594710153;10239.897601024044;10555.208359372924;10762.73093172677;10778;10899.455027248607;8704.825903481895;8720.825583488422;8722.47665140081;8817.735467935972;8926;9565.378250413702;9778.462184579916;9800.509974501247;9842.261830362751;9884.505774711235;1569.3879387039065;1664.625459271654;1670.607407259293;1679.7480377943161;1698.346136737365;760.1106705154982;938.7606160429184;990.1385794358954;992.2409356842022;1015.5785349080197;19919.701204481946;20586.382408527767;20598.691019634716;20695.06872190755;20745.688814667792;929.3494553812359;930.6044930904338;951.4291425144858;967.511406739592;986.3194395866828;10526.210534209811;10920.344779313267;10952.4523773811;11007.06439952597;11042.723931901766;583499;591799;592627;596756;598910;117926.41036794933;127627;128023;128820.3558982199;132511;978.1587834462357;983.8327484327682;1022.3559157730582;1028.4857571214393;1033.0805583031108;16771;18322.633547329246;18553.794003389736;18976.715349269496;19664.213431463155;55188.44811551833;55607.721961389936;56233.8753224925;57606.7119664399;59127.70436147792;71041;75424.49151016839;77428;78370.6081469589;79130.20869791228;7665.156832748505;7733.4973226740085;8199.877001845096;8210.3021243195;8232.217939295757;11778.705532361759;12305.569305074638;12870.22778633285;12908.806367904677;12926.547570834975;8722.389432739647;9893.50532473359;10238.795224095626;10860.022597966221;11125.721856953642;8104.432689711663;9528.475933823705;9846.212303015885;9904.504774761377;9912.256580756328;11221.214514983873;11254.437278136224;11316.886831131584;11682.88317116852;11709;8133.227343402367;8201.876971845546;8244.134365891496;8334.49993000422;8459.577021149042;9592.113394868513;11156.832647510457;11504.884951150381;11567.942160289145;11626.651200463999 03:42:21 INFO - PROCESS | 1654 | |3;mootools.html;1488.3971991343503;1514.2050423527646;1691.3319238900635;1786.7766529183853;1801.24347773935;7629.771106866789;7870.685172593089;8077.434579579432;8541.103184165659;8565.443246188994;7298.489105762599;8010.319122874548;8340.499570025802;8391.70629027984;8467.95766021169;2283.257941169114;2329.474194402669;2329.848559843613;2338.251759436981;2339.157903154863;2305.4812667149927;2336.1823361823313;2365.47959448921;2371.537550177716;2380.73811880693;2309.4919117794057;2313.5488579726875;2329.091654254842;2337.3805941425453;2342.16853017179;2285.5771682238674;2288.4507718147606;2304.2741536415974;2311.133325003124;2312.722473303206;2316.9073237070497;2350.118705485443;2353.9529209415887;2355.8115350771895;2357.799587035087;8382.664693412256;8444.788880278042;8455.323574114056;8459.450135741157;8474.576271186415;5088.643794934356;5312;5319.361676598833;5321.281626980347;5331.733413329318;3226.5644138041534;3374.898753037413;3478.113081164312;3534.7348948828685;3542.8582856685957;980.8185485685171;1010.5957616953204;1049.7690508088208;1056.8203405421098;1061.6443491430302;10778.676639700821;10913.345199288067;10986.066184374338;11035.227534072617;11036.392998385167;4513.345564893072;4669.299605059268;4742.5020372861045;4751.477337492873;4775.403074615673;9479.573419196153;9535.52322383878;9570;9571.090746379083;9577.856332155023;4613.953860461353;5265.631405801634;5281.630285879952;5286.15421532553;5292.5236728693835;4267.786610669454;4293.720908140961;4747.715137091785;4760.357351757538;4814.325994360793;3885.7668539887695;3887.475190849199;3974.741641793274;4001.1197536541913;4011.839526418969;1109.3066833229232;1254.5044707340548;1287.819705241267;1289.0267847775058;1301.576987479073;823.7281697039963;892.790194304338;893.4549924546021;916.5600511754395;919.0625561926797;5058.988202359543;5606.495415412633;5723.513501352349;5790;5830.591858569945;918.8483900156399;928.2434815625244;973.0512750068768;991.5141580625442;991.5240684471494;2951.1736713719765;3466.2374277658873;3842.692584593226;3926.6466018058513;3930.21395720857;9580.712578622653;9823.75440613976;10182.541785619665;10210.183185345155;10230.976902309709;9969.900300997042;10206.336588121749;10208.183345332356;10210.336328138646;10252.743681408025;6887.931120688829;7857.7249796258;7914.169012253746;7950.284474397447;8019.919800802034;5486.725663716878;5562.833115006556;5566.554675626021;5657.886842263214;5671.319441667027;9287.024862389622;9334.06659334061;9390.530473476436;9481.336306458481;9629.133377995733;8564.229219370287;8799;9734.123928846439;9747;9792;6212.627242365473;6425.710843012169;6614.4708423325055;6691.364320389555;6811.8978215325815;7720.7683769487;7947.841043178988;7962.004749406325;7989.680412783424;8024.358051355761;7063.6821343038655;7211.855762884608;7219.422446204186;7228.566286022856;7313.414926805949;6944.126231484189;7000.054992576141;7021.648917554204;7146.213916469081;7154.427645788429;6719.966400167968;6730.091437655854;6812.250652428328;6820.761273355409;6829.112215411965;4671.9299210512545;4784.378030855966;4824.541668541483;4856.125897338513;4866.440359358719;4175.373693945932;4496.303073023687;4643.117807616542;4793.9280910787065;5039.647224694383 03:42:21 INFO - PROCESS | 1654 | |4;prototype.html;258.5501227863517;300.77892748829606;308.5695454840499;310.58381768430297;313.99843000784995;4901.33831932689;4919.458859525449;5337.70642614656;5409.323834520685;5421.674699518032;4408.4291723703745;4694.835680751175;5128.999690120847;5281.287026251455;5355.7857685692525;3950.5061867266663;4143.958560414388;4146.834126634931;4154.127633197032;4166.895827604319;3974;4158.438610787535;4169.457970463835;4191.2246234446575;4192.77156589426;3142.968570314302;3426.623071462137;3510.2277498950316;3605.837737301828;3769.698424126077;4034.3141665916864;4107;4110.972256935781;4112.712110152291;4113.848122525697;3181.8727250909938;3325.1022223999626;3369.393509168349;3585.032041348847;3635.2729454109285;4294.0676695486845;4787.425508938914;4872.076753947638;4992.350994370745;5344.9732751336;3826.770393776382;4587.151376995266;4590.472095709003;4593.586577208034;4659.860204193879;4805.299919062338;5117.189841548932;5653.8586535336535;5654.067078931971;5780.121460525346;3350.9286217245917;3443.8450269737923;3634.800515829796;3727.329080765461;3858.440526123696;5229.660072095302;5440.755166017539;5898.835716676083;5959.374265702082;5960.13913321608;3483.7038851697384;3883;3895.4156876468755;3907.941380879289;3921.8039098044983;3351.4302568563403;3438.398280300937;3854.76647472806;3976.9717444103135;3999.5800440953863;4303.600155876822;4584.61030812378;4606.608438282717;4608.539146085364;4634.930476042862;5172.956682110545;5558.333000040021;5789.218455508537;5794.449527294901;5813.215215945877;1080.3140006096028;1147.8106112491512;1238.108561835477;1264.4057293072242;1280.551806867589;818.3453237410048;866.9942866275217;871.3900269811157;874.2044739287345;875.3259989807813;4593.438231001475;5082.313887625197;5103.336566246364;5182.352205974254;5207.9844430335725;4686.929696054562;5445.428230035789;5527.585431092684;5657.462541058593;5679.967223271524;4611.788678792943;4923.532264434873;4942.787188327544;5055.724923784238;5203.490987613625;2514.333701569086;2676.183763952013;2700.4599080184043;2826.886924523037;2926.678065412803;5474.42518535556;5476.316604863668;5739.311282646109;5767.711614419262;5772.451617096369;5500.972495137498;5718.1708652246025;5729.28383952006;6027.0166267696895;6066.332703402622;5308.814191503279;5863.355030946678;6045.425684559959;6078.817635470943;6139.171211886517;4902.950970490249;5521.205608177278;5857.531397488276;5950;5975.312839023568;5710.714464276853;5733.369329373849;5736.827895163152;5775.220345253337;5827.9417205829095;4600.355950166912;5599.944000559943;5956.761729530944;5971.611845230046;6172.4136207060255;3322.4850148227065;3601.495790589267;3610.061384040116;3885.8834234972996;3941.822617982255;4483.327500874895;5428.56571474289;5641.37944826077;5743.62666426681;5842.38654941225;4693.741844198602;5575.553955683455;5576.553875690016;5634.140733557998;5641.435856414326;4993.375828021497;5581.664810440098;5591.064691037238;5636.420872745918;5654.2649455572155;5309.495597918191;5505.752241149078;5537.640053396516;5546.944530554642;5560.499555040065;5679.716014199356;5686.066737309335;5692.658440493418;5696.772129114789;5701.857453563694;4902.093112774148;5530.364008139116;5557.44425557441;5598.69207193592;5722 03:42:21 INFO - PROCESS | 1654 | |5;yui.html;684.4216636941783;685.8388278754495;688.2566827825948;743.4387037786471;797.3262593108823;11503.597374091909;12764.68088297793;14600.12399256044;14614.976951613378;14729.45340739223;13265.734685306312;13332.533361332355;13416.396262168202;14333.928330358334;14908.180050097268;613.9232595925487;638.8881945659514;749.3068911257066;813.3615112136973;824.3611201318965;810.2100452059254;815.0097631377874;816.824382757707;817.1868990354606;817.7587611654576;851.1233429567556;858.7595473267464;860.3633311349588;860.7805009722512;861.3798065392907;855.8930133733284;856.0369584217755;857.6869442653418;857.8327226190895;858.9184027517376;623.4513628007325;633.1420924647083;633.8510450044282;635.4566845347254;635.8887194740948;13920.234387108705;14277.14337139775;14286.928565357106;14348.42606295747;14634.268286585735;12909.54816581415;12913.677158071123;12990.895773859233;13046.804297935538;13768.24274664893;11000.834987475195;11025.889741102566;11285.097192224603;11447.942760286229;11647;3477.3914564951288;3602.7838329700303;3676.2096149327754;3694.649008344203;3725.2549490102087;79830;81135.78296325619;81908;88885;92460.53769731108;10307.484625768682;10328.277020608635;10581.259311848096;10596;10612.204084693525;16392.672146557;19666;20141.093650785457;20173.697394539093;20191.091400886995;7557.35762460197;7600.239976002355;7621.809454763676;7705.113911900204;7837.059552853694;4183.6234738873645;4246.575342465729;4264.33902745075;4287.914241715211;4317.114991426813;3330.900072997814;3432.8798492052656;3436.0722604897037;3437.553118094682;3452.6374730653424;601.648035899004;602.2802750712856;641.3169973977699;642.8907085795425;656.9507286953498;409.70910653436397;538.9595780316492;554.2988120028167;584.9792112763171;611.9418655227746;7523.736669216661;8817.29461643067;9126.315526335551;9142.405743626643;9291.349605527683;522.3496746550569;605.5579427018301;640.9967950160312;641.9390157934989;683.1699485125497;3466.445368740989;4390.2317094508335;4418.536053714378;4503.842365517191;4507.57178068083;73505.63247183837;73799;79007.86448880805;85566;108777.45611271892;78258.21741782509;78567.60716196382;79596;99259;100211.49894250628;56928;65260;76681.93272269047;79904.60047699725;85285.72071418933;9084.046175151514;9613;9672.08115229052;9713.320067595483;9758.316917815684;42131;45210.86972825706;52979.735101324244;53174.670633232934;54513.63715907134;69398.65300673465;69757.65121174362;74841.25158748344;75392;78256.60871695605;11485.712857178638;11901.166918315635;12212.267263964191;12251.448684809384;12254.080943929062;13554.52559160464;13684.65788355299;13773.311334433438;13870.029097963046;14699.338529765972;10828.808831028424;12637.368131593568;12860.03549733755;13588.388522516312;13591.79612305836;12701.491940322283;12805.359732013176;12980.286084265455;13496.865031349953;13615.727685446434;12415.37923103823;12475.875241247835;12695.174813637084;12816.55142070023;13287.601371958857;10893.727656808644;10901.812809455574;11041.282316649393;11235.382053987109;11462.853714628804;8630.964284285927;9381.343305968516;10202.38785672862;10674.46627668598;11303.547858085585 03:42:21 INFO - PROCESS | 1654 | __end_tp_report 03:42:21 INFO - PROCESS | 1654 | __start_cc_report 03:42:21 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,376 03:42:21 INFO - PROCESS | 1654 | __end_cc_report 03:42:21 INFO - PROCESS | 1654 | __startTimestamp1479555741422__endTimestamp 03:42:21 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | ------- Summary: start ------- 03:42:21 INFO - PROCESS | 1654 | Number of tests: 6 03:42:21 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5662.20 Median:5987.94 stddev:684.38 (11.4%) stddev-sans-first:653.62 03:42:21 INFO - PROCESS | 1654 | Values: 2991.4 3376.8 3438.5 3444.1 3457.3 5676.2 5753.7 5762.4 5764.0 5778.9 5746.5 5758.9 5846.1 5850.9 5891.8 4042.0 5243.0 5250.0 5299.7 5315.1 4686.5 5339.4 5347.2 5347.5 5359.9 5196.2 5206.5 5245.8 5330.0 5355.6 3901.2 4401.4 5254.7 5324.1 5371.2 3993.9 4699.7 5204.6 5245.7 5259.1 5104.5 5502.1 5802.4 5823.7 5882.2 5250.5 6116.6 6128.8 6131.2 6199.0 6135.6 6165.7 6169.6 6173.9 6184.6 5922.7 5937.3 5943.0 5987.9 5990.4 6108.1 6140.1 6182.8 6202.0 6273.7 6072.4 6074.8 6118.8 6124.9 6140.9 6130.8 6154.4 6171.7 6181.3 6198.1 6055.6 6103.2 6113.9 6117.0 6134.2 6084.1 6107.0 6115.5 6126.4 6133.0 4943.0 4951.8 4988.1 4997.4 5008.3 4439.2 4485.2 4498.2 4505.8 4583.0 5804.8 5892.9 5904.1 6021.0 6050.9 6108.5 6114.4 6131.6 6143.3 6217.5 5087.8 5437.8 5830.9 5875.5 5997.1 5334.1 5398.7 5403.5 5407.9 5433.6 6243.2 6328.4 6332.4 6337.2 6344.2 6080.0 6176.8 6182.4 6186.2 6193.0 6133.4 6180.3 6190.8 6192.8 6193.1 6131.9 6146.0 6160.5 6161.4 6161.9 6133.7 6170.5 6172.2 6193.7 6200.9 4778.7 4953.6 5958.1 6032.9 6079.2 6068.8 6074.4 6075.7 6085.7 6115.1 4660.5 5029.9 5899.3 5974.2 6059.4 6005.2 6057.8 6088.4 6118.9 6128.0 4524.5 5021.2 5908.8 5960.2 6009.0 03:42:21 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:67889.25 Median:39386.71 stddev:148355.04 (376.7%) stddev-sans-first:148684.80 03:42:21 INFO - PROCESS | 1654 | Values: 446.8 468.9 505.2 513.3 515.3 37554.8 39352.4 39421.0 39470.8 39649.6 40534.0 40865.4 40915.6 40973.8 41010.6 8294.3 9302.6 9475.3 9491.9 9506.2 9423.6 9496.1 9523.5 9527.6 9529.9 9496.7 9511.6 9531.0 9541.0 9555.3 9410.6 9529.0 9531.1 9537.0 9542.9 9360.3 9456.4 9466.2 9476.0 9478.6 38327.4 38691.4 38781.0 38901.0 38936.0 3325.8 3333.4 3529.9 3795.9 3829.5 28342.9 29159.9 29267.9 29499.0 30116.2 3200.7 3215.0 3228.1 3228.6 3306.6 467575.0 468342.0 469782.7 470353.0 470728.0 55011.2 55399.0 55481.7 55497.0 55513.0 57622.8 57654.0 58053.4 58242.1 58857.7 15269.0 15421.2 15440.5 15450.9 15503.6 11893.2 11948.7 11998.7 12009.8 12011.3 13789.0 14825.0 14828.3 14849.3 14976.5 5794.0 5906.4 6215.8 6309.0 6309.8 848.6 972.6 979.6 1023.4 1047.4 23759.4 28935.0 29470.6 29528.0 29714.1 959.0 962.8 974.6 1035.1 1099.1 14696.9 15070.5 15143.2 15343.8 15418.0 499473.5 582987.0 622916.9 631105.0 632064.0 55251.0 55264.4 55406.7 55450.0 55500.2 1251.3 1341.1 1359.6 1368.2 1368.2 28160.6 28797.7 28999.0 29142.0 29414.0 40839.8 47596.8 48060.5 48084.0 48420.5 585775.0 590478.0 591553.0 592102.0 594914.0 37784.8 38176.6 38422.1 38466.8 38512.4 44108.8 44512.9 45461.3 45592.0 45831.5 32401.5 36038.5 37182.1 37679.2 38580.6 36855.4 36926.4 37427.3 37536.4 37688.0 30705.9 35253.0 35837.5 36225.8 36328.8 27971.7 30830.7 32828.0 32887.8 32956.5 24727.0 24798.0 24809.6 24973.1 25249.6 03:42:21 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:36598.66 Median:34491.90 stddev:99300.99 (287.9%) stddev-sans-first:99542.25 03:42:21 INFO - PROCESS | 1654 | Values: 348.0 366.3 378.9 383.9 394.5 10956.5 11362.5 11497.4 11528.4 18658.3 18716.6 20614.0 20703.8 20840.5 21632.4 5876.0 5984.8 6222.0 6802.8 6840.1 4509.9 6416.7 7252.5 7613.4 7672.3 6523.4 7381.0 7637.4 7729.7 7733.9 7321.4 7584.1 7658.2 7670.3 7678.5 6336.9 7390.9 7448.2 7450.8 7485.4 21147.3 26824.2 26987.8 27361.6 27398.3 2385.2 2396.0 2435.4 2500.5 2634.2 15838.9 16908.9 17073.7 17106.8 17253.0 1968.8 2086.4 2087.0 2114.0 2128.9 95961.0 146093.0 146776.0 148512.3 148991.3 21349.9 21694.5 21978.3 22009.7 22042.3 34446.5 34537.3 34953.7 35108.6 35362.1 10239.9 10555.2 10762.7 10778.0 10899.5 8704.8 8720.8 8722.5 8817.7 8926.0 9565.4 9778.5 9800.5 9842.3 9884.5 1569.4 1664.6 1670.6 1679.7 1698.3 760.1 938.8 990.1 992.2 1015.6 19919.7 20586.4 20598.7 20695.1 20745.7 929.3 930.6 951.4 967.5 986.3 10526.2 10920.3 10952.5 11007.1 11042.7 583499.0 591799.0 592627.0 596756.0 598910.0 117926.4 127627.0 128023.0 128820.4 132511.0 978.2 983.8 1022.4 1028.5 1033.1 16771.0 18322.6 18553.8 18976.7 19664.2 55188.4 55607.7 56233.9 57606.7 59127.7 71041.0 75424.5 77428.0 78370.6 79130.2 7665.2 7733.5 8199.9 8210.3 8232.2 11778.7 12305.6 12870.2 12908.8 12926.5 8722.4 9893.5 10238.8 10860.0 11125.7 8104.4 9528.5 9846.2 9904.5 9912.3 11221.2 11254.4 11316.9 11682.9 11709.0 8133.2 8201.9 8244.1 8334.5 8459.6 9592.1 11156.8 11504.9 11567.9 11626.7 03:42:21 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5447.47 Median:5283.89 stddev:2985.02 (56.5%) stddev-sans-first:2978.56 03:42:21 INFO - PROCESS | 1654 | Values: 1488.4 1514.2 1691.3 1786.8 1801.2 7629.8 7870.7 8077.4 8541.1 8565.4 7298.5 8010.3 8340.5 8391.7 8468.0 2283.3 2329.5 2329.8 2338.3 2339.2 2305.5 2336.2 2365.5 2371.5 2380.7 2309.5 2313.5 2329.1 2337.4 2342.2 2285.6 2288.5 2304.3 2311.1 2312.7 2316.9 2350.1 2354.0 2355.8 2357.8 8382.7 8444.8 8455.3 8459.5 8474.6 5088.6 5312.0 5319.4 5321.3 5331.7 3226.6 3374.9 3478.1 3534.7 3542.9 980.8 1010.6 1049.8 1056.8 1061.6 10778.7 10913.3 10986.1 11035.2 11036.4 4513.3 4669.3 4742.5 4751.5 4775.4 9479.6 9535.5 9570.0 9571.1 9577.9 4614.0 5265.6 5281.6 5286.2 5292.5 4267.8 4293.7 4747.7 4760.4 4814.3 3885.8 3887.5 3974.7 4001.1 4011.8 1109.3 1254.5 1287.8 1289.0 1301.6 823.7 892.8 893.5 916.6 919.1 5059.0 5606.5 5723.5 5790.0 5830.6 918.8 928.2 973.1 991.5 991.5 2951.2 3466.2 3842.7 3926.6 3930.2 9580.7 9823.8 10182.5 10210.2 10231.0 9969.9 10206.3 10208.2 10210.3 10252.7 6887.9 7857.7 7914.2 7950.3 8019.9 5486.7 5562.8 5566.6 5657.9 5671.3 9287.0 9334.1 9390.5 9481.3 9629.1 8564.2 8799.0 9734.1 9747.0 9792.0 6212.6 6425.7 6614.5 6691.4 6811.9 7720.8 7947.8 7962.0 7989.7 8024.4 7063.7 7211.9 7219.4 7228.6 7313.4 6944.1 7000.1 7021.6 7146.2 7154.4 6720.0 6730.1 6812.3 6820.8 6829.1 4671.9 4784.4 4824.5 4856.1 4866.4 4175.4 4496.3 4643.1 4793.9 5039.6 03:42:21 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4519.15 Median:5069.02 stddev:1431.28 (28.2%) stddev-sans-first:1399.12 03:42:21 INFO - PROCESS | 1654 | Values: 258.6 300.8 308.6 310.6 314.0 4901.3 4919.5 5337.7 5409.3 5421.7 4408.4 4694.8 5129.0 5281.3 5355.8 3950.5 4144.0 4146.8 4154.1 4166.9 3974.0 4158.4 4169.5 4191.2 4192.8 3143.0 3426.6 3510.2 3605.8 3769.7 4034.3 4107.0 4111.0 4112.7 4113.8 3181.9 3325.1 3369.4 3585.0 3635.3 4294.1 4787.4 4872.1 4992.4 5345.0 3826.8 4587.2 4590.5 4593.6 4659.9 4805.3 5117.2 5653.9 5654.1 5780.1 3350.9 3443.8 3634.8 3727.3 3858.4 5229.7 5440.8 5898.8 5959.4 5960.1 3483.7 3883.0 3895.4 3907.9 3921.8 3351.4 3438.4 3854.8 3977.0 3999.6 4303.6 4584.6 4606.6 4608.5 4634.9 5173.0 5558.3 5789.2 5794.4 5813.2 1080.3 1147.8 1238.1 1264.4 1280.6 818.3 867.0 871.4 874.2 875.3 4593.4 5082.3 5103.3 5182.4 5208.0 4686.9 5445.4 5527.6 5657.5 5680.0 4611.8 4923.5 4942.8 5055.7 5203.5 2514.3 2676.2 2700.5 2826.9 2926.7 5474.4 5476.3 5739.3 5767.7 5772.5 5501.0 5718.2 5729.3 6027.0 6066.3 5308.8 5863.4 6045.4 6078.8 6139.2 4903.0 5521.2 5857.5 5950.0 5975.3 5710.7 5733.4 5736.8 5775.2 5827.9 4600.4 5599.9 5956.8 5971.6 6172.4 3322.5 3601.5 3610.1 3885.9 3941.8 4483.3 5428.6 5641.4 5743.6 5842.4 4693.7 5575.6 5576.6 5634.1 5641.4 4993.4 5581.7 5591.1 5636.4 5654.3 5309.5 5505.8 5537.6 5546.9 5560.5 5679.7 5686.1 5692.7 5696.8 5701.9 4902.1 5530.4 5557.4 5598.7 5722.0 03:42:21 INFO - PROCESS | 1654 | 03:42:21 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:18936.49 Median:4505.71 stddev:26585.39 (590.0%) stddev-sans-first:26624.64 03:42:21 INFO - PROCESS | 1654 | Values: 684.4 685.8 688.3 743.4 797.3 11503.6 12764.7 14600.1 14615.0 14729.5 13265.7 13332.5 13416.4 14333.9 14908.2 613.9 638.9 749.3 813.4 824.4 810.2 815.0 816.8 817.2 817.8 851.1 858.8 860.4 860.8 861.4 855.9 856.0 857.7 857.8 858.9 623.5 633.1 633.9 635.5 635.9 13920.2 14277.1 14286.9 14348.4 14634.3 12909.5 12913.7 12990.9 13046.8 13768.2 11000.8 11025.9 11285.1 11447.9 11647.0 3477.4 3602.8 3676.2 3694.6 3725.3 79830.0 81135.8 81908.0 88885.0 92460.5 10307.5 10328.3 10581.3 10596.0 10612.2 16392.7 19666.0 20141.1 20173.7 20191.1 7557.4 7600.2 7621.8 7705.1 7837.1 4183.6 4246.6 4264.3 4287.9 4317.1 3330.9 3432.9 3436.1 3437.6 3452.6 601.6 602.3 641.3 642.9 657.0 409.7 539.0 554.3 585.0 611.9 7523.7 8817.3 9126.3 9142.4 9291.3 522.3 605.6 641.0 641.9 683.2 3466.4 4390.2 4418.5 4503.8 4507.6 73505.6 73799.0 79007.9 85566.0 108777.5 78258.2 78567.6 79596.0 99259.0 100211.5 56928.0 65260.0 76681.9 79904.6 85285.7 9084.0 9613.0 9672.1 9713.3 9758.3 42131.0 45210.9 52979.7 53174.7 54513.6 69398.7 69757.7 74841.3 75392.0 78256.6 11485.7 11901.2 12212.3 12251.4 12254.1 13554.5 13684.7 13773.3 13870.0 14699.3 10828.8 12637.4 12860.0 13588.4 13591.8 12701.5 12805.4 12980.3 13496.9 13615.7 12415.4 12475.9 12695.2 12816.6 13287.6 10893.7 10901.8 11041.3 11235.4 11462.9 8631.0 9381.3 10202.4 10674.5 11303.5 03:42:21 INFO - PROCESS | 1654 | -------- Summary: end -------- 03:42:21 INFO - PROCESS | 1654 | 03:42:22 INFO - TEST-INFO | 1654: exit 0 03:42:22 INFO - TEST-OK | dromaeo_css | took 1116656ms 03:42:22 INFO - TEST-START | kraken 03:42:22 INFO - operating with platform_type : mac_ 03:42:22 INFO - Initialising browser for kraken test... 03:42:22 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpkKQTFv/profile http://localhost:49241/getInfo.html) 03:42:23 ERROR - PROCESS | 1685 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 03:42:25 INFO - PROCESS | 1685 | __metrics Screen width/height:1600/1200 03:42:25 INFO - PROCESS | 1685 | colorDepth:24 03:42:25 INFO - PROCESS | 1685 | Browser inner width/height: 1024/689 03:42:25 INFO - PROCESS | 1685 | __metrics 03:42:25 ERROR - PROCESS | 1685 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 03:42:25 INFO - TEST-INFO | 1685: exit 0 03:42:25 INFO - Browser initialized. 03:42:25 INFO - Running cycle 1/1 for kraken test... 03:42:25 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpkKQTFv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:37 INFO - PROCESS | 1688 | RSS: Main: 224092160 03:42:37 INFO - PROCESS | 1688 | 03:42:40 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 03:42:41 INFO - PROCESS | 1688 | RSS: Main: 236486656 03:42:41 INFO - PROCESS | 1688 | 03:42:44 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 03:42:44 INFO - PROCESS | 1688 | RSS: Main: 243990528 03:42:44 INFO - PROCESS | 1688 | 03:42:48 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 03:42:48 INFO - PROCESS | 1688 | RSS: Main: 247779328 03:42:48 INFO - PROCESS | 1688 | 03:42:50 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 03:42:51 INFO - PROCESS | 1688 | RSS: Main: 248242176 03:42:51 INFO - PROCESS | 1688 | 03:42:53 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:42:54 INFO - PROCESS | 1688 | RSS: Main: 249430016 03:42:54 INFO - PROCESS | 1688 | 03:42:56 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 03:42:57 INFO - PROCESS | 1688 | RSS: Main: 254054400 03:42:57 INFO - PROCESS | 1688 | 03:43:00 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 03:43:01 INFO - PROCESS | 1688 | RSS: Main: 254562304 03:43:01 INFO - PROCESS | 1688 | 03:43:04 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 03:43:04 INFO - PROCESS | 1688 | RSS: Main: 253493248 03:43:04 INFO - PROCESS | 1688 | 03:43:06 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:43:07 INFO - PROCESS | 1688 | RSS: Main: 252477440 03:43:07 INFO - PROCESS | 1688 | 03:43:09 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:43:09 INFO - PROCESS | 1688 | RSS: Main: 252735488 03:43:09 INFO - PROCESS | 1688 | 03:43:11 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:43:12 INFO - PROCESS | 1688 | RSS: Main: 252526592 03:43:12 INFO - PROCESS | 1688 | 03:43:15 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:43:16 INFO - PROCESS | 1688 | RSS: Main: 252743680 03:43:16 INFO - PROCESS | 1688 | 03:43:19 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:43:20 INFO - PROCESS | 1688 | RSS: Main: 252907520 03:43:20 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 03:43:21 INFO - PROCESS | 1688 | RSS: Main: 254795776 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | __start_tp_report 03:43:21 INFO - PROCESS | 1688 | _x_x_mozilla_page_load 03:43:21 INFO - PROCESS | 1688 | _x_x_mozilla_page_load_details 03:43:21 INFO - PROCESS | 1688 | |i|pagename|runs| 03:43:21 INFO - PROCESS | 1688 | |0;ai-astar;106;120;119;118;121;119;126;120;98;134 03:43:21 INFO - PROCESS | 1688 | |1;audio-beat-detection;149;115;118;147;115;118;119;118;140;112 03:43:21 INFO - PROCESS | 1688 | |2;audio-dft;194;203;211;193;274;218;198;181;197;195 03:43:21 INFO - PROCESS | 1688 | |3;audio-fft;83;58;77;75;65;54;58;67;106;80 03:43:21 INFO - PROCESS | 1688 | |4;audio-oscillator;81;58;86;95;96;98;111;71;72;90 03:43:21 INFO - PROCESS | 1688 | |5;imaging-gaussian-blur;93;91;94;91;112;82;132;76;133;128 03:43:21 INFO - PROCESS | 1688 | |6;imaging-darkroom;104;104;103;97;105;128;120;169;156;102 03:43:21 INFO - PROCESS | 1688 | |7;imaging-desaturate;108;108;88;88;92;87;91;127;133;105 03:43:21 INFO - PROCESS | 1688 | |8;json-parse-financial;56;57;55;57;59;61;59;59;60;59 03:43:21 INFO - PROCESS | 1688 | |9;json-stringify-tinderbox;85;72;76;81;68;69;70;69;99;62 03:43:21 INFO - PROCESS | 1688 | |10;stanford-crypto-aes;88;84;84;88;89;96;102;82;90;87 03:43:21 INFO - PROCESS | 1688 | |11;stanford-crypto-ccm;173;150;145;166;150;150;163;160;213;178 03:43:21 INFO - PROCESS | 1688 | |12;stanford-crypto-pbkdf2;172;204;220;182;169;171;173;174;170;170 03:43:21 INFO - PROCESS | 1688 | |13;stanford-crypto-sha256-iterative;70;67;68;68;67;67;67;67;66;67 03:43:21 INFO - PROCESS | 1688 | __end_tp_report 03:43:21 INFO - PROCESS | 1688 | __start_cc_report 03:43:21 INFO - PROCESS | 1688 | _x_x_mozilla_cycle_collect,1002 03:43:21 INFO - PROCESS | 1688 | __end_cc_report 03:43:21 INFO - PROCESS | 1688 | __startTimestamp1479555801971__endTimestamp 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | ------- Summary: start ------- 03:43:21 INFO - PROCESS | 1688 | Number of tests: 14 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#0] ai-astar Cycles:10 Average:118.10 Median:120.50 stddev:9.90 (8.2%) stddev-sans-first:9.49 03:43:21 INFO - PROCESS | 1688 | Values: 106.0 120.0 119.0 118.0 121.0 119.0 126.0 120.0 98.0 134.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:118.50 stddev:14.29 (12.1%) stddev-sans-first:12.26 03:43:21 INFO - PROCESS | 1688 | Values: 149.0 115.0 118.0 147.0 115.0 118.0 119.0 118.0 140.0 112.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#2] audio-dft Cycles:10 Average:206.40 Median:200.50 stddev:25.83 (12.9%) stddev-sans-first:27.00 03:43:21 INFO - PROCESS | 1688 | Values: 194.0 203.0 211.0 193.0 274.0 218.0 198.0 181.0 197.0 195.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#3] audio-fft Cycles:10 Average:72.30 Median:71.00 stddev:15.51 (21.8%) stddev-sans-first:15.96 03:43:21 INFO - PROCESS | 1688 | Values: 83.0 58.0 77.0 75.0 65.0 54.0 58.0 67.0 106.0 80.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#4] audio-oscillator Cycles:10 Average:85.80 Median:88.00 stddev:15.62 (17.7%) stddev-sans-first:16.47 03:43:21 INFO - PROCESS | 1688 | Values: 81.0 58.0 86.0 95.0 96.0 98.0 111.0 71.0 72.0 90.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#5] imaging-gaussian-blur Cycles:10 Average:103.20 Median:86.50 stddev:21.31 (24.6%) stddev-sans-first:22.28 03:43:21 INFO - PROCESS | 1688 | Values: 93.0 91.0 94.0 91.0 112.0 82.0 132.0 76.0 133.0 128.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#6] imaging-darkroom Cycles:10 Average:118.80 Median:124.00 stddev:25.00 (20.2%) stddev-sans-first:25.94 03:43:21 INFO - PROCESS | 1688 | Values: 104.0 104.0 103.0 97.0 105.0 128.0 120.0 169.0 156.0 102.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#7] imaging-desaturate Cycles:10 Average:102.70 Median:87.50 stddev:16.67 (19.0%) stddev-sans-first:17.57 03:43:21 INFO - PROCESS | 1688 | Values: 108.0 108.0 88.0 88.0 92.0 87.0 91.0 127.0 133.0 105.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:59.00 stddev:1.87 (3.2%) stddev-sans-first:1.81 03:43:21 INFO - PROCESS | 1688 | Values: 56.0 57.0 55.0 57.0 59.0 61.0 59.0 59.0 60.0 59.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#9] json-stringify-tinderbox Cycles:10 Average:75.10 Median:74.00 stddev:10.73 (14.5%) stddev-sans-first:10.77 03:43:21 INFO - PROCESS | 1688 | Values: 85.0 72.0 76.0 81.0 68.0 69.0 70.0 69.0 99.0 62.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:88.00 stddev:6.00 (6.8%) stddev-sans-first:6.35 03:43:21 INFO - PROCESS | 1688 | Values: 88.0 84.0 84.0 88.0 89.0 96.0 102.0 82.0 90.0 87.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#11] stanford-crypto-ccm Cycles:10 Average:164.80 Median:164.50 stddev:20.12 (12.2%) stddev-sans-first:21.12 03:43:21 INFO - PROCESS | 1688 | Values: 173.0 150.0 145.0 166.0 150.0 150.0 163.0 160.0 213.0 178.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:173.50 stddev:17.41 (10.0%) stddev-sans-first:18.19 03:43:21 INFO - PROCESS | 1688 | Values: 172.0 204.0 220.0 182.0 169.0 171.0 173.0 174.0 170.0 170.0 03:43:21 INFO - PROCESS | 1688 | 03:43:21 INFO - PROCESS | 1688 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:1.07 (1.6%) stddev-sans-first:0.60 03:43:21 INFO - PROCESS | 1688 | Values: 70.0 67.0 68.0 68.0 67.0 67.0 67.0 67.0 66.0 67.0 03:43:21 INFO - PROCESS | 1688 | -------- Summary: end -------- 03:43:21 INFO - PROCESS | 1688 | 03:43:22 INFO - TEST-INFO | 1688: exit 0 03:43:22 INFO - TEST-OK | kraken | took 60733ms 03:43:22 INFO - SUITE-END | took 1177s 03:43:23 INFO - Completed test suite (00:19:37) 03:43:23 INFO - javascript benchmark 03:43:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7245.544570185536, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2991.371811919497, 3376.763626546143, 3438.5186073949653, 3444.0873168610337, 3457.325821464814, 5676.176954341623, 5753.741081651326, 5762.423757624236, 5763.9711801441035, 5778.884422311551, 5746.540276777858, 5758.942410575893, 5846.093855452401, 5850.941490585093, 5891.764329426828, 4042.0, 5242.973785131069, 5249.976254630339, 5299.708516031616, 5315.096433606296, 4686.46105697844, 5339.41266460689, 5347.171188465794, 5347.491988261109, 5359.919601205985, 5196.220566914955, 5206.531412172903, 5245.763940622681, 5330.0, 5355.6251062425645, 3901.200253947934, 4401.427814384109, 5254.710990895499, 5324.068288049576, 5371.221172929942, 3993.8602148924647, 4699.718016918961, 5204.583633309327, 5245.711485868275, 5259.0796610593, 5104.515071068243, 5502.147167189092, 5802.361740208616, 5823.6505809651135, 5882.2353094097925, 5250.474952504719, 6116.602420842631, 6128.846778830521, 6131.162586540582, 6198.969005154945, 6135.569531795964, 6165.722542485599, 6169.598976066586, 6173.938260617381, 6184.5980011299125, 5922.703864806828, 5937.346891841894, 5942.970285148546, 5987.9401205987815, 5990.371011043865, 6108.083787431832, 6140.074827791203, 6182.814515564541, 6201.96899015502, 6273.654948977759, 6072.423119803611, 6074.787382441594, 6118.847028824226, 6124.899720463051, 6140.944270905864, 6130.846728831726, 6154.3845615438095, 6171.691415429211, 6181.320054793969, 6198.132261483396, 6055.6063855849225, 6103.201958139785, 6113.877722445615, 6117.0, 6134.171886795315, 6084.056971169476, 6107.0, 6115.483736156547, 6126.387361263927, 6132.969335153385, 4943.036107958986, 4951.777170027357, 4988.102141614544, 4997.425296090996, 5008.348914641147, 4439.178751930838, 4485.192665320274, 4498.235299999008, 4505.752183629932, 4583.0, 5804.79683211094, 5892.941070589325, 5904.114382842608, 6021.006533921942, 6050.878982420415, 6108.493847774667, 6114.358196333954, 6131.601445906002, 6143.296949342275, 6217.533684973645, 5087.847364579068, 5437.809676661298, 5830.883382332415, 5875.500582450454, 5997.100434934795, 5334.11987022137, 5398.703071331036, 5403.486668766461, 5407.864803379868, 5433.6196466247775, 6243.157173781481, 6328.430441260309, 6332.430081292706, 6337.17616709834, 6344.206974128234, 6079.969600151971, 6176.752929882934, 6182.381761823782, 6186.1648677427975, 6193.0, 6133.395871484143, 6180.289266734203, 6190.845228869314, 6192.752289908533, 6193.102000209854, 6131.908021379772, 6145.955187618027, 6160.476359509313, 6161.353057928853, 6161.876762464815, 6133.662648554194, 6170.50635949114, 6172.197614310111, 6193.659348735863, 6200.875982480416, 4778.685861388146, 4953.603711703127, 5958.106284057426, 6032.939670603413, 6079.209702738615, 6068.787592434243, 6074.362191969956, 6075.726592303446, 6085.695715214133, 6115.113308570321, 4660.533946605312, 5029.874253143701, 5899.321578018583, 5974.2233509643465, 6059.394060593905, 6005.219321488178, 6057.757689692363, 6088.36072212429, 6118.908216376669, 6128.019516877278, 4524.479684836286, 5021.196608542616, 5908.763649453974, 5960.165576819336, 6008.969955150196], "value": 5621.0264283914785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.83020452228146, 468.8780916961589, 505.17908398851864, 513.2890946039734, 515.3377909386438, 37554.81222593887, 39352.409713854315, 39421.01447463815, 39470.80264598677, 39649.60350396495, 40534.0, 40865.387019194735, 40915.59084409155, 40973.79513102438, 41010.58989410105, 8294.253517183452, 9302.627894884214, 9475.289353298496, 9491.857622135674, 9506.239500839916, 9423.575939082724, 9496.050394960517, 9523.523823808782, 9527.571259293314, 9529.857052144223, 9496.66761663342, 9511.57197926095, 9531.046895310483, 9541.0, 9555.331126821126, 9410.62357505699, 9529.04709529045, 9531.142197202285, 9536.952315238448, 9542.856857147148, 9360.297977651704, 9456.385334953206, 9466.19537339327, 9475.952620236854, 9478.573464194127, 38327.42508862369, 38691.41962870559, 38781.0, 38901.0, 38936.0, 3325.7671962962604, 3333.399988002159, 3529.9117522061897, 3795.8671446499516, 3829.5213098362706, 28342.858285708644, 29159.854200729074, 29267.853660731562, 29499.0, 30116.24709382261, 3200.7119359257777, 3214.9839250803598, 3228.1122691260093, 3228.596425446819, 3306.6032076150777, 467575.0, 468342.0, 469782.6510867458, 470353.0, 470728.0, 55011.17483237755, 55399.0, 55481.72259138679, 55497.0, 55513.0, 57622.847543048905, 57654.0, 58053.4194658048, 58242.12636810451, 58857.70571147117, 15269.0, 15421.228938553028, 15440.536783896501, 15450.922745386426, 15503.612409689847, 11893.226940248856, 11948.701282467833, 11998.700032499082, 12009.759804804029, 12011.33937633421, 13789.0, 14825.0, 14828.258587070819, 14849.257537123101, 14976.47582334613, 5793.971030144907, 5906.409359064145, 6215.844603884849, 6308.968455157695, 6309.842253943688, 848.5841937450729, 972.562346943864, 979.5591983607405, 1023.4422239879284, 1047.418682631147, 23759.40601484942, 28935.0, 29470.557941630894, 29527.966521172086, 29714.108576742732, 958.9978472496258, 962.831504486712, 974.6452431120606, 1035.1304903880625, 1099.0603034405628, 14696.853031469762, 15070.547883563511, 15143.242837858063, 15343.846561534241, 15418.0, 499473.50263248454, 582987.0, 622916.88541557, 631105.0, 632064.0, 55251.0, 55264.44735552673, 55406.72296638491, 55450.0, 55500.16749748757, 1251.28676654308, 1341.121565374681, 1359.592122363287, 1368.2337890781193, 1368.2406264523086, 28160.577591335736, 28797.71202288034, 28998.985035523656, 29142.0, 29414.0, 40839.7958010208, 47596.810079748284, 48060.519394805604, 48084.03831923272, 48420.51579484301, 585775.0, 590478.0, 591553.0, 592102.0, 594914.0, 37784.811075944446, 38176.618233818415, 38422.078896055646, 38466.80766596261, 38512.42231366588, 44108.779456102515, 44512.88717782081, 45461.31808022948, 45592.0, 45831.54168458273, 32401.51397729083, 36038.45942310815, 37182.12035521137, 37679.246415071, 38580.614193857706, 36855.44716829196, 36926.446103307935, 37427.251454971294, 37536.43695344627, 37688.0, 30705.92529261465, 35253.0, 35837.46243806397, 36225.818870905474, 36328.81835590805, 27971.720282796912, 30830.691693082783, 32828.015159545255, 32887.8489252875, 32956.50565241571, 24727.0, 24798.0, 24809.62785558182, 24973.125940591992, 25249.621255680813], "value": 19271.529389618372, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.9773814702045, 366.29305440499843, 378.9242151569686, 383.9212961342925, 394.53839008360217, 10956.506957186926, 11362.545498180074, 11497.42512874357, 11528.423578821046, 18658.25366985319, 18716.625667486675, 20614.0, 20703.792962070376, 20840.47898802531, 21632.35102946914, 5876.001079816441, 5984.79053233138, 6222.004479283316, 6802.761903333384, 6840.144981877265, 4509.909801803962, 6416.679166041679, 7252.528585641943, 7613.390928725717, 7672.309492145706, 6523.4455071319, 7381.040464739576, 7637.427192960522, 7729.690812367498, 7733.9226607734045, 7321.414286857039, 7584.127825300078, 7658.195889431585, 7670.348020418243, 7678.462507624468, 6336.936630633681, 7390.8891366629005, 7448.180700122982, 7450.813729656802, 7485.401167906609, 21147.25984590547, 26824.195274141806, 26987.785549650118, 27361.589576156373, 27398.315042124108, 2385.1771138957033, 2395.9880200598886, 2435.3546310227803, 2500.524900268943, 2634.183403145031, 15838.920805396014, 16908.90092144007, 17073.658526829517, 17106.828931710646, 17253.0, 1968.7637483501928, 2086.384516567616, 2086.968695469554, 2113.95772084559, 2128.88291143987, 95961.0, 146093.0, 146776.0, 148512.2574387132, 148991.25504372516, 21349.893250533958, 21694.45763855916, 21978.340649780534, 22009.66985495219, 22042.33872983813, 34446.48330275048, 34537.30925381478, 34953.65046349555, 35108.648913511046, 35362.11594710153, 10239.897601024044, 10555.208359372924, 10762.73093172677, 10778.0, 10899.455027248607, 8704.825903481895, 8720.825583488422, 8722.47665140081, 8817.735467935972, 8926.0, 9565.378250413702, 9778.462184579916, 9800.509974501247, 9842.261830362751, 9884.505774711235, 1569.3879387039065, 1664.625459271654, 1670.607407259293, 1679.7480377943161, 1698.346136737365, 760.1106705154982, 938.7606160429184, 990.1385794358954, 992.2409356842022, 1015.5785349080197, 19919.701204481946, 20586.382408527767, 20598.691019634716, 20695.06872190755, 20745.688814667792, 929.3494553812359, 930.6044930904338, 951.4291425144858, 967.511406739592, 986.3194395866828, 10526.210534209811, 10920.344779313267, 10952.4523773811, 11007.06439952597, 11042.723931901766, 583499.0, 591799.0, 592627.0, 596756.0, 598910.0, 117926.41036794933, 127627.0, 128023.0, 128820.3558982199, 132511.0, 978.1587834462357, 983.8327484327682, 1022.3559157730582, 1028.4857571214393, 1033.0805583031108, 16771.0, 18322.633547329246, 18553.794003389736, 18976.715349269496, 19664.213431463155, 55188.44811551833, 55607.721961389936, 56233.8753224925, 57606.7119664399, 59127.70436147792, 71041.0, 75424.49151016839, 77428.0, 78370.6081469589, 79130.20869791228, 7665.156832748505, 7733.4973226740085, 8199.877001845096, 8210.3021243195, 8232.217939295757, 11778.705532361759, 12305.569305074638, 12870.22778633285, 12908.806367904677, 12926.547570834975, 8722.389432739647, 9893.50532473359, 10238.795224095626, 10860.022597966221, 11125.721856953642, 8104.432689711663, 9528.475933823705, 9846.212303015885, 9904.504774761377, 9912.256580756328, 11221.214514983873, 11254.437278136224, 11316.886831131584, 11682.88317116852, 11709.0, 8133.227343402367, 8201.876971845546, 8244.134365891496, 8334.49993000422, 8459.577021149042, 9592.113394868513, 11156.832647510457, 11504.884951150381, 11567.942160289145, 11626.651200463999], "value": 10572.671193099814, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.3971991343503, 1514.2050423527646, 1691.3319238900635, 1786.7766529183853, 1801.24347773935, 7629.771106866789, 7870.685172593089, 8077.434579579432, 8541.103184165659, 8565.443246188994, 7298.489105762599, 8010.319122874548, 8340.499570025802, 8391.70629027984, 8467.95766021169, 2283.257941169114, 2329.474194402669, 2329.848559843613, 2338.251759436981, 2339.157903154863, 2305.4812667149927, 2336.1823361823313, 2365.47959448921, 2371.537550177716, 2380.73811880693, 2309.4919117794057, 2313.5488579726875, 2329.091654254842, 2337.3805941425453, 2342.16853017179, 2285.5771682238674, 2288.4507718147606, 2304.2741536415974, 2311.133325003124, 2312.722473303206, 2316.9073237070497, 2350.118705485443, 2353.9529209415887, 2355.8115350771895, 2357.799587035087, 8382.664693412256, 8444.788880278042, 8455.323574114056, 8459.450135741157, 8474.576271186415, 5088.643794934356, 5312.0, 5319.361676598833, 5321.281626980347, 5331.733413329318, 3226.5644138041534, 3374.898753037413, 3478.113081164312, 3534.7348948828685, 3542.8582856685957, 980.8185485685171, 1010.5957616953204, 1049.7690508088208, 1056.8203405421098, 1061.6443491430302, 10778.676639700821, 10913.345199288067, 10986.066184374338, 11035.227534072617, 11036.392998385167, 4513.345564893072, 4669.299605059268, 4742.5020372861045, 4751.477337492873, 4775.403074615673, 9479.573419196153, 9535.52322383878, 9570.0, 9571.090746379083, 9577.856332155023, 4613.953860461353, 5265.631405801634, 5281.630285879952, 5286.15421532553, 5292.5236728693835, 4267.786610669454, 4293.720908140961, 4747.715137091785, 4760.357351757538, 4814.325994360793, 3885.7668539887695, 3887.475190849199, 3974.741641793274, 4001.1197536541913, 4011.839526418969, 1109.3066833229232, 1254.5044707340548, 1287.819705241267, 1289.0267847775058, 1301.576987479073, 823.7281697039963, 892.790194304338, 893.4549924546021, 916.5600511754395, 919.0625561926797, 5058.988202359543, 5606.495415412633, 5723.513501352349, 5790.0, 5830.591858569945, 918.8483900156399, 928.2434815625244, 973.0512750068768, 991.5141580625442, 991.5240684471494, 2951.1736713719765, 3466.2374277658873, 3842.692584593226, 3926.6466018058513, 3930.21395720857, 9580.712578622653, 9823.75440613976, 10182.541785619665, 10210.183185345155, 10230.976902309709, 9969.900300997042, 10206.336588121749, 10208.183345332356, 10210.336328138646, 10252.743681408025, 6887.931120688829, 7857.7249796258, 7914.169012253746, 7950.284474397447, 8019.919800802034, 5486.725663716878, 5562.833115006556, 5566.554675626021, 5657.886842263214, 5671.319441667027, 9287.024862389622, 9334.06659334061, 9390.530473476436, 9481.336306458481, 9629.133377995733, 8564.229219370287, 8799.0, 9734.123928846439, 9747.0, 9792.0, 6212.627242365473, 6425.710843012169, 6614.4708423325055, 6691.364320389555, 6811.8978215325815, 7720.7683769487, 7947.841043178988, 7962.004749406325, 7989.680412783424, 8024.358051355761, 7063.6821343038655, 7211.855762884608, 7219.422446204186, 7228.566286022856, 7313.414926805949, 6944.126231484189, 7000.054992576141, 7021.648917554204, 7146.213916469081, 7154.427645788429, 6719.966400167968, 6730.091437655854, 6812.250652428328, 6820.761273355409, 6829.112215411965, 4671.9299210512545, 4784.378030855966, 4824.541668541483, 4856.125897338513, 4866.440359358719, 4175.373693945932, 4496.303073023687, 4643.117807616542, 4793.9280910787065, 5039.647224694383], "value": 4425.747623047276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.5501227863517, 300.77892748829606, 308.5695454840499, 310.58381768430297, 313.99843000784995, 4901.33831932689, 4919.458859525449, 5337.70642614656, 5409.323834520685, 5421.674699518032, 4408.4291723703745, 4694.835680751175, 5128.999690120847, 5281.287026251455, 5355.7857685692525, 3950.5061867266663, 4143.958560414388, 4146.834126634931, 4154.127633197032, 4166.895827604319, 3974.0, 4158.438610787535, 4169.457970463835, 4191.2246234446575, 4192.77156589426, 3142.968570314302, 3426.623071462137, 3510.2277498950316, 3605.837737301828, 3769.698424126077, 4034.3141665916864, 4107.0, 4110.972256935781, 4112.712110152291, 4113.848122525697, 3181.8727250909938, 3325.1022223999626, 3369.393509168349, 3585.032041348847, 3635.2729454109285, 4294.0676695486845, 4787.425508938914, 4872.076753947638, 4992.350994370745, 5344.9732751336, 3826.770393776382, 4587.151376995266, 4590.472095709003, 4593.586577208034, 4659.860204193879, 4805.299919062338, 5117.189841548932, 5653.8586535336535, 5654.067078931971, 5780.121460525346, 3350.9286217245917, 3443.8450269737923, 3634.800515829796, 3727.329080765461, 3858.440526123696, 5229.660072095302, 5440.755166017539, 5898.835716676083, 5959.374265702082, 5960.13913321608, 3483.7038851697384, 3883.0, 3895.4156876468755, 3907.941380879289, 3921.8039098044983, 3351.4302568563403, 3438.398280300937, 3854.76647472806, 3976.9717444103135, 3999.5800440953863, 4303.600155876822, 4584.61030812378, 4606.608438282717, 4608.539146085364, 4634.930476042862, 5172.956682110545, 5558.333000040021, 5789.218455508537, 5794.449527294901, 5813.215215945877, 1080.3140006096028, 1147.8106112491512, 1238.108561835477, 1264.4057293072242, 1280.551806867589, 818.3453237410048, 866.9942866275217, 871.3900269811157, 874.2044739287345, 875.3259989807813, 4593.438231001475, 5082.313887625197, 5103.336566246364, 5182.352205974254, 5207.9844430335725, 4686.929696054562, 5445.428230035789, 5527.585431092684, 5657.462541058593, 5679.967223271524, 4611.788678792943, 4923.532264434873, 4942.787188327544, 5055.724923784238, 5203.490987613625, 2514.333701569086, 2676.183763952013, 2700.4599080184043, 2826.886924523037, 2926.678065412803, 5474.42518535556, 5476.316604863668, 5739.311282646109, 5767.711614419262, 5772.451617096369, 5500.972495137498, 5718.1708652246025, 5729.28383952006, 6027.0166267696895, 6066.332703402622, 5308.814191503279, 5863.355030946678, 6045.425684559959, 6078.817635470943, 6139.171211886517, 4902.950970490249, 5521.205608177278, 5857.531397488276, 5950.0, 5975.312839023568, 5710.714464276853, 5733.369329373849, 5736.827895163152, 5775.220345253337, 5827.9417205829095, 4600.355950166912, 5599.944000559943, 5956.761729530944, 5971.611845230046, 6172.4136207060255, 3322.4850148227065, 3601.495790589267, 3610.061384040116, 3885.8834234972996, 3941.822617982255, 4483.327500874895, 5428.56571474289, 5641.37944826077, 5743.62666426681, 5842.38654941225, 4693.741844198602, 5575.553955683455, 5576.553875690016, 5634.140733557998, 5641.435856414326, 4993.375828021497, 5581.664810440098, 5591.064691037238, 5636.420872745918, 5654.2649455572155, 5309.495597918191, 5505.752241149078, 5537.640053396516, 5546.944530554642, 5560.499555040065, 5679.716014199356, 5686.066737309335, 5692.658440493418, 5696.772129114789, 5701.857453563694, 4902.093112774148, 5530.364008139116, 5557.44425557441, 5598.69207193592, 5722.0], "value": 4054.46101198361, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.4216636941783, 685.8388278754495, 688.2566827825948, 743.4387037786471, 797.3262593108823, 11503.597374091909, 12764.68088297793, 14600.12399256044, 14614.976951613378, 14729.45340739223, 13265.734685306312, 13332.533361332355, 13416.396262168202, 14333.928330358334, 14908.180050097268, 613.9232595925487, 638.8881945659514, 749.3068911257066, 813.3615112136973, 824.3611201318965, 810.2100452059254, 815.0097631377874, 816.824382757707, 817.1868990354606, 817.7587611654576, 851.1233429567556, 858.7595473267464, 860.3633311349588, 860.7805009722512, 861.3798065392907, 855.8930133733284, 856.0369584217755, 857.6869442653418, 857.8327226190895, 858.9184027517376, 623.4513628007325, 633.1420924647083, 633.8510450044282, 635.4566845347254, 635.8887194740948, 13920.234387108705, 14277.14337139775, 14286.928565357106, 14348.42606295747, 14634.268286585735, 12909.54816581415, 12913.677158071123, 12990.895773859233, 13046.804297935538, 13768.24274664893, 11000.834987475195, 11025.889741102566, 11285.097192224603, 11447.942760286229, 11647.0, 3477.3914564951288, 3602.7838329700303, 3676.2096149327754, 3694.649008344203, 3725.2549490102087, 79830.0, 81135.78296325619, 81908.0, 88885.0, 92460.53769731108, 10307.484625768682, 10328.277020608635, 10581.259311848096, 10596.0, 10612.204084693525, 16392.672146557, 19666.0, 20141.093650785457, 20173.697394539093, 20191.091400886995, 7557.35762460197, 7600.239976002355, 7621.809454763676, 7705.113911900204, 7837.059552853694, 4183.6234738873645, 4246.575342465729, 4264.33902745075, 4287.914241715211, 4317.114991426813, 3330.900072997814, 3432.8798492052656, 3436.0722604897037, 3437.553118094682, 3452.6374730653424, 601.648035899004, 602.2802750712856, 641.3169973977699, 642.8907085795425, 656.9507286953498, 409.70910653436397, 538.9595780316492, 554.2988120028167, 584.9792112763171, 611.9418655227746, 7523.736669216661, 8817.29461643067, 9126.315526335551, 9142.405743626643, 9291.349605527683, 522.3496746550569, 605.5579427018301, 640.9967950160312, 641.9390157934989, 683.1699485125497, 3466.445368740989, 4390.2317094508335, 4418.536053714378, 4503.842365517191, 4507.57178068083, 73505.63247183837, 73799.0, 79007.86448880805, 85566.0, 108777.45611271892, 78258.21741782509, 78567.60716196382, 79596.0, 99259.0, 100211.49894250628, 56928.0, 65260.0, 76681.93272269047, 79904.60047699725, 85285.72071418933, 9084.046175151514, 9613.0, 9672.08115229052, 9713.320067595483, 9758.316917815684, 42131.0, 45210.86972825706, 52979.735101324244, 53174.670633232934, 54513.63715907134, 69398.65300673465, 69757.65121174362, 74841.25158748344, 75392.0, 78256.60871695605, 11485.712857178638, 11901.166918315635, 12212.267263964191, 12251.448684809384, 12254.080943929062, 13554.52559160464, 13684.65788355299, 13773.311334433438, 13870.029097963046, 14699.338529765972, 10828.808831028424, 12637.368131593568, 12860.03549733755, 13588.388522516312, 13591.79612305836, 12701.491940322283, 12805.359732013176, 12980.286084265455, 13496.865031349953, 13615.727685446434, 12415.37923103823, 12475.875241247835, 12695.174813637084, 12816.55142070023, 13287.601371958857, 10893.727656808644, 10901.812809455574, 11041.282316649393, 11235.382053987109, 11462.853714628804, 8630.964284285927, 9381.343305968516, 10202.38785672862, 10674.46627668598, 11303.547858085585], "value": 7039.498531780498, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 120.0, 119.0, 118.0, 121.0, 119.0, 126.0, 120.0, 98.0, 134.0], "value": 118.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 118.0, 147.0, 115.0, 118.0, 119.0, 118.0, 140.0, 112.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 203.0, 211.0, 193.0, 274.0, 218.0, 198.0, 181.0, 197.0, 195.0], "value": 206.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 77.0, 75.0, 65.0, 54.0, 58.0, 67.0, 106.0, 80.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 86.0, 95.0, 96.0, 98.0, 111.0, 71.0, 72.0, 90.0], "value": 85.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 94.0, 91.0, 112.0, 82.0, 132.0, 76.0, 133.0, 128.0], "value": 103.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 104.0, 103.0, 97.0, 105.0, 128.0, 120.0, 169.0, 156.0, 102.0], "value": 118.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 88.0, 88.0, 92.0, 87.0, 91.0, 127.0, 133.0, 105.0], "value": 102.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 55.0, 57.0, 59.0, 61.0, 59.0, 59.0, 60.0, 59.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 76.0, 81.0, 68.0, 69.0, 70.0, 69.0, 99.0, 62.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 84.0, 88.0, 89.0, 96.0, 102.0, 82.0, 90.0, 87.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 150.0, 145.0, 166.0, 150.0, 150.0, 163.0, 160.0, 213.0, 178.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 204.0, 220.0, 182.0, 169.0, 171.0, 173.0, 174.0, 170.0, 170.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0], "value": 67.4, "unit": "score"}]}]} 03:43:23 INFO - Return code: 0 03:43:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:43:23 ERROR - # TBPL SUCCESS # 03:43:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:43:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:43:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:43:23 INFO - 2016-11-19 03:43:23.301 screenresolution[1693:13216] starting screenresolution argv=screenresolution get 03:43:23 INFO - 2016-11-19 03:43:23.303 screenresolution[1693:13216] Display 0: 1600x1200x32@60 03:43:23 INFO - 2016-11-19 03:43:23.309 screenresolution[1694:13219] starting screenresolution argv=screenresolution list 03:43:23 INFO - Available Modes on Display 0 03:43:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:43:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:43:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:43:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:43:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:43:23 INFO - Intel Iris: 03:43:23 INFO - Chipset Model: Intel Iris 03:43:23 INFO - Type: GPU 03:43:23 INFO - Bus: Built-In 03:43:23 INFO - VRAM (Dynamic, Max): 1536 MB 03:43:23 INFO - Vendor: Intel (0x8086) 03:43:23 INFO - Device ID: 0x0a2e 03:43:23 INFO - Revision ID: 0x0009 03:43:23 INFO - Displays: 03:43:23 INFO - Display: 03:43:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:43:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:43:23 INFO - Display Serial Number: 1600x1200 60 03:43:23 INFO - Main Display: Yes 03:43:23 INFO - Mirror: Off 03:43:23 INFO - Online: Yes 03:43:23 INFO - Rotation: Supported 03:43:23 INFO - Return code: 0 03:43:23 INFO - Running post-action listener: _resource_record_post_action 03:43:23 INFO - [mozharness: 2016-11-19 11:43:23.545648Z] Finished run-tests step (success) 03:43:23 INFO - Running post-run listener: _resource_record_post_run 03:43:23 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32173056; Write bytes: 657397760; Read time: 194; Write time: 1942 03:43:23 INFO - TinderboxPrint: CPU usage
54.5% 03:43:23 INFO - TinderboxPrint: I/O read bytes / time
32,173,056 / 194 03:43:23 INFO - TinderboxPrint: I/O write bytes / time
657,397,760 / 1,942 03:43:23 INFO - TinderboxPrint: CPU idle
2,205.7 (45.5%) 03:43:23 INFO - TinderboxPrint: CPU system
985.2 (20.3%) 03:43:23 INFO - TinderboxPrint: CPU user
1,654.8 (34.1%) 03:43:23 INFO - TinderboxPrint: Swap in / out
381,054,976 / 0 03:43:23 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 344478208; Write bytes: 345585664; Read time: 27358; Write time: 732 03:43:23 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31251456; Write bytes: 301789184; Read time: 184; Write time: 1174 03:43:24 INFO - Running post-run listener: _upload_blobber_files 03:43:24 INFO - Blob upload gear active. 03:43:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:43:24 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 03:43:24 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'] 03:43:24 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 03:43:24 INFO - (blobuploader) - INFO - Open directory for files ... 03:43:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:43:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:43:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:26 INFO - (blobuploader) - INFO - Done attempting. 03:43:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:43:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:43:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:27 INFO - (blobuploader) - INFO - Done attempting. 03:43:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:43:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:43:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:29 INFO - (blobuploader) - INFO - Done attempting. 03:43:29 INFO - (blobuploader) - INFO - Iteration through files over. 03:43:29 INFO - Return code: 0 03:43:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:43:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:43:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df0b89938dc59abcfe524d92bc6d2757a61c100e08a4ec9c58e56e4e07771532033add0887dc2e08507822418fe0589820ea2487bd83984f9fde701666d56be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d769c0d6fa2764807c854c0bad6678aa8fd56d73aef092b691e136dab3260da2e4cf9517a7fcb85b46483d22dbb178e7656df9a6f92e0676c9448f6dccf8e95f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7ee6ab0257075cef00076247feb1e285ee6519bb5b4fe3d752b63a065603d327a5d6571d9ae4a371b2ed48ed2297dce5da88e5cd5c1f7c5eeb9b05e8ecb6a67"} 03:43:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:43:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:43:29 INFO - Contents: 03:43:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df0b89938dc59abcfe524d92bc6d2757a61c100e08a4ec9c58e56e4e07771532033add0887dc2e08507822418fe0589820ea2487bd83984f9fde701666d56be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d769c0d6fa2764807c854c0bad6678aa8fd56d73aef092b691e136dab3260da2e4cf9517a7fcb85b46483d22dbb178e7656df9a6f92e0676c9448f6dccf8e95f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7ee6ab0257075cef00076247feb1e285ee6519bb5b4fe3d752b63a065603d327a5d6571d9ae4a371b2ed48ed2297dce5da88e5cd5c1f7c5eeb9b05e8ecb6a67"} 03:43:29 INFO - Running post-run listener: copy_logs_to_upload_dir 03:43:29 INFO - Copying logs to upload dir... 03:43:29 INFO - mkdir: /builds/slave/test/build/upload/logs 03:43:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.508939 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-19 03:43:29.682602) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 03:43:29.686476) ========= 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.z3AYUogbJS/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.t8I3Q0v08X/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/5df0b89938dc59abcfe524d92bc6d2757a61c100e08a4ec9c58e56e4e07771532033add0887dc2e08507822418fe0589820ea2487bd83984f9fde701666d56be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d769c0d6fa2764807c854c0bad6678aa8fd56d73aef092b691e136dab3260da2e4cf9517a7fcb85b46483d22dbb178e7656df9a6f92e0676c9448f6dccf8e95f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7ee6ab0257075cef00076247feb1e285ee6519bb5b4fe3d752b63a065603d327a5d6571d9ae4a371b2ed48ed2297dce5da88e5cd5c1f7c5eeb9b05e8ecb6a67"} build_url:https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011456 build_url: 'https://queue.taskcluster.net/v1/task/Y9SanmNxSfegEM4lYnBAOQ/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/5df0b89938dc59abcfe524d92bc6d2757a61c100e08a4ec9c58e56e4e07771532033add0887dc2e08507822418fe0589820ea2487bd83984f9fde701666d56be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d769c0d6fa2764807c854c0bad6678aa8fd56d73aef092b691e136dab3260da2e4cf9517a7fcb85b46483d22dbb178e7656df9a6f92e0676c9448f6dccf8e95f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7ee6ab0257075cef00076247feb1e285ee6519bb5b4fe3d752b63a065603d327a5d6571d9ae4a371b2ed48ed2297dce5da88e5cd5c1f7c5eeb9b05e8ecb6a67"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 03:43:29.719651) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 03:43:29.720007) ========= 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.z3AYUogbJS/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.t8I3Q0v08X/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.005060 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 03:43:29.768970) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 03:43:29.769324) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 03:43:29.769682) ========= ========= Total master_lag: 0.18 =========