builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0240 starttime: 1483410045.68 results: success (0) buildid: 20170102160620 builduid: 71baf702b9874b6881f910d8d38cd40a revision: 741296a2d92b93348fe573b2b5884d6421ba4282 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:45.682408) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:45.682929) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:45.683206) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:45.750077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:45.750367) ========= 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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-02 18:20:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 73.0M=0s 2017-01-02 18:20:46 (73.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.566615 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:46.343196) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:46.343497) ========= 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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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.019376 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:46.393581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-02 18:20:46.393914) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 741296a2d92b93348fe573b2b5884d6421ba4282 --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 741296a2d92b93348fe573b2b5884d6421ba4282 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GMs7DlEo1u/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.M3xiXXKgvg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-02 18:20:46,476 truncating revision to first 12 chars 2017-01-02 18:20:46,476 Setting DEBUG logging. 2017-01-02 18:20:46,476 attempt 1/10 2017-01-02 18:20:46,477 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/741296a2d92b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 18:20:47,574 unpacking tar archive at: mozilla-aurora-741296a2d92b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.361270 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-02 18:20:47.780113) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:47.780396) ========= script_repo_revision: 741296a2d92b93348fe573b2b5884d6421ba4282 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:47.780770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:47.781030) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 18:20:47.801307) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-02 18:20:47.801590) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:20:47 INFO - MultiFileLogger online at 20170102 18:20:47 in /builds/slave/test 18:20:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:20:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:20:48 INFO - {'append_to_log': False, 18:20:48 INFO - 'base_work_dir': '/builds/slave/test', 18:20:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:20:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:20:48 INFO - 'branch': 'Mozilla-Aurora', 18:20:48 INFO - 'buildbot_json_path': 'buildprops.json', 18:20:48 INFO - 'config_files': ('talos/mac_config.py',), 18:20:48 INFO - 'default_actions': ('clobber', 18:20:48 INFO - 'read-buildbot-config', 18:20:48 INFO - 'download-and-extract', 18:20:48 INFO - 'populate-webroot', 18:20:48 INFO - 'create-virtualenv', 18:20:48 INFO - 'install', 18:20:48 INFO - 'run-tests'), 18:20:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:20:48 INFO - 'download_minidump_stackwalk': True, 18:20:48 INFO - 'download_symbols': 'ondemand', 18:20:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:20:48 INFO - 'tooltool.py': '/tools/tooltool.py', 18:20:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:20:48 INFO - '/tools/misc-python/virtualenv.py')}, 18:20:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:20:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:20:48 INFO - 'installer_path': 'installer.exe', 18:20:48 INFO - 'log_level': 'info', 18:20:48 INFO - 'log_name': 'talos', 18:20:48 INFO - 'log_to_console': True, 18:20:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:20:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:20:48 INFO - 'opt_config_files': (), 18:20:48 INFO - 'pip_index': False, 18:20:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:20:48 INFO - 'cmd': ('bash', 18:20:48 INFO - '-c', 18:20:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:20:48 INFO - 'enabled': True, 18:20:48 INFO - 'halt_on_failure': False, 18:20:48 INFO - 'name': 'check_screen_resolution'},), 18:20:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:20:48 INFO - 'cmd': ('bash', 18:20:48 INFO - '-c', 18:20:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:20:48 INFO - 'enabled': True, 18:20:48 INFO - 'halt_on_failure': False, 18:20:48 INFO - 'name': 'check_screen_resolution'},), 18:20:48 INFO - 'run_cmd_checks_enabled': True, 18:20:48 INFO - 'sps_profile': False, 18:20:48 INFO - 'sps_profile_interval': 0, 18:20:48 INFO - 'suite': 'dromaeojs', 18:20:48 INFO - 'system_bits': '32', 18:20:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:20:48 INFO - 'title': 't-yosemite-r7-0240', 18:20:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:20:48 INFO - 'use_talos_json': True, 18:20:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:20:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:20:48 INFO - 'work_dir': 'build'} 18:20:48 INFO - [mozharness: 2017-01-03 02:20:48.008766Z] Running clobber step. 18:20:48 INFO - Running pre-action listener: _resource_record_pre_action 18:20:48 INFO - Running main action method: clobber 18:20:48 INFO - rmtree: /builds/slave/test/build 18:20:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:20:49 INFO - Running post-action listener: _resource_record_post_action 18:20:49 INFO - [mozharness: 2017-01-03 02:20:49.648601Z] Finished clobber step (success) 18:20:49 INFO - [mozharness: 2017-01-03 02:20:49.648725Z] Running read-buildbot-config step. 18:20:49 INFO - Running pre-action listener: _resource_record_pre_action 18:20:49 INFO - Running main action method: read_buildbot_config 18:20:49 INFO - Using buildbot properties: 18:20:49 INFO - { 18:20:49 INFO - "project": "", 18:20:49 INFO - "product": "firefox", 18:20:49 INFO - "script_repo_revision": "production", 18:20:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:20:49 INFO - "repository": "", 18:20:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:20:49 INFO - "buildid": "20170102160620", 18:20:49 INFO - "pgo_build": "False", 18:20:49 INFO - "basedir": "/builds/slave/test", 18:20:49 INFO - "buildnumber": 73, 18:20:49 INFO - "slavename": "t-yosemite-r7-0240", 18:20:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:20:49 INFO - "platform": "yosemite_r7", 18:20:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:20:49 INFO - "branch": "mozilla-aurora", 18:20:49 INFO - "repo_path": "releases/mozilla-aurora", 18:20:49 INFO - "revision": "741296a2d92b93348fe573b2b5884d6421ba4282", 18:20:49 INFO - "stage_platform": "macosx64", 18:20:49 INFO - "builduid": "71baf702b9874b6881f910d8d38cd40a", 18:20:49 INFO - "slavebuilddir": "test" 18:20:49 INFO - } 18:20:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 18:20:49 INFO - Running post-action listener: _resource_record_post_action 18:20:49 INFO - [mozharness: 2017-01-03 02:20:49.652059Z] Finished read-buildbot-config step (success) 18:20:49 INFO - [mozharness: 2017-01-03 02:20:49.652195Z] Running download-and-extract step. 18:20:49 INFO - Running pre-action listener: _resource_record_pre_action 18:20:49 INFO - Running main action method: download_and_extract 18:20:49 INFO - mkdir: /builds/slave/test/build/tests 18:20: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')]} 18:20:49 INFO - trying https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 18:20:49 INFO - Downloading https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/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 18:20:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/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 18:20:50 INFO - Downloaded 1199 bytes. 18:20:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:20:50 INFO - Using the following test package requirements: 18:20:50 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:20:50 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:20:50 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 18:20:50 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:20:50 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 18:20:50 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:20:50 INFO - u'jsshell-mac.zip'], 18:20:50 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:20:50 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 18:20:50 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:20:50 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:20:50 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 18:20:50 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:20:50 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 18:20:50 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:20:50 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 18:20:50 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:20:50 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 18:20:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:20:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:20:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:20:50 INFO - Fetch https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:20:51 INFO - Http code: 200 18:20:51 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 18:20:51 INFO - x-amz-cf-id: K_uQd_-pVB1cjQ_77kIAwf3wUPuM7vgtuoInmwuOwXzVDoVh5QpZvg== 18:20:51 INFO - x-amz-version-id: KghzPbTxv.x_2bvK3VixAGX_dIL6bYS_ 18:20:51 INFO - Expected file size: 16268470 18:20:51 INFO - Obtained file size: 16268470 18:20:58 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 18:20:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:20:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:20:58 INFO - Fetch https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:20:58 INFO - Http code: 200 18:20:58 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 18:20:58 INFO - x-amz-cf-id: vFyZMGMGDPrz4R9aa3ZhP2BDjYMfkO9log46wn6xSNwEDe97rbExmw== 18:20:58 INFO - x-amz-version-id: KghzPbTxv.x_2bvK3VixAGX_dIL6bYS_ 18:20:58 INFO - Expected file size: 16268470 18:20:58 INFO - Obtained file size: 16268470 18:21:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 18:21:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:21:05 INFO - Fetch https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 18:21:05 INFO - Http code: 200 18:21:05 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 18:21:05 INFO - x-amz-cf-id: X-s546XwLMqXIenmEIKLCCfvY5mJoqc0azAMtr26ObA4XZvi68RvlA== 18:21:05 INFO - x-amz-version-id: Fku8pdtuTcJzCKwq2ZnWohDuppZlvJvq 18:21:06 INFO - Expected file size: 14409038 18:21:06 INFO - Obtained file size: 14409038 18:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:07 INFO - trying https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:21:07 INFO - Downloading https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 18:21:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/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 18:21:10 INFO - Downloaded 109831082 bytes. 18:21:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:21:10 INFO - mkdir: /builds/slave/test/properties 18:21:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:21:10 INFO - Writing to file /builds/slave/test/properties/build_url 18:21:10 INFO - Contents: 18:21:10 INFO - build_url:https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:21:11 INFO - Running post-action listener: _resource_record_post_action 18:21:11 INFO - Running post-action listener: set_extra_try_arguments 18:21:11 INFO - [mozharness: 2017-01-03 02:21:11.126515Z] Finished download-and-extract step (success) 18:21:11 INFO - [mozharness: 2017-01-03 02:21:11.126640Z] Running populate-webroot step. 18:21:11 INFO - Running pre-action listener: _resource_record_pre_action 18:21:11 INFO - Running main action method: populate_webroot 18:21:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:21:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:21:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:21:11 INFO - '--tppagecycles', 18:21:11 INFO - '7'], 18:21:11 INFO - 'tests': ['tsvgm']}}, 18:21:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:21:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:21:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:21:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:21:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:11 INFO - 'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:21:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:21:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:11 INFO - 'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['damp', 'tps']}, 18:21:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:11 INFO - 'tests': ['damp', 'tps']}, 18:21:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['dromaeo_dom']}, 18:21:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:21:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['basic_compositor_video']}, 18:21:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:21:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['a11yr', 18:21:11 INFO - 'ts_paint', 18:21:11 INFO - 'tpaint', 18:21:11 INFO - 'sessionrestore', 18:21:11 INFO - 'sessionrestore_no_auto_restore', 18:21:11 INFO - 'tabpaint']}, 18:21:11 INFO - 'other-e10s': {'tests': ['a11yr', 18:21:11 INFO - 'ts_paint', 18:21:11 INFO - 'tpaint', 18:21:11 INFO - 'sessionrestore', 18:21:11 INFO - 'sessionrestore_no_auto_restore', 18:21:11 INFO - 'tabpaint']}, 18:21:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:21:11 INFO - 'ts_paint', 18:21:11 INFO - 'tpaint', 18:21:11 INFO - 'sessionrestore', 18:21:11 INFO - 'sessionrestore_no_auto_restore', 18:21:11 INFO - 'tabpaint']}, 18:21:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:21:11 INFO - 'ts_paint', 18:21:11 INFO - 'tpaint', 18:21:11 INFO - 'sessionrestore', 18:21:11 INFO - 'sessionrestore_no_auto_restore', 18:21:11 INFO - 'tabpaint']}, 18:21:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['a11yr', 18:21:11 INFO - 'ts_paint', 18:21:11 INFO - 'tpaint', 18:21:11 INFO - 'sessionrestore', 18:21:11 INFO - 'sessionrestore_no_auto_restore', 18:21:11 INFO - 'tabpaint']}, 18:21:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['a11yr', 18:21:11 INFO - 'ts_paint', 18:21:11 INFO - 'tpaint', 18:21:11 INFO - 'sessionrestore', 18:21:11 INFO - 'sessionrestore_no_auto_restore', 18:21:11 INFO - 'tabpaint']}, 18:21:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['tsvgx', 18:21:11 INFO - 'tsvgr_opacity', 18:21:11 INFO - 'tart', 18:21:11 INFO - 'tscrollx', 18:21:11 INFO - 'cart']}, 18:21:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:21:11 INFO - 'tsvgr_opacity', 18:21:11 INFO - 'tart', 18:21:11 INFO - 'tscrollx', 18:21:11 INFO - 'cart']}, 18:21:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:11 INFO - 'talos_options': ['--disable-e10s'], 18:21:11 INFO - 'tests': ['tp5o']}, 18:21:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:11 INFO - 'tests': ['tp5o']}, 18:21:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:11 INFO - 'talos_options': ['--disable-e10s', 18:21:11 INFO - '--xperf_path', 18:21:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:21:11 INFO - 'tests': ['tp5n']}, 18:21:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:11 INFO - 'talos_options': ['--xperf_path', 18:21:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:21:11 INFO - 'tests': ['tp5n']}}, 18:21:11 INFO - 'talos.zip': {'path': '', 18:21:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:21:11 INFO - Running post-action listener: _resource_record_post_action 18:21:11 INFO - [mozharness: 2017-01-03 02:21:11.145878Z] Finished populate-webroot step (success) 18:21:11 INFO - [mozharness: 2017-01-03 02:21:11.145987Z] Running create-virtualenv step. 18:21:11 INFO - Running pre-action listener: _resource_record_pre_action 18:21:11 INFO - Running main action method: create_virtualenv 18:21:11 INFO - Creating virtualenv /builds/slave/test/build/venv 18:21:11 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 18:21:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:21:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:21:11 INFO - Using real prefix '/tools/python27' 18:21:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:21:11 INFO - Installing distribute.............................................................................................................................................................................................done. 18:21:14 INFO - Installing pip.................done. 18:21:14 INFO - Return code: 0 18:21:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:21:15 INFO - Reading from file tmpfile_stdout 18:21:15 INFO - Output received: 18:21:15 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) 18:21:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:21:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:15 INFO - 'HOME': '/Users/cltbld', 18:21:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:15 INFO - 'LOGNAME': 'cltbld', 18:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:15 INFO - 'MOZ_NO_REMOTE': '1', 18:21:15 INFO - 'NO_EM_RESTART': '1', 18:21:15 INFO - 'PAGER': '/bin/cat', 18:21:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:15 INFO - 'PWD': '/builds/slave/test', 18:21:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:15 INFO - 'SHELL': '/bin/bash', 18:21:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:15 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:15 INFO - 'USER': 'cltbld', 18:21:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:15 INFO - 'XPC_FLAGS': '0x0', 18:21:15 INFO - 'XPC_SERVICE_NAME': '0', 18:21:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:15 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 18:21:15 INFO - Cleaning up... 18:21:15 INFO - Return code: 0 18:21:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:21:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:15 INFO - 'HOME': '/Users/cltbld', 18:21:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:15 INFO - 'LOGNAME': 'cltbld', 18:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:15 INFO - 'MOZ_NO_REMOTE': '1', 18:21:15 INFO - 'NO_EM_RESTART': '1', 18:21:15 INFO - 'PAGER': '/bin/cat', 18:21:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:15 INFO - 'PWD': '/builds/slave/test', 18:21:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:15 INFO - 'SHELL': '/bin/bash', 18:21:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:15 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:15 INFO - 'USER': 'cltbld', 18:21:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:15 INFO - 'XPC_FLAGS': '0x0', 18:21:15 INFO - 'XPC_SERVICE_NAME': '0', 18:21:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:15 INFO - Downloading/unpacking psutil>=3.1.1 18:21:15 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 18:21:15 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 18:21:15 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 18:21:15 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 18:21:15 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 18:21:15 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 18:21:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:21:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:21:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:21:19 INFO - Installing collected packages: psutil 18:21:19 INFO - Running setup.py install for psutil 18:21:19 INFO - building 'psutil._psutil_osx' extension 18:21:19 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 18:21:19 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 18:21:20 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 18:21:20 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 18:21:20 INFO - building 'psutil._psutil_posix' extension 18:21:20 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 18:21:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:21:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:21:20 INFO - ^ 18:21:20 INFO - 1 warning generated. 18:21:20 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 18:21:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:21:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:21:20 INFO - Successfully installed psutil 18:21:20 INFO - Cleaning up... 18:21:20 INFO - Return code: 0 18:21:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:21:20 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')]} 18:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:20 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')]} 18:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:20 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 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:20 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 18:21:20 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 18:21:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:20 INFO - 'HOME': '/Users/cltbld', 18:21:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:20 INFO - 'LOGNAME': 'cltbld', 18:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:20 INFO - 'MOZ_NO_REMOTE': '1', 18:21:20 INFO - 'NO_EM_RESTART': '1', 18:21:20 INFO - 'PAGER': '/bin/cat', 18:21:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:20 INFO - 'PWD': '/builds/slave/test', 18:21:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:20 INFO - 'SHELL': '/bin/bash', 18:21:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:20 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:20 INFO - 'USER': 'cltbld', 18:21:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:20 INFO - 'XPC_FLAGS': '0x0', 18:21:20 INFO - 'XPC_SERVICE_NAME': '0', 18:21:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:21:20 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 18:21:20 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 18:21:20 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 18:21:20 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 18:21:20 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 18:21:20 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 18:21:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:21:23 INFO - Installing collected packages: mozsystemmonitor 18:21:23 INFO - Running setup.py install for mozsystemmonitor 18:21:23 INFO - Successfully installed mozsystemmonitor 18:21:23 INFO - Cleaning up... 18:21:23 INFO - Return code: 0 18:21:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:21:23 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')]} 18:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:23 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')]} 18:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:23 INFO - 'HOME': '/Users/cltbld', 18:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:23 INFO - 'LOGNAME': 'cltbld', 18:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:23 INFO - 'MOZ_NO_REMOTE': '1', 18:21:23 INFO - 'NO_EM_RESTART': '1', 18:21:23 INFO - 'PAGER': '/bin/cat', 18:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:23 INFO - 'PWD': '/builds/slave/test', 18:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:23 INFO - 'SHELL': '/bin/bash', 18:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:23 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:23 INFO - 'USER': 'cltbld', 18:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:23 INFO - 'XPC_FLAGS': '0x0', 18:21:23 INFO - 'XPC_SERVICE_NAME': '0', 18:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:23 INFO - Downloading/unpacking jsonschema==2.5.1 18:21:23 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 18:21:23 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 18:21:23 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 18:21:23 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 18:21:23 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 18:21:23 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 18:21:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:21:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:21:26 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 18:21:26 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 18:21:26 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 18:21:26 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 18:21:26 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 18:21:26 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 18:21:27 INFO - Downloading functools32-3.2.3-2.tar.gz 18:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:21:27 INFO - warning: no files found matching '*.txt' 18:21:27 INFO - no previously-included directories found matching 'build' 18:21:27 INFO - no previously-included directories found matching 'dist' 18:21:27 INFO - no previously-included directories found matching '.git*' 18:21:27 INFO - Installing collected packages: jsonschema, functools32 18:21:27 INFO - Running setup.py install for functools32 18:21:27 INFO - warning: no files found matching '*.txt' 18:21:27 INFO - no previously-included directories found matching 'build' 18:21:27 INFO - no previously-included directories found matching 'dist' 18:21:27 INFO - no previously-included directories found matching '.git*' 18:21:27 INFO - Successfully installed jsonschema functools32 18:21:27 INFO - Cleaning up... 18:21:27 INFO - Return code: 0 18:21:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:21:27 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')]} 18:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:27 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')]} 18:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:27 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 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:27 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 18:21:27 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 18:21:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:27 INFO - 'HOME': '/Users/cltbld', 18:21:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:27 INFO - 'LOGNAME': 'cltbld', 18:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:27 INFO - 'MOZ_NO_REMOTE': '1', 18:21:27 INFO - 'NO_EM_RESTART': '1', 18:21:27 INFO - 'PAGER': '/bin/cat', 18:21:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:27 INFO - 'PWD': '/builds/slave/test', 18:21:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:27 INFO - 'SHELL': '/bin/bash', 18:21:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:27 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:27 INFO - 'USER': 'cltbld', 18:21:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:27 INFO - 'XPC_FLAGS': '0x0', 18:21:27 INFO - 'XPC_SERVICE_NAME': '0', 18:21:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:21:27 INFO - Cleaning up... 18:21:27 INFO - Return code: 0 18:21:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:21:27 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')]} 18:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:27 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')]} 18:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:27 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 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:27 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 18:21:27 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 18:21:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:27 INFO - 'HOME': '/Users/cltbld', 18:21:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:27 INFO - 'LOGNAME': 'cltbld', 18:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:27 INFO - 'MOZ_NO_REMOTE': '1', 18:21:27 INFO - 'NO_EM_RESTART': '1', 18:21:27 INFO - 'PAGER': '/bin/cat', 18:21:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:27 INFO - 'PWD': '/builds/slave/test', 18:21:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:27 INFO - 'SHELL': '/bin/bash', 18:21:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:27 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:27 INFO - 'USER': 'cltbld', 18:21:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:27 INFO - 'XPC_FLAGS': '0x0', 18:21:27 INFO - 'XPC_SERVICE_NAME': '0', 18:21:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:27 INFO - Downloading/unpacking blobuploader==1.2.4 18:21:27 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 18:21:27 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 18:21:27 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 18:21:27 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 18:21:27 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 18:21:27 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 18:21:30 INFO - Downloading blobuploader-1.2.4.tar.gz 18:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:21:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:21:30 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 18:21:30 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 18:21:30 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 18:21:30 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 18:21:30 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 18:21:30 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 18:21:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:21:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:21:31 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 18:21:31 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 18:21:31 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 18:21:31 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 18:21:31 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 18:21:31 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 18:21:31 INFO - Downloading docopt-0.6.1.tar.gz 18:21:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:21:31 INFO - Installing collected packages: blobuploader, requests, docopt 18:21:31 INFO - Running setup.py install for blobuploader 18:21:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:21:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:21:31 INFO - Running setup.py install for requests 18:21:32 INFO - Running setup.py install for docopt 18:21:32 INFO - Successfully installed blobuploader requests docopt 18:21:32 INFO - Cleaning up... 18:21:32 INFO - Return code: 0 18:21:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:32 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')]} 18:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:32 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')]} 18:21:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:32 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 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:32 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 18:21:32 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 18:21:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:32 INFO - 'HOME': '/Users/cltbld', 18:21:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:32 INFO - 'LOGNAME': 'cltbld', 18:21:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:32 INFO - 'MOZ_NO_REMOTE': '1', 18:21:32 INFO - 'NO_EM_RESTART': '1', 18:21:32 INFO - 'PAGER': '/bin/cat', 18:21:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:32 INFO - 'PWD': '/builds/slave/test', 18:21:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:32 INFO - 'SHELL': '/bin/bash', 18:21:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:32 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:32 INFO - 'USER': 'cltbld', 18:21:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:32 INFO - 'XPC_FLAGS': '0x0', 18:21:32 INFO - 'XPC_SERVICE_NAME': '0', 18:21:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:32 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-fMeGay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-gCD8zR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-_LC7t_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-6T0FWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-ZWiLgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-wdcUsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-GlIA4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-ZTbZe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-5_eYQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:33 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-fKYKJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:34 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-38C0ET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:34 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-cqhCoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:34 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-zZTbzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:34 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-2ZAH_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:34 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-W97MGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:34 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-MBn5oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:34 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-Yipwdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:21:34 INFO - Running setup.py install for manifestparser 18:21:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:21:34 INFO - Running setup.py install for mozcrash 18:21:35 INFO - Running setup.py install for mozdebug 18:21:35 INFO - Running setup.py install for mozdevice 18:21:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:21:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:21:35 INFO - Running setup.py install for mozfile 18:21:35 INFO - Running setup.py install for mozhttpd 18:21:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:21:35 INFO - Running setup.py install for mozinfo 18:21:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:21:35 INFO - Running setup.py install for mozInstall 18:21:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:21:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:21:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:21:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:21:35 INFO - Running setup.py install for mozleak 18:21:36 INFO - Running setup.py install for mozlog 18:21:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:21:36 INFO - Running setup.py install for moznetwork 18:21:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:21:36 INFO - Running setup.py install for mozprocess 18:21:36 INFO - Running setup.py install for mozprofile 18:21:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:21:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:21:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:21:36 INFO - Running setup.py install for mozrunner 18:21:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:21:36 INFO - Running setup.py install for mozscreenshot 18:21:37 INFO - Running setup.py install for moztest 18:21:37 INFO - Running setup.py install for mozversion 18:21:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:21:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:21:37 INFO - Cleaning up... 18:21:37 INFO - Return code: 0 18:21:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:37 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')]} 18:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:37 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')]} 18:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:37 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 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:37 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 18:21:37 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 18:21:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:37 INFO - 'HOME': '/Users/cltbld', 18:21:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:37 INFO - 'LOGNAME': 'cltbld', 18:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:37 INFO - 'MOZ_NO_REMOTE': '1', 18:21:37 INFO - 'NO_EM_RESTART': '1', 18:21:37 INFO - 'PAGER': '/bin/cat', 18:21:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:37 INFO - 'PWD': '/builds/slave/test', 18:21:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:37 INFO - 'SHELL': '/bin/bash', 18:21:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:37 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:37 INFO - 'USER': 'cltbld', 18:21:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:37 INFO - 'XPC_FLAGS': '0x0', 18:21:37 INFO - 'XPC_SERVICE_NAME': '0', 18:21:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-xI5ml6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:37 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)) 18:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-6kz2Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:37 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)) 18:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-I92Hjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:37 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)) 18:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-WTywMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-8xbymB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-Yx_9Ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-YfeXoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-RuMCzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-EiWf2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-M_MLXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-rTFOMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-uqXu6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:38 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)) 18:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-AlwOig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:39 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)) 18:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:39 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-eHX1ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:39 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)) 18:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:39 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-n9q4Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:39 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)) 18:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:39 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-6gUdPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:39 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)) 18:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:39 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-tSOfYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:39 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)) 18:21:39 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)) 18:21:39 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)) 18:21:39 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)) 18:21:39 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)) 18:21:39 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)) 18:21:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:42 INFO - Downloading blessings-1.6.tar.gz 18:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:21:42 INFO - Installing collected packages: blessings 18:21:42 INFO - Running setup.py install for blessings 18:21:42 INFO - Successfully installed blessings 18:21:42 INFO - Cleaning up... 18:21:42 INFO - Return code: 0 18:21:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:21:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:42 INFO - Reading from file tmpfile_stdout 18:21:42 INFO - Current package versions: 18:21:42 INFO - blessings == 1.6 18:21:42 INFO - blobuploader == 1.2.4 18:21:42 INFO - docopt == 0.6.1 18:21:42 INFO - functools32 == 3.2.3-2 18:21:42 INFO - jsonschema == 2.5.1 18:21:42 INFO - manifestparser == 1.1 18:21:42 INFO - mozInstall == 1.12 18:21:42 INFO - mozcrash == 1.0 18:21:42 INFO - mozdebug == 0.1 18:21:42 INFO - mozdevice == 0.48 18:21:42 INFO - mozfile == 1.2 18:21:42 INFO - mozhttpd == 0.7 18:21:42 INFO - mozinfo == 0.9 18:21:42 INFO - mozleak == 0.1 18:21:42 INFO - mozlog == 3.4 18:21:42 INFO - moznetwork == 0.27 18:21:42 INFO - mozprocess == 0.23 18:21:42 INFO - mozprofile == 0.28 18:21:42 INFO - mozrunner == 6.13 18:21:42 INFO - mozscreenshot == 0.1 18:21:42 INFO - mozsystemmonitor == 0.3 18:21:42 INFO - moztest == 0.7 18:21:42 INFO - mozversion == 1.4 18:21:42 INFO - psutil == 3.1.1 18:21:42 INFO - requests == 1.2.3 18:21:42 INFO - wsgiref == 0.1.2 18:21:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:42 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')]} 18:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:42 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')]} 18:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:42 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 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:42 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 18:21:42 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 18:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:42 INFO - 'HOME': '/Users/cltbld', 18:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:42 INFO - 'LOGNAME': 'cltbld', 18:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:42 INFO - 'MOZ_NO_REMOTE': '1', 18:21:42 INFO - 'NO_EM_RESTART': '1', 18:21:42 INFO - 'PAGER': '/bin/cat', 18:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:42 INFO - 'PWD': '/builds/slave/test', 18:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:42 INFO - 'SHELL': '/bin/bash', 18:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:42 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:42 INFO - 'USER': 'cltbld', 18:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:42 INFO - 'XPC_FLAGS': '0x0', 18:21:42 INFO - 'XPC_SERVICE_NAME': '0', 18:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 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)) 18:21:42 INFO - Cleaning up... 18:21:42 INFO - Return code: 0 18:21:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:21:42 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')]} 18:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:42 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')]} 18:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:42 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 0x10386c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b20578ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, '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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 18:21:42 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 18:21:42 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 18:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:42 INFO - 'HOME': '/Users/cltbld', 18:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:42 INFO - 'LOGNAME': 'cltbld', 18:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:42 INFO - 'MOZ_NO_REMOTE': '1', 18:21:42 INFO - 'NO_EM_RESTART': '1', 18:21:42 INFO - 'PAGER': '/bin/cat', 18:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:42 INFO - 'PWD': '/builds/slave/test', 18:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:42 INFO - 'SHELL': '/bin/bash', 18:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:21:42 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:42 INFO - 'USER': 'cltbld', 18:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:42 INFO - 'XPC_FLAGS': '0x0', 18:21:42 INFO - 'XPC_SERVICE_NAME': '0', 18:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:21:43 INFO - Cleaning up... 18:21:43 INFO - Return code: 0 18:21:43 INFO - Running post-action listener: _resource_record_post_action 18:21:43 INFO - Running post-action listener: _start_resource_monitoring 18:21:43 INFO - Starting resource monitoring. 18:21:43 INFO - [mozharness: 2017-01-03 02:21:43.274177Z] Finished create-virtualenv step (success) 18:21:43 INFO - [mozharness: 2017-01-03 02:21:43.275208Z] Running install step. 18:21:43 INFO - Running pre-action listener: _resource_record_pre_action 18:21:43 INFO - Running main action method: install 18:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:43 INFO - Reading from file tmpfile_stdout 18:21:43 INFO - Detecting whether we're running mozinstall >=1.0... 18:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:21:43 INFO - Reading from file tmpfile_stdout 18:21:43 INFO - Output received: 18:21:43 INFO - Usage: mozinstall [options] installer 18:21:43 INFO - Options: 18:21:43 INFO - -h, --help show this help message and exit 18:21:43 INFO - -d DEST, --destination=DEST 18:21:43 INFO - Directory to install application into. [default: 18:21:43 INFO - "/builds/slave/test"] 18:21:43 INFO - --app=APP Application being installed. [default: firefox] 18:21:43 INFO - mkdir: /builds/slave/test/build/application 18:21:43 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'] 18:21:43 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 18:22:14 INFO - Reading from file tmpfile_stdout 18:22:14 INFO - Output received: 18:22:14 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:22:14 INFO - Running post-action listener: _resource_record_post_action 18:22:14 INFO - [mozharness: 2017-01-03 02:22:14.781580Z] Finished install step (success) 18:22:14 INFO - [mozharness: 2017-01-03 02:22:14.781721Z] Running run-tests step. 18:22:14 INFO - Running pre-action listener: _resource_record_pre_action 18:22:14 INFO - Running main action method: run_tests 18:22:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:14 INFO - 2017-01-02 18:22:14.794 screenresolution[2167:25010] starting screenresolution argv=screenresolution get 18:22:14 INFO - 2017-01-02 18:22:14.797 screenresolution[2167:25010] Display 0: 1600x1200x32@60 18:22:14 INFO - 2017-01-02 18:22:14.802 screenresolution[2168:25013] starting screenresolution argv=screenresolution list 18:22:14 INFO - Available Modes on Display 0 18:22:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:15 INFO - Intel Iris: 18:22:15 INFO - Chipset Model: Intel Iris 18:22:15 INFO - Type: GPU 18:22:15 INFO - Bus: Built-In 18:22:15 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:15 INFO - Vendor: Intel (0x8086) 18:22:15 INFO - Device ID: 0x0a2e 18:22:15 INFO - Revision ID: 0x0009 18:22:15 INFO - Displays: 18:22:15 INFO - Display: 18:22:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:15 INFO - Display Serial Number: 1600x1200 60 18:22:15 INFO - Main Display: Yes 18:22:15 INFO - Mirror: Off 18:22:15 INFO - Online: Yes 18:22:15 INFO - Rotation: Supported 18:22:15 INFO - Return code: 0 18:22:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:22:15 INFO - Python 2.7.3 18:22:15 INFO - Return code: 0 18:22:15 INFO - grabbing minidump binary from tooltool 18:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:15 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 0x103680d30>, '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 0x1034e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103678b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:22:15 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 18:22:15 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 18:22:15 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 18:22:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:22:15 INFO - Return code: 0 18:22:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:22:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:22:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:22:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:22:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:22:15 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0240', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:22:15 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0240 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:22:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMs7DlEo1u/Render', 18:22:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:15 INFO - 'HOME': '/Users/cltbld', 18:22:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:15 INFO - 'LOGNAME': 'cltbld', 18:22:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:22:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:15 INFO - 'MOZ_NO_REMOTE': '1', 18:22:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:22:15 INFO - 'NO_EM_RESTART': '1', 18:22:15 INFO - 'PAGER': '/bin/cat', 18:22:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:15 INFO - 'PWD': '/builds/slave/test', 18:22:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:22:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:15 INFO - 'SHELL': '/bin/bash', 18:22:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3xiXXKgvg/Listeners', 18:22:15 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 18:22:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:15 INFO - 'USER': 'cltbld', 18:22:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:15 INFO - 'XPC_FLAGS': '0x0', 18:22:15 INFO - 'XPC_SERVICE_NAME': '0', 18:22:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0240', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:22:15 INFO - mozversion application_buildid: 20170102160620 18:22:15 INFO - mozversion application_changeset: 741296a2d92b93348fe573b2b5884d6421ba4282 18:22:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:22:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:22:15 INFO - mozversion application_name: Firefox 18:22:15 INFO - mozversion application_remotingname: firefox-dev 18:22:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:22:15 INFO - mozversion application_vendor: Mozilla 18:22:15 INFO - mozversion application_version: 52.0a2 18:22:15 INFO - mozversion platform_buildid: 20170102160620 18:22:15 INFO - mozversion platform_changeset: 741296a2d92b93348fe573b2b5884d6421ba4282 18:22:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:22:15 INFO - mozversion platform_version: 52.0a2 18:22:15 INFO - using testdate: 1483410135 18:22:15 INFO - actual date: 1483410135 18:22:15 INFO - starting webserver on 'localhost:55318' 18:22:15 INFO - SUITE-START | Running 2 tests 18:22:15 INFO - TEST-START | dromaeo_css 18:22:15 INFO - operating with platform_type : mac_ 18:22:15 INFO - Initialising browser for dromaeo_css test... 18:22:15 INFO - TEST-INFO | started process 2178 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpvFyGKj/profile http://localhost:55318/getInfo.html) 18:22:17 INFO - PROCESS | 2178 | __metrics Screen width/height:1600/1200 18:22:17 INFO - PROCESS | 2178 | colorDepth:24 18:22:17 INFO - PROCESS | 2178 | Browser inner width/height: 1024/689 18:22:17 INFO - PROCESS | 2178 | __metrics 18:22:17 INFO - PROCESS | 2178 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:22:17 INFO - PROCESS | 2178 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:22:18 INFO - TEST-INFO | 2178: exit 0 18:22:18 INFO - Browser initialized. 18:22:18 INFO - Running cycle 1/1 for dromaeo_css test... 18:22:18 INFO - TEST-INFO | started process 2180 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpvFyGKj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:22:31 INFO - PROCESS | 2180 | RSS: Main: 222466048 18:22:31 INFO - PROCESS | 2180 | 18:23:22 INFO - PROCESS | 2180 | 1483410202078 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] 18:25:35 INFO - PROCESS | 2180 | Cycle 1(1): loaded http://localhost:55318/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55318/tests/dromaeo/cssquery-ext.html) 18:25:36 INFO - PROCESS | 2180 | RSS: Main: 351240192 18:25:36 INFO - PROCESS | 2180 | 18:28:39 INFO - PROCESS | 2180 | Cycle 1(1): loaded http://localhost:55318/tests/dromaeo/cssquery-ext.html (next: http://localhost:55318/tests/dromaeo/cssquery-jquery.html) 18:28:40 INFO - PROCESS | 2180 | RSS: Main: 348155904 18:28:40 INFO - PROCESS | 2180 | 18:31:42 INFO - PROCESS | 2180 | Cycle 1(1): loaded http://localhost:55318/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55318/tests/dromaeo/cssquery-mootools.html) 18:31:43 INFO - PROCESS | 2180 | RSS: Main: 326303744 18:31:43 INFO - PROCESS | 2180 | 18:34:45 INFO - PROCESS | 2180 | Cycle 1(1): loaded http://localhost:55318/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55318/tests/dromaeo/cssquery-prototype.html) 18:34:46 INFO - PROCESS | 2180 | RSS: Main: 322031616 18:34:46 INFO - PROCESS | 2180 | 18:37:49 INFO - PROCESS | 2180 | Cycle 1(1): loaded http://localhost:55318/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55318/tests/dromaeo/cssquery-yui.html) 18:37:50 INFO - PROCESS | 2180 | RSS: Main: 307183616 18:37:50 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | Cycle 1(1): loaded http://localhost:55318/tests/dromaeo/cssquery-yui.html (next: http://localhost:55318/tests/dromaeo/cssquery-dojo.html) 18:40:53 INFO - PROCESS | 2180 | RSS: Main: 354627584 18:40:53 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | __start_tp_report 18:40:53 INFO - PROCESS | 2180 | _x_x_mozilla_page_load 18:40:53 INFO - PROCESS | 2180 | _x_x_mozilla_page_load_details 18:40:53 INFO - PROCESS | 2180 | |i|pagename|runs| 18:40:53 INFO - PROCESS | 2180 | |0;dojo.html;3297.0108967309816;3297.3075654112636;3302.8183449910243;3330.3838789823885;3335.399628066947;4872.902541949168;5683.976884160849;5702.201691763151;5786.392428794974;5806.7096645167785;5700.743466544005;5748.827535173941;5764.5676574256995;5858.648481091138;5875.001249787536;5202.453742357055;5226.817061402832;5231.921521177185;5283.841484755463;5293.655912365684;4348.847790327339;5191.714455704935;5203.219517072451;5330.440303768107;5332.92000619991;5191.558717508998;5216.582673386139;5241.8427447176455;5262.578993680516;5279.155335146377;5256.185291279858;5273.367195936512;5325.174597937326;5331.893362132774;5344.144936810092;5194.3247377840635;5252.343457067866;5273.499017593323;5289.074411977888;5331.706756128411;5670.659760414388;5676.460736230051;5708.086706127041;5752.597318187729;5776.884462310731;6106.2061931948565;6135.89252969787;6145.877082458326;6170.814875553696;6181.81454556364;6191.4118158774845;6206.565540412173;6218.782342617987;6227.626342419424;6238.251409830849;5793.333766616893;5824.009918313896;5838.357780644125;5894.705264736746;5910.054391297414;6260.154879091356;6268.373162683695;6278.466330361879;6281.780137695158;6282.403171698681;6150.138980542728;6155.753769849156;6158.692065396713;6160.722767475474;6178.104174894615;6124.5100391968535;6129.049997250433;6137.60105593135;6144.4945988232275;6187.721552530147;6132.122542660378;6151.876962460726;6174.135621013062;6185.041318595625;6196.411340922605;6103.3591472894705;6171.382861713792;6177.536684748662;6178.7219575119225;6185.288691800501;4815.422149342031;4924.60603151747;4956.083124621957;4967.95672908686;4997.775100120503;4373.2565463871215;4421.137878113737;4426.756528390905;4472.686911916135;4498.415206023196;5642.181883626851;5766.625169363978;5784.508316793035;5793.652380857077;5799.623024503394;6172.753089876354;6202.689865506797;6209.875802483925;6217.875642487215;6226.59527130735;5621.7189140542805;5698.373178950312;5722.484976352148;5774.913376299359;5779.971100144557;5344.652597581145;5399.595030372738;5406.351237851483;5413.756380962787;5419.701916394557;6119.380693981147;6150.015997440388;6157.53818463626;6171.753129874754;6211.875762484635;6175.536834737323;6186.443220110032;6187;6194;6200.007998720183;6052.939470605237;6107.188815080102;6200.658963757037;6208.937910621016;6211.534134939988;5133.460986596354;5143.331366922426;6129;6132.202813634199;6161.168242287234;6145.4161854623735;6189.319174890848;6193.84515387119;6208.441240288395;6210.937890621036;5465.289512363367;5667.923094612175;6062.393760623902;6153.230846144232;6195.101710252078;5480.506754392124;5947.881042379215;6007;6061.484773794277;6179.443850053517;5325.573954083741;6020.849478762891;6023.066424704178;6023.096535519707;6069.969650151721;5180.922286165786;6146.5390095742105;6154.538409619386;6155.1075094112;6157.168782214344;4807.399075115611;5873.4126587342635;5882.117682347682;5983.640981541122;5992.951233534027 18:40:53 INFO - PROCESS | 2180 | |1;ext.html;476.1880992907094;490.5977098779001;500.0723657615124;522.4618642797915;541.320642593545;25246.873765631193;29874.954376596797;30498.390032199342;32515.67484325156;32517.02448926536;41372;41903.37144942828;41940.16119677605;41947.580524194746;42067.36898946518;9282.675106371262;9482.81034379312;9487.952560237189;9490.240780737558;9500.382475139128;9420.717378478657;9521.190698790577;9521.857172142423;9527.999560046199;9547.14075733184;9506.619735210583;9530.523473826315;9533.90466095337;9541.95229023854;9562.18721408681;9474.715758527222;9505.382150160218;9506.572204250826;9558.761030974212;9562.856557151614;9370.906290937071;9454.05459454056;9455.912570054463;9469.337146399756;9474.478903660296;39430.802845985876;39687.00782480461;39687.20625587501;39697;39824.20351592952;3268.1176082457605;3300.455424854896;3349.8492567834505;3459.41189997701;3737.663610275088;28099.735511902014;28320.716792832012;29791;30802.845985769927;30870.536941945666;2733.439644872796;3216.437123503401;3221.806691598488;3258.853351599184;3317.3531161423534;502415;504811;505872;505925;524188.0649548065;32800.01599952005;33101.17247068843;33613.15967100793;57184.142237866465;57612;35253.29493410118;35265.82367088148;35306.64693353033;35392.81464668141;35475;14200.076994995292;14336.56990290293;14354.85645143556;14364.784528232085;14367.712645747026;12023.519059237708;12112.81830772539;12118.39408029595;12120.212186207867;12176.330301833485;6819.897701534481;7659.425543084291;7717.65270562826;7718.5368877866385;7792.259735325135;2760.7791376689815;2854.4433835402315;3222.726068284175;3224.758143139274;3239.5626590410466;851.1275942159336;920.327891712064;948.2698322291797;1003.7039073473343;1048.449563978902;29481;30035.94874179393;30069.849650751607;30188.84905575458;30218.54672179919;1026.4354604967386;1027.6249169053392;1042.4527123260198;1095.5343978808974;1109.589451902801;15018.849811501963;15278.847211527966;15314.770278445832;15454.072755634697;15906.68186636284;193711;957291;973836.2616373887;975496;976762;57089;57920.13119803206;57992;58075.41924580785;58304.416955829896;1223.3944197622234;1262.2363470100825;1350.9324533773272;1388.625071230762;1409.1615488784157;22752.20367287137;29993.85003075058;30636.080917572508;31314;32005;55064.72467637636;55848.72075639596;55853.16220256781;55864;55884.88230235454;638125;644266;645050;645302;647377;21711.34865954024;23337;23981.920813563782;26581.202563923114;26606.334841629116;37467.81266093652;43469.09173717067;44743.32885006793;45362;45485.63543093712;36083;36833.81583092068;37446.438303424926;37858.43212351872;39015;37205;37599;37968;38220;39026.609733902296;36198.4570231452;36672.63327366692;36872.81563592165;36943.44584831283;37343.253134937695;32000;32995;33712.49431258582;33881.3223735519;34076.82961585176;24653.753462465145;24743.762811858975;25218.873905630353;25254.74745252524;25295.747042530074 18:40:53 INFO - PROCESS | 2180 | |2;jquery.html;351.96128425873167;368.6995098994319;372.94965179700733;384.2353716104951;400.33744153426073;14737.263136843156;14964.176970266632;15436.459723909651;19967.10148043338;20391.38825835227;16173.83826161738;17571.506421954116;19314.32399866005;20452.386428407135;20534.58930821383;5593.888122237563;5858.648481091148;5860.53115750741;5863.677497737623;5866.149408335788;5121.641485096045;5693.971530142344;5741.397153298906;5786.450287222707;5823.010088285002;5652.657127620855;5729.39841316662;5740.626859254156;5751.56863235257;5756;5500.642458240201;5735.397783232742;5742.712864356787;5748.626339287975;5756.625819321731;4896.755162241873;4993.550580447741;5052.545270925634;5066.746662666852;5071.518205770446;24582;25032.249032529053;25119.36246694089;25271.74728252712;25635;2239.664050392454;2268.88655567221;2285.702858628384;2293.8853057347233;3045.512717965137;15037.473688420852;15278.15970121643;15769.68460630781;16327.673446531122;16336.18319084053;1584.5008822220964;1645.9670806583922;1660.4520508232254;1679.5801049737565;1702.5147832867697;112977.43511282474;113727.43136284349;114022;117431;119621.40189298998;10305.84541231882;10626.628068017582;10693.518791654395;10934.945325273482;11195.160362972809;13803.723925521434;13813.792793108112;13845.16928984244;13850.09974351664;13868;7625.351845093118;8035.718749843727;8169.5098294102545;8429.367797415218;8558.101399353103;8457.915420845837;8464.492130472192;8478.74563763088;8610.311175105977;8690.565471726388;6423.197100362455;6517.934820651828;6522.706478208397;6554.377334153248;6583.802485925334;1788.4813404112922;1827.378691244983;1830.3319288459882;1832.743415921772;1835.5686413692772;886.8536691445968;905.6196397513061;908.6456282050005;925.1026504290827;957.5260246178048;21430.785692143192;22319.44201394978;22550.32349029532;22610;22734.317970460583;954.341504361988;956.3926906414476;989.4063561862969;990.4601991914424;1025.3386565665105;11378.601748938912;11690.298582085103;11762;11811.409429528489;11972.58095966637;615409;620387;626111.8694406499;627449;632727;84976.57511712401;91525.62711559332;93655;114724;121419.39290303492;948.8434408322549;980.6469670918464;1019.938803671782;1020.7601213714771;1021.5862575656871;18922.810771892106;19423.12595933158;20081.29715459952;20086.79913200849;20341.288054918008;56711;57754.422455774904;58062.7096864513;59001.70499147477;59201.111983319424;79068.60465697634;81584.59207704161;82717.58641206755;82977.58511207406;83031.5848420754;7477.728786106266;7554.848903021799;7962.880556791537;8296.502209867427;8452.704155354779;12537.435815388511;12870.356482175666;13470.595882123553;13537.458501659823;13551.322433878464;11198.536322009146;11229.606963756229;11617.941910290394;11619.709507262387;11779.469923853618;9110;11226.270292430965;11245.831312530156;11297.661070167907;11640.592579259684;11052.115830733683;11449.771004579696;11665.708357291136;11783.76432471363;11903.226290291102;8197.221263979913;8335.624896879533;8362.790930226793;8396.706115285935;8439.915600843913;9644.276679248944;9961.252906031932;10825.242233043611;11022.393768342818;11326.660200194008 18:40:53 INFO - PROCESS | 2180 | |3;mootools.html;1486.4797320937673;1503.2558883352742;1675.664867026595;1818.5271829324372;1851.1207176591113;8167.754967350981;8559.144085591446;8568.971723393186;8582.442141260814;8610.311175105991;7108.360247577717;7255.419566434686;7530.021097257349;7541.472096953215;8150.877736833952;2321.129576408847;2349.5418393413293;2351.811855051592;2352.694149760529;2359.327591636384;2349.7297810751725;2378.774016468433;2395.8562486250794;2396.065534441569;2397.5924092904247;2303.1248125712204;2304.8617082974993;2335.1593426366494;2338.485533182697;2351.2946116165167;2310.3761984264324;2329.685492458522;2343.484433424644;2354.2584086012853;2359.315798418457;1863.5900101977543;2158.665406861939;2179.052112331141;2180.3894909425226;2186.7703891091364;7202.531835430733;8279.089300176976;8439.83120337596;8455.154484551556;8463.111373305837;5071.340725705634;5226.738663066832;5229.712365819878;5240.187770895517;5255.947440525584;3099.3181500070173;3122.2974830663147;3254.1376535218187;3340.6593406593406;3577.749557530974;1070.5289672544134;1073.9731506712317;1090.7273181704575;1110.1562812262657;1116.2465335898237;10919.235653504258;11031.062359699436;11041.116710663127;11050.502727377287;11092.445377731081;3965.722399432012;4492.438445194351;4533.342665313511;4727.030958653468;4732.195526760459;9404.576794044147;9404.670836520689;9408.341416100944;9416.293777966679;9433.66982155635;5179.886324440188;5193.169092945111;5199.090159222121;5206.167013277933;5211.765470553834;3951.68386529077;4264.552222016706;4368.868933931987;4765.8570242892765;4778.354922085544;3711;3767.7362584619364;3811.3711237646035;3842.423636454554;3855.9614403855603;1245.1657389549023;1247.607003793802;1255.2217625072512;1263.8988880889506;1268.898488120948;732.597071610612;855.3841234311286;901.5041727050227;907.7004588485784;909.1454033208895;5568.248286481355;5618.803341883015;5667.518260947783;5806.390329015477;5977.043673012297;901.8737376767258;943.8159558886089;966.7534778631403;974.0454354732402;989.8960609136082;3227.128675257667;3231.272963583175;3740.8503659853845;3826.641542252516;3940.2316548273384;9688.079632434907;9898;10159.898401016044;10161.695149145537;10177.643782467578;10229.74425639365;10254.743631409274;10260.538275777608;10262.179025677928;10266.384016959006;7735.845283094307;7953.721619743281;7954.960225198837;7963.721269755531;7978.162292959272;5582.86042848932;5686.374498805211;5749.281339832521;5952.821415357546;5963.552733545088;9491.952540237255;9536;9540.189083927666;9542.618295268112;9546.904530954602;8475.915240847513;9226.538673066454;9443.150116489549;9757.365771224848;9773.267004974514;7155.570665760071;7197.280271972761;7204.0994875640545;7246.89129663066;7308.488405811542;7763.340116090196;7850.568218748023;7865.292123708894;7867.803304917194;7869.527828330319;6949.583025018515;7067.081279433641;7158.463115266271;7207.5675459472595;7313.305236002571;6867.706804819318;6993.125859267591;7028.8945665814035;7120.1099862517185;7152.24901385347;5569.275994120901;6565.146530950946;6666;6671.232808227116;6687.866242675216;4859.586935110555;4872.756362181947;4900;4902.093112774148;4934.605231581393;4815.374001379798;4826.95173048265;4838.104950584153;4983.401991761012;5005.77474013663 18:40:53 INFO - PROCESS | 2180 | |4;prototype.html;258.27940047268123;304.4702218140435;306.5447810002147;310.57137169022485;314.0844438461884;4687.55468230518;4831.5168483151665;5237.292965449663;5308.549442862137;5315.548178404831;4910.268196170909;4964.652474326789;5029.924551131736;5095.261187127864;5216.60875434343;3981.223661386031;4001.2397644447533;4005.339119045354;4027.060246020407;4062.517181228845;4024.3762216856435;4028.97985510072;4034.69739769517;4043.6360727534516;4045.5752146024683;3015.677927995442;3385.4752513360345;3936.2914675358425;3951.74313669612;4005.439238506611;3296.8021918684954;3311.7415382154745;3363.848626811787;3956.6439020488006;3964.7312859884846;3436.398630239723;3441.20852203992;3441.82790860456;3449.189440481485;3449.896503104911;4164.875053748362;4581.587657110843;4921.827736029221;5042.268871013683;5113.386393632788;3686.225892562565;4339.587739164775;4504.527024662429;4585.610223131004;4587.839425620087;5529.751161197715;5534.335879694422;5548.750306236229;5550.197150439043;5556.583256255756;3303.8678452861855;3503.6846683798326;3756.8729381185535;3756.9104959561696;3849.6920246380223;5221.791128354861;5259.474052594748;5552.278203833475;5590.496855283044;5869.735861886183;3439.638837922032;3568.2685049564775;3699.611540788232;3745.382011968049;3864.304425203434;3665.6884164845756;3888.2223555289056;3915.393114067324;3916.5300163980505;3929.5677475477673;3455.585329760445;3852.6725228355344;4147.382520816884;4538.52345503724;4567.2464043432465;4230.450041494586;4656.137544981966;5541;5556.99974004683;5634.690092044977;1076.682378698286;1169.5497233564968;1209.7399059202314;1264.690150913032;1274.2736640115045;676.8375589858399;806.8426656801985;836.7698882807276;866.882970798934;872.1148034744613;4241.575842415734;4459.264221403497;4478.9611686852295;4779.593734532604;4924.4583095859425;4676.854170728194;4963.627727920348;5458.754356053987;5488.9725551371985;5513.173024046425;4089.6932730045364;4113.210753322314;4141.8964525887095;4538.205813982539;4917.967266841228;2805.5651374037056;2812.7749780017957;2822.717728227161;2825.7739380849484;2829.688734239232;4676.438827340741;5447.673139611636;5495.368032676213;5495.708508500499;5509.614326997072;4910.821402863358;5454.4545545445935;5592.748326325325;5951.23267680522;5984.222051133412;5131.692098474029;5911.76352945886;5920;5938.524918006549;5972.729498408658;4728.527147285244;5352.464753524617;5466.316710411199;5784.958707432703;5836.601143941922;5762.913556296743;5767.884642307046;5772.402418911591;5773.8556536086935;5794.565407594363;5181.53366197044;5897.967855625163;5917.408259174048;5951.583389162717;5959.970200148971;3224.7258982985772;3464.7747896386654;3789.1853251551047;3815.8552434269054;3908.6710518423874;5766.309561880484;5779.2198053263955;5801.708325211603;5813.854653633693;5814.854628634318;4755.643326750438;5638.351589567088;5643.360032778753;5647.905268680174;5682.77268909239;5350.491703288181;5576.414476479911;5626.580570354787;5637.125066216177;5698.54411647059;5049.798008079782;5234.712090835041;5515.669059856422;5524.259858144832;5543.445655434424;4672.626189904731;5079.6698214615935;5162.612804039787;5610.847972500239;5614.354349249888;4718.522775542868;5360.249565060973;5460.235567020541;5486.780528778804;5494.945050549603 18:40:53 INFO - PROCESS | 2180 | |5;yui.html;606.866489372338;718.1382341190573;772.7643068863998;781.6209138567796;794.3724457678435;13973;14048.648783780398;14765.18791466469;14767.556973290792;14782.039167454108;12905.483780648763;13157.60527184186;13209.933950330258;13324.800127998087;13340.733185336312;824.9958750206256;843.9113893041234;845.1295165979052;846.272205902923;847.2120927537388;656.940875321221;679.1341040173793;685.1093578348152;852.9914700853005;855.315747402079;869.6651789061225;893.7363477774072;897.1880448194395;902.278177458031;906.9773255668595;888.3559419420933;890.6214858685033;890.8173824365989;891.4072142025545;893.3835653399134;508.5600955173748;522.6602708239675;662.8839953008204;663.7112855907695;663.9070530125787;13175.209487430784;13654.795178072336;13725.931370343083;13978.650533736636;13995.020348575605;13453.125546839521;13716.794248086188;13833.170009799444;13976.301184940712;13994.790078148835;10553.577856885715;10971;11139.665810025712;11457.484413201342;11612.36132012739;3731;3732.197577520846;3733.3653278942643;3828.7319887607878;3854.3447613905705;83097;85774.57112714459;85782.5710871448;86344;86890;10258.846117308247;10300.433476158732;10498.370097794157;10511.1591072714;10583.523741431654;20446.07992640335;20601.278955236496;20707.689384659243;20728;20737.377878663665;7479.177290498041;7534.924650753423;7626.084869815547;7657.846843063107;7735.613219339123;4222.725522841006;4298.290782020994;4314.137172565437;4345.891352716208;4472.08322293929;3345.916352091217;3434.3303055904357;3459.3600183965546;3461.9134521636656;3466.9133271667906;644.3588629313863;647.5273050673035;665.8601693644389;703.3522836722509;706.0574133531793;578.5978744772427;630.9432151106422;633.7369991453587;644.5069521815815;671.5399951033557;8234.382421318305;9087.31845111606;9531.428114313163;9606.183474404754;9822.312438129262;617.9721912513947;623.9175031320655;627.3726273726273;628.8773689130576;679.5820570349299;3986.5016872890887;4214.578542145822;4325.199838029912;4338.544452832471;4389.385486031958;66680;99627;102075.48962255141;105200.47399763105;105242.94757052485;89452;91878.54060729654;94533;94840.30958142395;95367.52316238375;48647.27029094496;53182.73408632932;65680.67159664171;68964;80048.59975700201;9043.593038313424;9459.944916776203;9581.680760308258;9691.321607487409;9774;47676.761616191696;48232.517674822804;48776;53351.46648533465;53757;55766;72344.9148262787;74669;77244.45511089859;80452.95470452908;11664.825027624762;11734.823977640172;11970.040062706637;12359.50561977511;13047.28239946812;12070.27578345302;12265.26408415498;12634.305113218861;13593.184408935496;14839.40642374293;12509.687257818627;12679.746405072032;12823.886287924164;13025.218486890439;13178.934105329412;10370.637027703995;12393.380330983235;12819.42312595954;13238.205707657571;13302.334883255606;11785.528578856749;11993.64019079429;12356.938215308866;12450.564230251897;12815.807762883751;8549.572521374032;10445.529951152064;10546.103581195684;10687.091597214323;10964.725881853017;11006.889931100586;11028.84274537806;11226.158038147008;11231.4384280784;11312.83030754523 18:40:53 INFO - PROCESS | 2180 | __end_tp_report 18:40:53 INFO - PROCESS | 2180 | __start_cc_report 18:40:53 INFO - PROCESS | 2180 | _x_x_mozilla_cycle_collect,1547 18:40:53 INFO - PROCESS | 2180 | __end_cc_report 18:40:53 INFO - PROCESS | 2180 | __startTimestamp1483411253460__endTimestamp 18:40:53 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | ------- Summary: start ------- 18:40:53 INFO - PROCESS | 2180 | Number of tests: 6 18:40:53 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | [#0] dojo.html Cycles:165 Average:5695.42 Median:5882.12 stddev:636.43 (10.8%) stddev-sans-first:609.93 18:40:53 INFO - PROCESS | 2180 | Values: 3297.0 3297.3 3302.8 3330.4 3335.4 4872.9 5684.0 5702.2 5786.4 5806.7 5700.7 5748.8 5764.6 5858.6 5875.0 5202.5 5226.8 5231.9 5283.8 5293.7 4348.8 5191.7 5203.2 5330.4 5332.9 5191.6 5216.6 5241.8 5262.6 5279.2 5256.2 5273.4 5325.2 5331.9 5344.1 5194.3 5252.3 5273.5 5289.1 5331.7 5670.7 5676.5 5708.1 5752.6 5776.9 6106.2 6135.9 6145.9 6170.8 6181.8 6191.4 6206.6 6218.8 6227.6 6238.3 5793.3 5824.0 5838.4 5894.7 5910.1 6260.2 6268.4 6278.5 6281.8 6282.4 6150.1 6155.8 6158.7 6160.7 6178.1 6124.5 6129.0 6137.6 6144.5 6187.7 6132.1 6151.9 6174.1 6185.0 6196.4 6103.4 6171.4 6177.5 6178.7 6185.3 4815.4 4924.6 4956.1 4968.0 4997.8 4373.3 4421.1 4426.8 4472.7 4498.4 5642.2 5766.6 5784.5 5793.7 5799.6 6172.8 6202.7 6209.9 6217.9 6226.6 5621.7 5698.4 5722.5 5774.9 5780.0 5344.7 5399.6 5406.4 5413.8 5419.7 6119.4 6150.0 6157.5 6171.8 6211.9 6175.5 6186.4 6187.0 6194.0 6200.0 6052.9 6107.2 6200.7 6208.9 6211.5 5133.5 5143.3 6129.0 6132.2 6161.2 6145.4 6189.3 6193.8 6208.4 6210.9 5465.3 5667.9 6062.4 6153.2 6195.1 5480.5 5947.9 6007.0 6061.5 6179.4 5325.6 6020.8 6023.1 6023.1 6070.0 5180.9 6146.5 6154.5 6155.1 6157.2 4807.4 5873.4 5882.1 5983.6 5993.0 18:40:53 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | [#1] ext.html Cycles:180 Average:75252.96 Median:37074.22 stddev:187411.19 (505.5%) stddev-sans-first:187852.83 18:40:53 INFO - PROCESS | 2180 | Values: 476.2 490.6 500.1 522.5 541.3 25246.9 29875.0 30498.4 32515.7 32517.0 41372.0 41903.4 41940.2 41947.6 42067.4 9282.7 9482.8 9488.0 9490.2 9500.4 9420.7 9521.2 9521.9 9528.0 9547.1 9506.6 9530.5 9533.9 9542.0 9562.2 9474.7 9505.4 9506.6 9558.8 9562.9 9370.9 9454.1 9455.9 9469.3 9474.5 39430.8 39687.0 39687.2 39697.0 39824.2 3268.1 3300.5 3349.8 3459.4 3737.7 28099.7 28320.7 29791.0 30802.8 30870.5 2733.4 3216.4 3221.8 3258.9 3317.4 502415.0 504811.0 505872.0 505925.0 524188.1 32800.0 33101.2 33613.2 57184.1 57612.0 35253.3 35265.8 35306.6 35392.8 35475.0 14200.1 14336.6 14354.9 14364.8 14367.7 12023.5 12112.8 12118.4 12120.2 12176.3 6819.9 7659.4 7717.7 7718.5 7792.3 2760.8 2854.4 3222.7 3224.8 3239.6 851.1 920.3 948.3 1003.7 1048.4 29481.0 30035.9 30069.8 30188.8 30218.5 1026.4 1027.6 1042.5 1095.5 1109.6 15018.8 15278.8 15314.8 15454.1 15906.7 193711.0 957291.0 973836.3 975496.0 976762.0 57089.0 57920.1 57992.0 58075.4 58304.4 1223.4 1262.2 1350.9 1388.6 1409.2 22752.2 29993.9 30636.1 31314.0 32005.0 55064.7 55848.7 55853.2 55864.0 55884.9 638125.0 644266.0 645050.0 645302.0 647377.0 21711.3 23337.0 23981.9 26581.2 26606.3 37467.8 43469.1 44743.3 45362.0 45485.6 36083.0 36833.8 37446.4 37858.4 39015.0 37205.0 37599.0 37968.0 38220.0 39026.6 36198.5 36672.6 36872.8 36943.4 37343.3 32000.0 32995.0 33712.5 33881.3 34076.8 24653.8 24743.8 25218.9 25254.7 25295.7 18:40:53 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | [#2] jquery.html Cycles:180 Average:35298.45 Median:25453.37 stddev:103433.60 (406.4%) stddev-sans-first:103690.48 18:40:53 INFO - PROCESS | 2180 | Values: 352.0 368.7 372.9 384.2 400.3 14737.3 14964.2 15436.5 19967.1 20391.4 16173.8 17571.5 19314.3 20452.4 20534.6 5593.9 5858.6 5860.5 5863.7 5866.1 5121.6 5694.0 5741.4 5786.5 5823.0 5652.7 5729.4 5740.6 5751.6 5756.0 5500.6 5735.4 5742.7 5748.6 5756.6 4896.8 4993.6 5052.5 5066.7 5071.5 24582.0 25032.2 25119.4 25271.7 25635.0 2239.7 2268.9 2285.7 2293.9 3045.5 15037.5 15278.2 15769.7 16327.7 16336.2 1584.5 1646.0 1660.5 1679.6 1702.5 112977.4 113727.4 114022.0 117431.0 119621.4 10305.8 10626.6 10693.5 10934.9 11195.2 13803.7 13813.8 13845.2 13850.1 13868.0 7625.4 8035.7 8169.5 8429.4 8558.1 8457.9 8464.5 8478.7 8610.3 8690.6 6423.2 6517.9 6522.7 6554.4 6583.8 1788.5 1827.4 1830.3 1832.7 1835.6 886.9 905.6 908.6 925.1 957.5 21430.8 22319.4 22550.3 22610.0 22734.3 954.3 956.4 989.4 990.5 1025.3 11378.6 11690.3 11762.0 11811.4 11972.6 615409.0 620387.0 626111.9 627449.0 632727.0 84976.6 91525.6 93655.0 114724.0 121419.4 948.8 980.6 1019.9 1020.8 1021.6 18922.8 19423.1 20081.3 20086.8 20341.3 56711.0 57754.4 58062.7 59001.7 59201.1 79068.6 81584.6 82717.6 82977.6 83031.6 7477.7 7554.8 7962.9 8296.5 8452.7 12537.4 12870.4 13470.6 13537.5 13551.3 11198.5 11229.6 11617.9 11619.7 11779.5 9110.0 11226.3 11245.8 11297.7 11640.6 11052.1 11449.8 11665.7 11783.8 11903.2 8197.2 8335.6 8362.8 8396.7 8439.9 9644.3 9961.3 10825.2 11022.4 11326.7 18:40:53 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | [#3] mootools.html Cycles:180 Average:5443.93 Median:5196.13 stddev:2997.04 (57.7%) stddev-sans-first:2990.69 18:40:53 INFO - PROCESS | 2180 | Values: 1486.5 1503.3 1675.7 1818.5 1851.1 8167.8 8559.1 8569.0 8582.4 8610.3 7108.4 7255.4 7530.0 7541.5 8150.9 2321.1 2349.5 2351.8 2352.7 2359.3 2349.7 2378.8 2395.9 2396.1 2397.6 2303.1 2304.9 2335.2 2338.5 2351.3 2310.4 2329.7 2343.5 2354.3 2359.3 1863.6 2158.7 2179.1 2180.4 2186.8 7202.5 8279.1 8439.8 8455.2 8463.1 5071.3 5226.7 5229.7 5240.2 5255.9 3099.3 3122.3 3254.1 3340.7 3577.7 1070.5 1074.0 1090.7 1110.2 1116.2 10919.2 11031.1 11041.1 11050.5 11092.4 3965.7 4492.4 4533.3 4727.0 4732.2 9404.6 9404.7 9408.3 9416.3 9433.7 5179.9 5193.2 5199.1 5206.2 5211.8 3951.7 4264.6 4368.9 4765.9 4778.4 3711.0 3767.7 3811.4 3842.4 3856.0 1245.2 1247.6 1255.2 1263.9 1268.9 732.6 855.4 901.5 907.7 909.1 5568.2 5618.8 5667.5 5806.4 5977.0 901.9 943.8 966.8 974.0 989.9 3227.1 3231.3 3740.9 3826.6 3940.2 9688.1 9898.0 10159.9 10161.7 10177.6 10229.7 10254.7 10260.5 10262.2 10266.4 7735.8 7953.7 7955.0 7963.7 7978.2 5582.9 5686.4 5749.3 5952.8 5963.6 9492.0 9536.0 9540.2 9542.6 9546.9 8475.9 9226.5 9443.2 9757.4 9773.3 7155.6 7197.3 7204.1 7246.9 7308.5 7763.3 7850.6 7865.3 7867.8 7869.5 6949.6 7067.1 7158.5 7207.6 7313.3 6867.7 6993.1 7028.9 7120.1 7152.2 5569.3 6565.1 6666.0 6671.2 6687.9 4859.6 4872.8 4900.0 4902.1 4934.6 4815.4 4827.0 4838.1 4983.4 5005.8 18:40:53 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | [#4] prototype.html Cycles:180 Average:4409.57 Median:4910.54 stddev:1400.27 (28.5%) stddev-sans-first:1369.10 18:40:53 INFO - PROCESS | 2180 | Values: 258.3 304.5 306.5 310.6 314.1 4687.6 4831.5 5237.3 5308.5 5315.5 4910.3 4964.7 5029.9 5095.3 5216.6 3981.2 4001.2 4005.3 4027.1 4062.5 4024.4 4029.0 4034.7 4043.6 4045.6 3015.7 3385.5 3936.3 3951.7 4005.4 3296.8 3311.7 3363.8 3956.6 3964.7 3436.4 3441.2 3441.8 3449.2 3449.9 4164.9 4581.6 4921.8 5042.3 5113.4 3686.2 4339.6 4504.5 4585.6 4587.8 5529.8 5534.3 5548.8 5550.2 5556.6 3303.9 3503.7 3756.9 3756.9 3849.7 5221.8 5259.5 5552.3 5590.5 5869.7 3439.6 3568.3 3699.6 3745.4 3864.3 3665.7 3888.2 3915.4 3916.5 3929.6 3455.6 3852.7 4147.4 4538.5 4567.2 4230.5 4656.1 5541.0 5557.0 5634.7 1076.7 1169.5 1209.7 1264.7 1274.3 676.8 806.8 836.8 866.9 872.1 4241.6 4459.3 4479.0 4779.6 4924.5 4676.9 4963.6 5458.8 5489.0 5513.2 4089.7 4113.2 4141.9 4538.2 4918.0 2805.6 2812.8 2822.7 2825.8 2829.7 4676.4 5447.7 5495.4 5495.7 5509.6 4910.8 5454.5 5592.7 5951.2 5984.2 5131.7 5911.8 5920.0 5938.5 5972.7 4728.5 5352.5 5466.3 5785.0 5836.6 5762.9 5767.9 5772.4 5773.9 5794.6 5181.5 5898.0 5917.4 5951.6 5960.0 3224.7 3464.8 3789.2 3815.9 3908.7 5766.3 5779.2 5801.7 5813.9 5814.9 4755.6 5638.4 5643.4 5647.9 5682.8 5350.5 5576.4 5626.6 5637.1 5698.5 5049.8 5234.7 5515.7 5524.3 5543.4 4672.6 5079.7 5162.6 5610.8 5614.4 4718.5 5360.2 5460.2 5486.8 5494.9 18:40:53 INFO - PROCESS | 2180 | 18:40:53 INFO - PROCESS | 2180 | [#5] yui.html Cycles:180 Average:19185.94 Median:4430.73 stddev:27385.50 (618.1%) stddev-sans-first:27426.79 18:40:53 INFO - PROCESS | 2180 | Values: 606.9 718.1 772.8 781.6 794.4 13973.0 14048.6 14765.2 14767.6 14782.0 12905.5 13157.6 13209.9 13324.8 13340.7 825.0 843.9 845.1 846.3 847.2 656.9 679.1 685.1 853.0 855.3 869.7 893.7 897.2 902.3 907.0 888.4 890.6 890.8 891.4 893.4 508.6 522.7 662.9 663.7 663.9 13175.2 13654.8 13725.9 13978.7 13995.0 13453.1 13716.8 13833.2 13976.3 13994.8 10553.6 10971.0 11139.7 11457.5 11612.4 3731.0 3732.2 3733.4 3828.7 3854.3 83097.0 85774.6 85782.6 86344.0 86890.0 10258.8 10300.4 10498.4 10511.2 10583.5 20446.1 20601.3 20707.7 20728.0 20737.4 7479.2 7534.9 7626.1 7657.8 7735.6 4222.7 4298.3 4314.1 4345.9 4472.1 3345.9 3434.3 3459.4 3461.9 3466.9 644.4 647.5 665.9 703.4 706.1 578.6 630.9 633.7 644.5 671.5 8234.4 9087.3 9531.4 9606.2 9822.3 618.0 623.9 627.4 628.9 679.6 3986.5 4214.6 4325.2 4338.5 4389.4 66680.0 99627.0 102075.5 105200.5 105242.9 89452.0 91878.5 94533.0 94840.3 95367.5 48647.3 53182.7 65680.7 68964.0 80048.6 9043.6 9459.9 9581.7 9691.3 9774.0 47676.8 48232.5 48776.0 53351.5 53757.0 55766.0 72344.9 74669.0 77244.5 80453.0 11664.8 11734.8 11970.0 12359.5 13047.3 12070.3 12265.3 12634.3 13593.2 14839.4 12509.7 12679.7 12823.9 13025.2 13178.9 10370.6 12393.4 12819.4 13238.2 13302.3 11785.5 11993.6 12356.9 12450.6 12815.8 8549.6 10445.5 10546.1 10687.1 10964.7 11006.9 11028.8 11226.2 11231.4 11312.8 18:40:53 INFO - PROCESS | 2180 | -------- Summary: end -------- 18:40:53 INFO - PROCESS | 2180 | 18:40:54 INFO - TEST-INFO | 2180: exit 0 18:40:54 INFO - TEST-OK | dromaeo_css | took 1118756ms 18:40:54 INFO - TEST-START | kraken 18:40:54 INFO - operating with platform_type : mac_ 18:40:54 INFO - Initialising browser for kraken test... 18:40:54 INFO - TEST-INFO | started process 2184 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpe9_J2s/profile http://localhost:55318/getInfo.html) 18:40:56 INFO - PROCESS | 2184 | __metrics Screen width/height:1600/1200 18:40:56 INFO - PROCESS | 2184 | colorDepth:24 18:40:56 INFO - PROCESS | 2184 | Browser inner width/height: 1024/689 18:40:56 INFO - PROCESS | 2184 | __metrics 18:40:56 INFO - PROCESS | 2184 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:40:56 INFO - PROCESS | 2184 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:40:57 INFO - TEST-INFO | 2184: exit 0 18:40:57 INFO - Browser initialized. 18:40:57 INFO - Running cycle 1/1 for kraken test... 18:40:57 INFO - TEST-INFO | started process 2185 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpe9_J2s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:09 INFO - PROCESS | 2185 | RSS: Main: 222695424 18:41:09 INFO - PROCESS | 2185 | 18:41:12 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55318/tests/kraken/driver.html?testName=audio-beat-detection) 18:41:13 INFO - PROCESS | 2185 | RSS: Main: 272728064 18:41:13 INFO - PROCESS | 2185 | 18:41:15 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55318/tests/kraken/driver.html?testName=audio-dft) 18:41:16 INFO - PROCESS | 2185 | RSS: Main: 283291648 18:41:16 INFO - PROCESS | 2185 | 18:41:19 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55318/tests/kraken/driver.html?testName=audio-fft) 18:41:20 INFO - PROCESS | 2185 | RSS: Main: 379760640 18:41:20 INFO - PROCESS | 2185 | 18:41:22 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55318/tests/kraken/driver.html?testName=audio-oscillator) 18:41:22 INFO - PROCESS | 2185 | RSS: Main: 283607040 18:41:22 INFO - PROCESS | 2185 | 18:41:25 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55318/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:41:25 INFO - PROCESS | 2185 | RSS: Main: 284831744 18:41:25 INFO - PROCESS | 2185 | 18:41:28 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55318/tests/kraken/driver.html?testName=imaging-darkroom) 18:41:29 INFO - PROCESS | 2185 | RSS: Main: 325500928 18:41:29 INFO - PROCESS | 2185 | 18:41:31 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55318/tests/kraken/driver.html?testName=imaging-desaturate) 18:41:32 INFO - PROCESS | 2185 | RSS: Main: 326602752 18:41:32 INFO - PROCESS | 2185 | 18:41:35 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55318/tests/kraken/driver.html?testName=json-parse-financial) 18:41:36 INFO - PROCESS | 2185 | RSS: Main: 324136960 18:41:36 INFO - PROCESS | 2185 | 18:41:37 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55318/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:41:38 INFO - PROCESS | 2185 | RSS: Main: 299532288 18:41:38 INFO - PROCESS | 2185 | 18:41:40 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55318/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:41:40 INFO - PROCESS | 2185 | RSS: Main: 300302336 18:41:40 INFO - PROCESS | 2185 | 18:41:43 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55318/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:41:43 INFO - PROCESS | 2185 | RSS: Main: 302743552 18:41:43 INFO - PROCESS | 2185 | 18:41:46 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55318/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:41:47 INFO - PROCESS | 2185 | RSS: Main: 321261568 18:41:47 INFO - PROCESS | 2185 | 18:41:50 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55318/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:41:50 INFO - PROCESS | 2185 | RSS: Main: 315232256 18:41:50 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | Cycle 1(1): loaded http://localhost:55318/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55318/tests/kraken/driver.html?testName=ai-astar) 18:41:52 INFO - PROCESS | 2185 | RSS: Main: 359284736 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | __start_tp_report 18:41:52 INFO - PROCESS | 2185 | _x_x_mozilla_page_load 18:41:52 INFO - PROCESS | 2185 | _x_x_mozilla_page_load_details 18:41:52 INFO - PROCESS | 2185 | |i|pagename|runs| 18:41:52 INFO - PROCESS | 2185 | |0;ai-astar;115;115;114;115;115;115;115;115;115;114 18:41:52 INFO - PROCESS | 2185 | |1;audio-beat-detection;146;114;129;145;110;111;110;108;138;109 18:41:52 INFO - PROCESS | 2185 | |2;audio-dft;185;187;194;187;182;184;184;182;183;183 18:41:52 INFO - PROCESS | 2185 | |3;audio-fft;81;59;58;106;86;65;59;59;85;83 18:41:52 INFO - PROCESS | 2185 | |4;audio-oscillator;85;69;115;100;96;75;89;68;69;93 18:41:52 INFO - PROCESS | 2185 | |5;imaging-gaussian-blur;88;86;89;86;89;85;89;87;89;85 18:41:52 INFO - PROCESS | 2185 | |6;imaging-darkroom;93;93;94;93;94;93;94;93;94;94 18:41:52 INFO - PROCESS | 2185 | |7;imaging-desaturate;83;85;82;89;66;94;128;70;127;123 18:41:52 INFO - PROCESS | 2185 | |8;json-parse-financial;55;56;53;53;55;59;59;58;58;58 18:41:52 INFO - PROCESS | 2185 | |9;json-stringify-tinderbox;77;65;73;74;71;70;72;72;72;74 18:41:52 INFO - PROCESS | 2185 | |10;stanford-crypto-aes;98;98;95;100;77;92;92;98;102;100 18:41:52 INFO - PROCESS | 2185 | |11;stanford-crypto-ccm;141;142;141;153;144;146;139;143;217;159 18:41:52 INFO - PROCESS | 2185 | |12;stanford-crypto-pbkdf2;169;165;167;164;213;165;162;167;165;164 18:41:52 INFO - PROCESS | 2185 | |13;stanford-crypto-sha256-iterative;67;68;70;67;66;68;66;67;65;66 18:41:52 INFO - PROCESS | 2185 | __end_tp_report 18:41:52 INFO - PROCESS | 2185 | __start_cc_report 18:41:52 INFO - PROCESS | 2185 | _x_x_mozilla_cycle_collect,2021 18:41:52 INFO - PROCESS | 2185 | __end_cc_report 18:41:52 INFO - PROCESS | 2185 | __startTimestamp1483411312959__endTimestamp 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | ------- Summary: start ------- 18:41:52 INFO - PROCESS | 2185 | Number of tests: 14 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:41:52 INFO - PROCESS | 2185 | Values: 115.0 115.0 114.0 115.0 115.0 115.0 115.0 115.0 115.0 114.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:121.50 stddev:15.80 (13.0%) stddev-sans-first:14.18 18:41:52 INFO - PROCESS | 2185 | Values: 146.0 114.0 129.0 145.0 110.0 111.0 110.0 108.0 138.0 109.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#2] audio-dft Cycles:10 Average:185.10 Median:184.50 stddev:3.60 (2.0%) stddev-sans-first:3.82 18:41:52 INFO - PROCESS | 2185 | Values: 185.0 187.0 194.0 187.0 182.0 184.0 184.0 182.0 183.0 183.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#3] audio-fft Cycles:10 Average:74.10 Median:73.00 stddev:16.43 (22.5%) stddev-sans-first:17.24 18:41:52 INFO - PROCESS | 2185 | Values: 81.0 59.0 58.0 106.0 86.0 65.0 59.0 59.0 85.0 83.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:80.00 stddev:15.70 (19.6%) stddev-sans-first:16.65 18:41:52 INFO - PROCESS | 2185 | Values: 85.0 69.0 115.0 100.0 96.0 75.0 89.0 68.0 69.0 93.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.50 stddev:1.70 (1.9%) stddev-sans-first:1.79 18:41:52 INFO - PROCESS | 2185 | Values: 88.0 86.0 89.0 86.0 89.0 85.0 89.0 87.0 89.0 85.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 18:41:52 INFO - PROCESS | 2185 | Values: 93.0 93.0 94.0 93.0 94.0 93.0 94.0 93.0 94.0 94.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:82.50 stddev:23.12 (28.0%) stddev-sans-first:24.14 18:41:52 INFO - PROCESS | 2185 | Values: 83.0 85.0 82.0 89.0 66.0 94.0 128.0 70.0 127.0 123.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:58.00 stddev:2.32 (4.0%) stddev-sans-first:2.40 18:41:52 INFO - PROCESS | 2185 | Values: 55.0 56.0 53.0 53.0 55.0 59.0 59.0 58.0 58.0 58.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.50 stddev:3.13 (4.3%) stddev-sans-first:2.74 18:41:52 INFO - PROCESS | 2185 | Values: 77.0 65.0 73.0 74.0 71.0 70.0 72.0 72.0 72.0 74.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:93.50 stddev:7.21 (7.7%) stddev-sans-first:7.57 18:41:52 INFO - PROCESS | 2185 | Values: 98.0 98.0 95.0 100.0 77.0 92.0 92.0 98.0 102.0 100.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:145.00 stddev:23.49 (16.2%) stddev-sans-first:24.54 18:41:52 INFO - PROCESS | 2185 | Values: 141.0 142.0 141.0 153.0 144.0 146.0 139.0 143.0 217.0 159.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:166.00 stddev:15.20 (9.2%) stddev-sans-first:16.12 18:41:52 INFO - PROCESS | 2185 | Values: 169.0 165.0 167.0 164.0 213.0 165.0 162.0 167.0 165.0 164.0 18:41:52 INFO - PROCESS | 2185 | 18:41:52 INFO - PROCESS | 2185 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.41 (2.1%) stddev-sans-first:1.50 18:41:52 INFO - PROCESS | 2185 | Values: 67.0 68.0 70.0 67.0 66.0 68.0 66.0 67.0 65.0 66.0 18:41:52 INFO - PROCESS | 2185 | -------- Summary: end -------- 18:41:52 INFO - PROCESS | 2185 | 18:41:53 INFO - TEST-INFO | 2185: exit 0 18:41:54 INFO - TEST-OK | kraken | took 59901ms 18:41:54 INFO - SUITE-END | took 1178s 18:41:54 INFO - Completed test suite (00:19:38) 18:41:54 INFO - javascript benchmark 18:41:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.0108967309816, 3297.3075654112636, 3302.8183449910243, 3330.3838789823885, 3335.399628066947, 4872.902541949168, 5683.976884160849, 5702.201691763151, 5786.392428794974, 5806.7096645167785, 5700.743466544005, 5748.827535173941, 5764.5676574256995, 5858.648481091138, 5875.001249787536, 5202.453742357055, 5226.817061402832, 5231.921521177185, 5283.841484755463, 5293.655912365684, 4348.847790327339, 5191.714455704935, 5203.219517072451, 5330.440303768107, 5332.92000619991, 5191.558717508998, 5216.582673386139, 5241.8427447176455, 5262.578993680516, 5279.155335146377, 5256.185291279858, 5273.367195936512, 5325.174597937326, 5331.893362132774, 5344.144936810092, 5194.3247377840635, 5252.343457067866, 5273.499017593323, 5289.074411977888, 5331.706756128411, 5670.659760414388, 5676.460736230051, 5708.086706127041, 5752.597318187729, 5776.884462310731, 6106.2061931948565, 6135.89252969787, 6145.877082458326, 6170.814875553696, 6181.81454556364, 6191.4118158774845, 6206.565540412173, 6218.782342617987, 6227.626342419424, 6238.251409830849, 5793.333766616893, 5824.009918313896, 5838.357780644125, 5894.705264736746, 5910.054391297414, 6260.154879091356, 6268.373162683695, 6278.466330361879, 6281.780137695158, 6282.403171698681, 6150.138980542728, 6155.753769849156, 6158.692065396713, 6160.722767475474, 6178.104174894615, 6124.5100391968535, 6129.049997250433, 6137.60105593135, 6144.4945988232275, 6187.721552530147, 6132.122542660378, 6151.876962460726, 6174.135621013062, 6185.041318595625, 6196.411340922605, 6103.3591472894705, 6171.382861713792, 6177.536684748662, 6178.7219575119225, 6185.288691800501, 4815.422149342031, 4924.60603151747, 4956.083124621957, 4967.95672908686, 4997.775100120503, 4373.2565463871215, 4421.137878113737, 4426.756528390905, 4472.686911916135, 4498.415206023196, 5642.181883626851, 5766.625169363978, 5784.508316793035, 5793.652380857077, 5799.623024503394, 6172.753089876354, 6202.689865506797, 6209.875802483925, 6217.875642487215, 6226.59527130735, 5621.7189140542805, 5698.373178950312, 5722.484976352148, 5774.913376299359, 5779.971100144557, 5344.652597581145, 5399.595030372738, 5406.351237851483, 5413.756380962787, 5419.701916394557, 6119.380693981147, 6150.015997440388, 6157.53818463626, 6171.753129874754, 6211.875762484635, 6175.536834737323, 6186.443220110032, 6187.0, 6194.0, 6200.007998720183, 6052.939470605237, 6107.188815080102, 6200.658963757037, 6208.937910621016, 6211.534134939988, 5133.460986596354, 5143.331366922426, 6129.0, 6132.202813634199, 6161.168242287234, 6145.4161854623735, 6189.319174890848, 6193.84515387119, 6208.441240288395, 6210.937890621036, 5465.289512363367, 5667.923094612175, 6062.393760623902, 6153.230846144232, 6195.101710252078, 5480.506754392124, 5947.881042379215, 6007.0, 6061.484773794277, 6179.443850053517, 5325.573954083741, 6020.849478762891, 6023.066424704178, 6023.096535519707, 6069.969650151721, 5180.922286165786, 6146.5390095742105, 6154.538409619386, 6155.1075094112, 6157.168782214344, 4807.399075115611, 5873.4126587342635, 5882.117682347682, 5983.640981541122, 5992.951233534027], "value": 5656.314585222112, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.1880992907094, 490.5977098779001, 500.0723657615124, 522.4618642797915, 541.320642593545, 25246.873765631193, 29874.954376596797, 30498.390032199342, 32515.67484325156, 32517.02448926536, 41372.0, 41903.37144942828, 41940.16119677605, 41947.580524194746, 42067.36898946518, 9282.675106371262, 9482.81034379312, 9487.952560237189, 9490.240780737558, 9500.382475139128, 9420.717378478657, 9521.190698790577, 9521.857172142423, 9527.999560046199, 9547.14075733184, 9506.619735210583, 9530.523473826315, 9533.90466095337, 9541.95229023854, 9562.18721408681, 9474.715758527222, 9505.382150160218, 9506.572204250826, 9558.761030974212, 9562.856557151614, 9370.906290937071, 9454.05459454056, 9455.912570054463, 9469.337146399756, 9474.478903660296, 39430.802845985876, 39687.00782480461, 39687.20625587501, 39697.0, 39824.20351592952, 3268.1176082457605, 3300.455424854896, 3349.8492567834505, 3459.41189997701, 3737.663610275088, 28099.735511902014, 28320.716792832012, 29791.0, 30802.845985769927, 30870.536941945666, 2733.439644872796, 3216.437123503401, 3221.806691598488, 3258.853351599184, 3317.3531161423534, 502415.0, 504811.0, 505872.0, 505925.0, 524188.0649548065, 32800.01599952005, 33101.17247068843, 33613.15967100793, 57184.142237866465, 57612.0, 35253.29493410118, 35265.82367088148, 35306.64693353033, 35392.81464668141, 35475.0, 14200.076994995292, 14336.56990290293, 14354.85645143556, 14364.784528232085, 14367.712645747026, 12023.519059237708, 12112.81830772539, 12118.39408029595, 12120.212186207867, 12176.330301833485, 6819.897701534481, 7659.425543084291, 7717.65270562826, 7718.5368877866385, 7792.259735325135, 2760.7791376689815, 2854.4433835402315, 3222.726068284175, 3224.758143139274, 3239.5626590410466, 851.1275942159336, 920.327891712064, 948.2698322291797, 1003.7039073473343, 1048.449563978902, 29481.0, 30035.94874179393, 30069.849650751607, 30188.84905575458, 30218.54672179919, 1026.4354604967386, 1027.6249169053392, 1042.4527123260198, 1095.5343978808974, 1109.589451902801, 15018.849811501963, 15278.847211527966, 15314.770278445832, 15454.072755634697, 15906.68186636284, 193711.0, 957291.0, 973836.2616373887, 975496.0, 976762.0, 57089.0, 57920.13119803206, 57992.0, 58075.41924580785, 58304.416955829896, 1223.3944197622234, 1262.2363470100825, 1350.9324533773272, 1388.625071230762, 1409.1615488784157, 22752.20367287137, 29993.85003075058, 30636.080917572508, 31314.0, 32005.0, 55064.72467637636, 55848.72075639596, 55853.16220256781, 55864.0, 55884.88230235454, 638125.0, 644266.0, 645050.0, 645302.0, 647377.0, 21711.34865954024, 23337.0, 23981.920813563782, 26581.202563923114, 26606.334841629116, 37467.81266093652, 43469.09173717067, 44743.32885006793, 45362.0, 45485.63543093712, 36083.0, 36833.81583092068, 37446.438303424926, 37858.43212351872, 39015.0, 37205.0, 37599.0, 37968.0, 38220.0, 39026.609733902296, 36198.4570231452, 36672.63327366692, 36872.81563592165, 36943.44584831283, 37343.253134937695, 32000.0, 32995.0, 33712.49431258582, 33881.3223735519, 34076.82961585176, 24653.753462465145, 24743.762811858975, 25218.873905630353, 25254.74745252524, 25295.747042530074], "value": 18289.102797530733, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.96128425873167, 368.6995098994319, 372.94965179700733, 384.2353716104951, 400.33744153426073, 14737.263136843156, 14964.176970266632, 15436.459723909651, 19967.10148043338, 20391.38825835227, 16173.83826161738, 17571.506421954116, 19314.32399866005, 20452.386428407135, 20534.58930821383, 5593.888122237563, 5858.648481091148, 5860.53115750741, 5863.677497737623, 5866.149408335788, 5121.641485096045, 5693.971530142344, 5741.397153298906, 5786.450287222707, 5823.010088285002, 5652.657127620855, 5729.39841316662, 5740.626859254156, 5751.56863235257, 5756.0, 5500.642458240201, 5735.397783232742, 5742.712864356787, 5748.626339287975, 5756.625819321731, 4896.755162241873, 4993.550580447741, 5052.545270925634, 5066.746662666852, 5071.518205770446, 24582.0, 25032.249032529053, 25119.36246694089, 25271.74728252712, 25635.0, 2239.664050392454, 2268.88655567221, 2285.702858628384, 2293.8853057347233, 3045.512717965137, 15037.473688420852, 15278.15970121643, 15769.68460630781, 16327.673446531122, 16336.18319084053, 1584.5008822220964, 1645.9670806583922, 1660.4520508232254, 1679.5801049737565, 1702.5147832867697, 112977.43511282474, 113727.43136284349, 114022.0, 117431.0, 119621.40189298998, 10305.84541231882, 10626.628068017582, 10693.518791654395, 10934.945325273482, 11195.160362972809, 13803.723925521434, 13813.792793108112, 13845.16928984244, 13850.09974351664, 13868.0, 7625.351845093118, 8035.718749843727, 8169.5098294102545, 8429.367797415218, 8558.101399353103, 8457.915420845837, 8464.492130472192, 8478.74563763088, 8610.311175105977, 8690.565471726388, 6423.197100362455, 6517.934820651828, 6522.706478208397, 6554.377334153248, 6583.802485925334, 1788.4813404112922, 1827.378691244983, 1830.3319288459882, 1832.743415921772, 1835.5686413692772, 886.8536691445968, 905.6196397513061, 908.6456282050005, 925.1026504290827, 957.5260246178048, 21430.785692143192, 22319.44201394978, 22550.32349029532, 22610.0, 22734.317970460583, 954.341504361988, 956.3926906414476, 989.4063561862969, 990.4601991914424, 1025.3386565665105, 11378.601748938912, 11690.298582085103, 11762.0, 11811.409429528489, 11972.58095966637, 615409.0, 620387.0, 626111.8694406499, 627449.0, 632727.0, 84976.57511712401, 91525.62711559332, 93655.0, 114724.0, 121419.39290303492, 948.8434408322549, 980.6469670918464, 1019.938803671782, 1020.7601213714771, 1021.5862575656871, 18922.810771892106, 19423.12595933158, 20081.29715459952, 20086.79913200849, 20341.288054918008, 56711.0, 57754.422455774904, 58062.7096864513, 59001.70499147477, 59201.111983319424, 79068.60465697634, 81584.59207704161, 82717.58641206755, 82977.58511207406, 83031.5848420754, 7477.728786106266, 7554.848903021799, 7962.880556791537, 8296.502209867427, 8452.704155354779, 12537.435815388511, 12870.356482175666, 13470.595882123553, 13537.458501659823, 13551.322433878464, 11198.536322009146, 11229.606963756229, 11617.941910290394, 11619.709507262387, 11779.469923853618, 9110.0, 11226.270292430965, 11245.831312530156, 11297.661070167907, 11640.592579259684, 11052.115830733683, 11449.771004579696, 11665.708357291136, 11783.76432471363, 11903.226290291102, 8197.221263979913, 8335.624896879533, 8362.790930226793, 8396.706115285935, 8439.915600843913, 9644.276679248944, 9961.252906031932, 10825.242233043611, 11022.393768342818, 11326.660200194008], "value": 9649.344474284399, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1486.4797320937673, 1503.2558883352742, 1675.664867026595, 1818.5271829324372, 1851.1207176591113, 8167.754967350981, 8559.144085591446, 8568.971723393186, 8582.442141260814, 8610.311175105991, 7108.360247577717, 7255.419566434686, 7530.021097257349, 7541.472096953215, 8150.877736833952, 2321.129576408847, 2349.5418393413293, 2351.811855051592, 2352.694149760529, 2359.327591636384, 2349.7297810751725, 2378.774016468433, 2395.8562486250794, 2396.065534441569, 2397.5924092904247, 2303.1248125712204, 2304.8617082974993, 2335.1593426366494, 2338.485533182697, 2351.2946116165167, 2310.3761984264324, 2329.685492458522, 2343.484433424644, 2354.2584086012853, 2359.315798418457, 1863.5900101977543, 2158.665406861939, 2179.052112331141, 2180.3894909425226, 2186.7703891091364, 7202.531835430733, 8279.089300176976, 8439.83120337596, 8455.154484551556, 8463.111373305837, 5071.340725705634, 5226.738663066832, 5229.712365819878, 5240.187770895517, 5255.947440525584, 3099.3181500070173, 3122.2974830663147, 3254.1376535218187, 3340.6593406593406, 3577.749557530974, 1070.5289672544134, 1073.9731506712317, 1090.7273181704575, 1110.1562812262657, 1116.2465335898237, 10919.235653504258, 11031.062359699436, 11041.116710663127, 11050.502727377287, 11092.445377731081, 3965.722399432012, 4492.438445194351, 4533.342665313511, 4727.030958653468, 4732.195526760459, 9404.576794044147, 9404.670836520689, 9408.341416100944, 9416.293777966679, 9433.66982155635, 5179.886324440188, 5193.169092945111, 5199.090159222121, 5206.167013277933, 5211.765470553834, 3951.68386529077, 4264.552222016706, 4368.868933931987, 4765.8570242892765, 4778.354922085544, 3711.0, 3767.7362584619364, 3811.3711237646035, 3842.423636454554, 3855.9614403855603, 1245.1657389549023, 1247.607003793802, 1255.2217625072512, 1263.8988880889506, 1268.898488120948, 732.597071610612, 855.3841234311286, 901.5041727050227, 907.7004588485784, 909.1454033208895, 5568.248286481355, 5618.803341883015, 5667.518260947783, 5806.390329015477, 5977.043673012297, 901.8737376767258, 943.8159558886089, 966.7534778631403, 974.0454354732402, 989.8960609136082, 3227.128675257667, 3231.272963583175, 3740.8503659853845, 3826.641542252516, 3940.2316548273384, 9688.079632434907, 9898.0, 10159.898401016044, 10161.695149145537, 10177.643782467578, 10229.74425639365, 10254.743631409274, 10260.538275777608, 10262.179025677928, 10266.384016959006, 7735.845283094307, 7953.721619743281, 7954.960225198837, 7963.721269755531, 7978.162292959272, 5582.86042848932, 5686.374498805211, 5749.281339832521, 5952.821415357546, 5963.552733545088, 9491.952540237255, 9536.0, 9540.189083927666, 9542.618295268112, 9546.904530954602, 8475.915240847513, 9226.538673066454, 9443.150116489549, 9757.365771224848, 9773.267004974514, 7155.570665760071, 7197.280271972761, 7204.0994875640545, 7246.89129663066, 7308.488405811542, 7763.340116090196, 7850.568218748023, 7865.292123708894, 7867.803304917194, 7869.527828330319, 6949.583025018515, 7067.081279433641, 7158.463115266271, 7207.5675459472595, 7313.305236002571, 6867.706804819318, 6993.125859267591, 7028.8945665814035, 7120.1099862517185, 7152.24901385347, 5569.275994120901, 6565.146530950946, 6666.0, 6671.232808227116, 6687.866242675216, 4859.586935110555, 4872.756362181947, 4900.0, 4902.093112774148, 4934.605231581393, 4815.374001379798, 4826.95173048265, 4838.104950584153, 4983.401991761012, 5005.77474013663], "value": 4415.943555035074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.27940047268123, 304.4702218140435, 306.5447810002147, 310.57137169022485, 314.0844438461884, 4687.55468230518, 4831.5168483151665, 5237.292965449663, 5308.549442862137, 5315.548178404831, 4910.268196170909, 4964.652474326789, 5029.924551131736, 5095.261187127864, 5216.60875434343, 3981.223661386031, 4001.2397644447533, 4005.339119045354, 4027.060246020407, 4062.517181228845, 4024.3762216856435, 4028.97985510072, 4034.69739769517, 4043.6360727534516, 4045.5752146024683, 3015.677927995442, 3385.4752513360345, 3936.2914675358425, 3951.74313669612, 4005.439238506611, 3296.8021918684954, 3311.7415382154745, 3363.848626811787, 3956.6439020488006, 3964.7312859884846, 3436.398630239723, 3441.20852203992, 3441.82790860456, 3449.189440481485, 3449.896503104911, 4164.875053748362, 4581.587657110843, 4921.827736029221, 5042.268871013683, 5113.386393632788, 3686.225892562565, 4339.587739164775, 4504.527024662429, 4585.610223131004, 4587.839425620087, 5529.751161197715, 5534.335879694422, 5548.750306236229, 5550.197150439043, 5556.583256255756, 3303.8678452861855, 3503.6846683798326, 3756.8729381185535, 3756.9104959561696, 3849.6920246380223, 5221.791128354861, 5259.474052594748, 5552.278203833475, 5590.496855283044, 5869.735861886183, 3439.638837922032, 3568.2685049564775, 3699.611540788232, 3745.382011968049, 3864.304425203434, 3665.6884164845756, 3888.2223555289056, 3915.393114067324, 3916.5300163980505, 3929.5677475477673, 3455.585329760445, 3852.6725228355344, 4147.382520816884, 4538.52345503724, 4567.2464043432465, 4230.450041494586, 4656.137544981966, 5541.0, 5556.99974004683, 5634.690092044977, 1076.682378698286, 1169.5497233564968, 1209.7399059202314, 1264.690150913032, 1274.2736640115045, 676.8375589858399, 806.8426656801985, 836.7698882807276, 866.882970798934, 872.1148034744613, 4241.575842415734, 4459.264221403497, 4478.9611686852295, 4779.593734532604, 4924.4583095859425, 4676.854170728194, 4963.627727920348, 5458.754356053987, 5488.9725551371985, 5513.173024046425, 4089.6932730045364, 4113.210753322314, 4141.8964525887095, 4538.205813982539, 4917.967266841228, 2805.5651374037056, 2812.7749780017957, 2822.717728227161, 2825.7739380849484, 2829.688734239232, 4676.438827340741, 5447.673139611636, 5495.368032676213, 5495.708508500499, 5509.614326997072, 4910.821402863358, 5454.4545545445935, 5592.748326325325, 5951.23267680522, 5984.222051133412, 5131.692098474029, 5911.76352945886, 5920.0, 5938.524918006549, 5972.729498408658, 4728.527147285244, 5352.464753524617, 5466.316710411199, 5784.958707432703, 5836.601143941922, 5762.913556296743, 5767.884642307046, 5772.402418911591, 5773.8556536086935, 5794.565407594363, 5181.53366197044, 5897.967855625163, 5917.408259174048, 5951.583389162717, 5959.970200148971, 3224.7258982985772, 3464.7747896386654, 3789.1853251551047, 3815.8552434269054, 3908.6710518423874, 5766.309561880484, 5779.2198053263955, 5801.708325211603, 5813.854653633693, 5814.854628634318, 4755.643326750438, 5638.351589567088, 5643.360032778753, 5647.905268680174, 5682.77268909239, 5350.491703288181, 5576.414476479911, 5626.580570354787, 5637.125066216177, 5698.54411647059, 5049.798008079782, 5234.712090835041, 5515.669059856422, 5524.259858144832, 5543.445655434424, 4672.626189904731, 5079.6698214615935, 5162.612804039787, 5610.847972500239, 5614.354349249888, 4718.522775542868, 5360.249565060973, 5460.235567020541, 5486.780528778804, 5494.945050549603], "value": 3959.4768646385514, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.866489372338, 718.1382341190573, 772.7643068863998, 781.6209138567796, 794.3724457678435, 13973.0, 14048.648783780398, 14765.18791466469, 14767.556973290792, 14782.039167454108, 12905.483780648763, 13157.60527184186, 13209.933950330258, 13324.800127998087, 13340.733185336312, 824.9958750206256, 843.9113893041234, 845.1295165979052, 846.272205902923, 847.2120927537388, 656.940875321221, 679.1341040173793, 685.1093578348152, 852.9914700853005, 855.315747402079, 869.6651789061225, 893.7363477774072, 897.1880448194395, 902.278177458031, 906.9773255668595, 888.3559419420933, 890.6214858685033, 890.8173824365989, 891.4072142025545, 893.3835653399134, 508.5600955173748, 522.6602708239675, 662.8839953008204, 663.7112855907695, 663.9070530125787, 13175.209487430784, 13654.795178072336, 13725.931370343083, 13978.650533736636, 13995.020348575605, 13453.125546839521, 13716.794248086188, 13833.170009799444, 13976.301184940712, 13994.790078148835, 10553.577856885715, 10971.0, 11139.665810025712, 11457.484413201342, 11612.36132012739, 3731.0, 3732.197577520846, 3733.3653278942643, 3828.7319887607878, 3854.3447613905705, 83097.0, 85774.57112714459, 85782.5710871448, 86344.0, 86890.0, 10258.846117308247, 10300.433476158732, 10498.370097794157, 10511.1591072714, 10583.523741431654, 20446.07992640335, 20601.278955236496, 20707.689384659243, 20728.0, 20737.377878663665, 7479.177290498041, 7534.924650753423, 7626.084869815547, 7657.846843063107, 7735.613219339123, 4222.725522841006, 4298.290782020994, 4314.137172565437, 4345.891352716208, 4472.08322293929, 3345.916352091217, 3434.3303055904357, 3459.3600183965546, 3461.9134521636656, 3466.9133271667906, 644.3588629313863, 647.5273050673035, 665.8601693644389, 703.3522836722509, 706.0574133531793, 578.5978744772427, 630.9432151106422, 633.7369991453587, 644.5069521815815, 671.5399951033557, 8234.382421318305, 9087.31845111606, 9531.428114313163, 9606.183474404754, 9822.312438129262, 617.9721912513947, 623.9175031320655, 627.3726273726273, 628.8773689130576, 679.5820570349299, 3986.5016872890887, 4214.578542145822, 4325.199838029912, 4338.544452832471, 4389.385486031958, 66680.0, 99627.0, 102075.48962255141, 105200.47399763105, 105242.94757052485, 89452.0, 91878.54060729654, 94533.0, 94840.30958142395, 95367.52316238375, 48647.27029094496, 53182.73408632932, 65680.67159664171, 68964.0, 80048.59975700201, 9043.593038313424, 9459.944916776203, 9581.680760308258, 9691.321607487409, 9774.0, 47676.761616191696, 48232.517674822804, 48776.0, 53351.46648533465, 53757.0, 55766.0, 72344.9148262787, 74669.0, 77244.45511089859, 80452.95470452908, 11664.825027624762, 11734.823977640172, 11970.040062706637, 12359.50561977511, 13047.28239946812, 12070.27578345302, 12265.26408415498, 12634.305113218861, 13593.184408935496, 14839.40642374293, 12509.687257818627, 12679.746405072032, 12823.886287924164, 13025.218486890439, 13178.934105329412, 10370.637027703995, 12393.380330983235, 12819.42312595954, 13238.205707657571, 13302.334883255606, 11785.528578856749, 11993.64019079429, 12356.938215308866, 12450.564230251897, 12815.807762883751, 8549.572521374032, 10445.529951152064, 10546.103581195684, 10687.091597214323, 10964.725881853017, 11006.889931100586, 11028.84274537806, 11226.158038147008, 11231.4384280784, 11312.83030754523], "value": 7133.894685538542, "unit": "score"}], "name": "dromaeo_css", "value": 7066.584855256843, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 115.0, 115.0, 115.0, 115.0, 115.0, 115.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 129.0, 145.0, 110.0, 111.0, 110.0, 108.0, 138.0, 109.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 194.0, 187.0, 182.0, 184.0, 184.0, 182.0, 183.0, 183.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 106.0, 86.0, 65.0, 59.0, 59.0, 85.0, 83.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 115.0, 100.0, 96.0, 75.0, 89.0, 68.0, 69.0, 93.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 89.0, 85.0, 89.0, 87.0, 89.0, 85.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 82.0, 89.0, 66.0, 94.0, 128.0, 70.0, 127.0, 123.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 53.0, 53.0, 55.0, 59.0, 59.0, 58.0, 58.0, 58.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 65.0, 73.0, 74.0, 71.0, 70.0, 72.0, 72.0, 72.0, 74.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 95.0, 100.0, 77.0, 92.0, 92.0, 98.0, 102.0, 100.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 141.0, 153.0, 144.0, 146.0, 139.0, 143.0, 217.0, 159.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 167.0, 164.0, 213.0, 165.0, 162.0, 167.0, 165.0, 164.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 67.0, 66.0, 68.0, 66.0, 67.0, 65.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1470.6, "alertThreshold": 2.0}]} 18:41:54 INFO - Return code: 0 18:41:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:41:54 INFO - # TBPL SUCCESS # 18:41:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:41:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:41:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:41:54 INFO - 2017-01-02 18:41:54.259 screenresolution[2189:27267] starting screenresolution argv=screenresolution get 18:41:54 INFO - 2017-01-02 18:41:54.262 screenresolution[2189:27267] Display 0: 1600x1200x32@60 18:41:54 INFO - 2017-01-02 18:41:54.268 screenresolution[2190:27270] starting screenresolution argv=screenresolution list 18:41:54 INFO - Available Modes on Display 0 18:41:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:41:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:41:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:41:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:41:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:41:54 INFO - Intel Iris: 18:41:54 INFO - Chipset Model: Intel Iris 18:41:54 INFO - Type: GPU 18:41:54 INFO - Bus: Built-In 18:41:54 INFO - VRAM (Dynamic, Max): 1536 MB 18:41:54 INFO - Vendor: Intel (0x8086) 18:41:54 INFO - Device ID: 0x0a2e 18:41:54 INFO - Revision ID: 0x0009 18:41:54 INFO - Displays: 18:41:54 INFO - Display: 18:41:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:41:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:41:54 INFO - Display Serial Number: 1600x1200 60 18:41:54 INFO - Main Display: Yes 18:41:54 INFO - Mirror: Off 18:41:54 INFO - Online: Yes 18:41:54 INFO - Rotation: Supported 18:41:54 INFO - Return code: 0 18:41:54 INFO - Running post-action listener: _resource_record_post_action 18:41:54 INFO - [mozharness: 2017-01-03 02:41:54.510754Z] Finished run-tests step (success) 18:41:54 INFO - Running post-run listener: _resource_record_post_run 18:41:54 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 729088; Write bytes: 655752704; Read time: 7; Write time: 1866 18:41:54 INFO - TinderboxPrint: CPU usage
53.6% 18:41:54 INFO - TinderboxPrint: I/O read bytes / time
729,088 / 7 18:41:54 INFO - TinderboxPrint: I/O write bytes / time
655,752,704 / 1,866 18:41:54 INFO - TinderboxPrint: CPU idle
2,247.9 (46.4%) 18:41:54 INFO - TinderboxPrint: CPU system
971.6 (20.1%) 18:41:54 INFO - TinderboxPrint: CPU user
1,622.1 (33.5%) 18:41:54 INFO - TinderboxPrint: Swap in / out
349,663,232 / 0 18:41:54 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 344453632; Write bytes: 345388544; Read time: 26773; Write time: 703 18:41:54 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 729088; Write bytes: 303450112; Read time: 7; Write time: 1133 18:41:54 INFO - Running post-run listener: _upload_blobber_files 18:41:54 INFO - Blob upload gear active. 18:41:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:41:54 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 18:41:54 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'] 18:41:54 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 18:41:55 INFO - (blobuploader) - INFO - Open directory for files ... 18:41:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:41:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:41:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:56 INFO - (blobuploader) - INFO - Done attempting. 18:41:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:41:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:41:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:57 INFO - (blobuploader) - INFO - Done attempting. 18:41:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:41:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:41:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:59 INFO - (blobuploader) - INFO - Done attempting. 18:41:59 INFO - (blobuploader) - INFO - Iteration through files over. 18:41:59 INFO - Return code: 0 18:41:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:41:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:41:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b985c0ceefb63a10b7a2196b400eb6620ce1d487b08d478dae594acd760e2ab4b3c0b6ff014994b1a2cde33fba459a41cc1251281c73536b1c4d171e4de1ff99", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6d5916aa48b70667f846fd78d405704eb57360cf4f15cb708792b33d19dc39ac7d5267b905a949a4afc0e77a5778987b3abe74986639a09c12601bdfd65ecb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c31b11186f2fb4e3550c5e063c3bb5a74a7bad1f9d69973b192762115fdb6351fd1e5c2ac77abcba9a1637c8ddca54aa4bb25e23d244101689f112177bfb3721"} 18:41:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:41:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:41:59 INFO - Contents: 18:41:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b985c0ceefb63a10b7a2196b400eb6620ce1d487b08d478dae594acd760e2ab4b3c0b6ff014994b1a2cde33fba459a41cc1251281c73536b1c4d171e4de1ff99", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6d5916aa48b70667f846fd78d405704eb57360cf4f15cb708792b33d19dc39ac7d5267b905a949a4afc0e77a5778987b3abe74986639a09c12601bdfd65ecb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c31b11186f2fb4e3550c5e063c3bb5a74a7bad1f9d69973b192762115fdb6351fd1e5c2ac77abcba9a1637c8ddca54aa4bb25e23d244101689f112177bfb3721"} 18:41:59 INFO - Running post-run listener: copy_logs_to_upload_dir 18:41:59 INFO - Copying logs to upload dir... 18:41:59 INFO - mkdir: /builds/slave/test/build/upload/logs 18:41:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.031196 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-02 18:41:59.870365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 18:41:59.873775) ========= 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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b985c0ceefb63a10b7a2196b400eb6620ce1d487b08d478dae594acd760e2ab4b3c0b6ff014994b1a2cde33fba459a41cc1251281c73536b1c4d171e4de1ff99", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6d5916aa48b70667f846fd78d405704eb57360cf4f15cb708792b33d19dc39ac7d5267b905a949a4afc0e77a5778987b3abe74986639a09c12601bdfd65ecb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c31b11186f2fb4e3550c5e063c3bb5a74a7bad1f9d69973b192762115fdb6351fd1e5c2ac77abcba9a1637c8ddca54aa4bb25e23d244101689f112177bfb3721"} build_url:https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011406 build_url: 'https://queue.taskcluster.net/v1/task/YK4_ARr9QVauCL8H4oQqoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b985c0ceefb63a10b7a2196b400eb6620ce1d487b08d478dae594acd760e2ab4b3c0b6ff014994b1a2cde33fba459a41cc1251281c73536b1c4d171e4de1ff99", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6d5916aa48b70667f846fd78d405704eb57360cf4f15cb708792b33d19dc39ac7d5267b905a949a4afc0e77a5778987b3abe74986639a09c12601bdfd65ecb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c31b11186f2fb4e3550c5e063c3bb5a74a7bad1f9d69973b192762115fdb6351fd1e5c2ac77abcba9a1637c8ddca54aa4bb25e23d244101689f112177bfb3721"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 18:41:59.908210) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:41:59.908538) ========= 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.GMs7DlEo1u/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.M3xiXXKgvg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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.005192 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:41:59.957365) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 18:41:59.957752) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 18:41:59.958076) ========= ========= Total master_lag: 0.19 =========