builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0208 starttime: 1481972242.62 results: success (0) buildid: 20161217004004 builduid: c71f79c3f630442590cffef7a1eaec91 revision: 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:22.620064) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:22.620578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:22.620899) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:22.649186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:22.649914) ========= 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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-17 02:57:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.5M=0.001s 2016-12-17 02:57:23 (19.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.628422 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.404689) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.405045) ========= 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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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.037106 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.514363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 02:57:23.514758) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 --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 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QtbRqc9ZES/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.4ceNuXEjmM/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-17 02:57:23,615 truncating revision to first 12 chars 2016-12-17 02:57:23,615 Setting DEBUG logging. 2016-12-17 02:57:23,615 attempt 1/10 2016-12-17 02:57:23,615 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4a3e7356c7d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-17 02:57:24,629 unpacking tar archive at: mozilla-aurora-4a3e7356c7d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.290338 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 02:57:24.832594) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:24.832997) ========= script_repo_revision: 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:24.833476) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:24.833798) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:24.854080) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-17 02:57:24.854512) ========= /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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:57:25 INFO - MultiFileLogger online at 20161217 02:57:25 in /builds/slave/test 02:57:25 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 02:57:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:57:25 INFO - {'append_to_log': False, 02:57:25 INFO - 'base_work_dir': '/builds/slave/test', 02:57:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:57:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:57:25 INFO - 'branch': 'Mozilla-Aurora', 02:57:25 INFO - 'buildbot_json_path': 'buildprops.json', 02:57:25 INFO - 'config_files': ('talos/mac_config.py',), 02:57:25 INFO - 'default_actions': ('clobber', 02:57:25 INFO - 'read-buildbot-config', 02:57:25 INFO - 'download-and-extract', 02:57:25 INFO - 'populate-webroot', 02:57:25 INFO - 'create-virtualenv', 02:57:25 INFO - 'install', 02:57:25 INFO - 'run-tests'), 02:57:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:57:25 INFO - 'download_minidump_stackwalk': True, 02:57:25 INFO - 'download_symbols': 'ondemand', 02:57:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:57:25 INFO - 'tooltool.py': '/tools/tooltool.py', 02:57:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:57:25 INFO - '/tools/misc-python/virtualenv.py')}, 02:57:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:57:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:57:25 INFO - 'installer_path': 'installer.exe', 02:57:25 INFO - 'log_level': 'info', 02:57:25 INFO - 'log_name': 'talos', 02:57:25 INFO - 'log_to_console': True, 02:57:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:57:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:57:25 INFO - 'opt_config_files': (), 02:57:25 INFO - 'pip_index': False, 02:57:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:57:25 INFO - 'cmd': ('bash', 02:57:25 INFO - '-c', 02:57:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:57:25 INFO - 'enabled': True, 02:57:25 INFO - 'halt_on_failure': False, 02:57:25 INFO - 'name': 'check_screen_resolution'},), 02:57:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:57:25 INFO - 'cmd': ('bash', 02:57:25 INFO - '-c', 02:57:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:57:25 INFO - 'enabled': True, 02:57:25 INFO - 'halt_on_failure': False, 02:57:25 INFO - 'name': 'check_screen_resolution'},), 02:57:25 INFO - 'run_cmd_checks_enabled': True, 02:57:25 INFO - 'sps_profile': False, 02:57:25 INFO - 'sps_profile_interval': 0, 02:57:25 INFO - 'suite': 'dromaeojs', 02:57:25 INFO - 'system_bits': '32', 02:57:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:57:25 INFO - 'title': 't-yosemite-r7-0208', 02:57:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:57:25 INFO - 'use_talos_json': True, 02:57:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:57:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:57:25 INFO - 'work_dir': 'build'} 02:57:25 INFO - [mozharness: 2016-12-17 10:57:25.056418Z] Running clobber step. 02:57:25 INFO - Running pre-action listener: _resource_record_pre_action 02:57:25 INFO - Running main action method: clobber 02:57:25 INFO - rmtree: /builds/slave/test/build 02:57:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:57:27 INFO - Running post-action listener: _resource_record_post_action 02:57:27 INFO - [mozharness: 2016-12-17 10:57:27.435278Z] Finished clobber step (success) 02:57:27 INFO - [mozharness: 2016-12-17 10:57:27.435442Z] Running read-buildbot-config step. 02:57:27 INFO - Running pre-action listener: _resource_record_pre_action 02:57:27 INFO - Running main action method: read_buildbot_config 02:57:27 INFO - Using buildbot properties: 02:57:27 INFO - { 02:57:27 INFO - "project": "", 02:57:27 INFO - "product": "firefox", 02:57:27 INFO - "script_repo_revision": "production", 02:57:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:57:27 INFO - "repository": "", 02:57:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:57:27 INFO - "buildid": "20161217004004", 02:57:27 INFO - "stage_platform": "macosx64", 02:57:27 INFO - "basedir": "/builds/slave/test", 02:57:27 INFO - "nightly_build": "True", 02:57:27 INFO - "buildnumber": 69, 02:57:27 INFO - "slavename": "t-yosemite-r7-0208", 02:57:27 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:57:27 INFO - "platform": "yosemite_r7", 02:57:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:57:27 INFO - "branch": "mozilla-aurora", 02:57:27 INFO - "repo_path": "releases/mozilla-aurora", 02:57:27 INFO - "revision": "4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8", 02:57:27 INFO - "pgo_build": "False", 02:57:27 INFO - "builduid": "c71f79c3f630442590cffef7a1eaec91", 02:57:27 INFO - "slavebuilddir": "test" 02:57:27 INFO - } 02:57:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:57:27 INFO - Running post-action listener: _resource_record_post_action 02:57:27 INFO - [mozharness: 2016-12-17 10:57:27.438706Z] Finished read-buildbot-config step (success) 02:57:27 INFO - [mozharness: 2016-12-17 10:57:27.438769Z] Running download-and-extract step. 02:57:27 INFO - Running pre-action listener: _resource_record_pre_action 02:57:27 INFO - Running main action method: download_and_extract 02:57:27 INFO - mkdir: /builds/slave/test/build/tests 02:57: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')]} 02:57:27 INFO - trying https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:57:27 INFO - Downloading https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/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 02:57:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/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 02:57:28 INFO - Downloaded 1199 bytes. 02:57:28 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:57:28 INFO - Using the following test package requirements: 02:57:28 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:57:28 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:28 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:57:28 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:28 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:57:28 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:28 INFO - u'jsshell-mac.zip'], 02:57:28 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:28 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:57:28 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:57:28 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:28 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:57:28 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:28 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:57:28 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:28 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:57:28 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:28 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:57:28 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:57:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:57:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:57:28 INFO - Fetch https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:57:28 INFO - Http code: 200 02:57:28 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 02:57:28 INFO - x-amz-cf-id: BJjHt-Uksi1tXdoktRh79z6y5erT0gxX4ybrl1h7MFm9wzAUbhnRBw== 02:57:28 INFO - x-amz-version-id: mxzthTWKBX_H_DtdBl6PPeK3enn8Ezsc 02:57:29 INFO - Expected file size: 16280458 02:57:29 INFO - Obtained file size: 16280458 02:57:35 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 02:57:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:57:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:57:35 INFO - Fetch https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:57:36 INFO - Http code: 200 02:57:36 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 02:57:36 INFO - x-amz-cf-id: dmErAySnNDrJg_rFS97eBw8DkEWRWQjHDiA2H2uA8-hAkUCDhyCh0w== 02:57:36 INFO - x-amz-version-id: mxzthTWKBX_H_DtdBl6PPeK3enn8Ezsc 02:57:36 INFO - Expected file size: 16280458 02:57:36 INFO - Obtained file size: 16280458 02:57:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:57:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:57:43 INFO - Fetch https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:57:43 INFO - Http code: 200 02:57:43 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 02:57:43 INFO - x-amz-cf-id: D4VrFWgPKlxoU7nVFMaJP1oLE_Okv8Jx86KH3MWFecjAUTSvjR_gPQ== 02:57:43 INFO - x-amz-version-id: MCCf875cY1BsheeyFQ2nMdMKAmGRwvZT 02:57:44 INFO - Expected file size: 14409038 02:57:44 INFO - Obtained file size: 14409038 02:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:44 INFO - trying https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:44 INFO - Downloading https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:57:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/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 02:57:48 INFO - Downloaded 109831895 bytes. 02:57:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:48 INFO - mkdir: /builds/slave/test/properties 02:57:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:57:48 INFO - Writing to file /builds/slave/test/properties/build_url 02:57:48 INFO - Contents: 02:57:48 INFO - build_url:https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:49 INFO - Running post-action listener: _resource_record_post_action 02:57:49 INFO - Running post-action listener: set_extra_try_arguments 02:57:49 INFO - [mozharness: 2016-12-17 10:57:49.281336Z] Finished download-and-extract step (success) 02:57:49 INFO - [mozharness: 2016-12-17 10:57:49.281476Z] Running populate-webroot step. 02:57:49 INFO - Running pre-action listener: _resource_record_pre_action 02:57:49 INFO - Running main action method: populate_webroot 02:57:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:57:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:57:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:57:49 INFO - '--tppagecycles', 02:57:49 INFO - '7'], 02:57:49 INFO - 'tests': ['tsvgm']}}, 02:57:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:57:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:57:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:57:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:57:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['damp', 'tps']}, 02:57:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'tests': ['damp', 'tps']}, 02:57:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['dromaeo_dom']}, 02:57:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:57:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['basic_compositor_video']}, 02:57:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:57:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other-e10s': {'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['tsvgx', 02:57:49 INFO - 'tsvgr_opacity', 02:57:49 INFO - 'tart', 02:57:49 INFO - 'tscrollx', 02:57:49 INFO - 'cart']}, 02:57:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:57:49 INFO - 'tsvgr_opacity', 02:57:49 INFO - 'tart', 02:57:49 INFO - 'tscrollx', 02:57:49 INFO - 'cart']}, 02:57:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['tp5o']}, 02:57:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'tests': ['tp5o']}, 02:57:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--disable-e10s', 02:57:49 INFO - '--xperf_path', 02:57:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:49 INFO - 'tests': ['tp5n']}, 02:57:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--xperf_path', 02:57:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:49 INFO - 'tests': ['tp5n']}}, 02:57:49 INFO - 'talos.zip': {'path': '', 02:57:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:57:49 INFO - Running post-action listener: _resource_record_post_action 02:57:49 INFO - [mozharness: 2016-12-17 10:57:49.300412Z] Finished populate-webroot step (success) 02:57:49 INFO - [mozharness: 2016-12-17 10:57:49.300539Z] Running create-virtualenv step. 02:57:49 INFO - Running pre-action listener: _resource_record_pre_action 02:57:49 INFO - Running main action method: create_virtualenv 02:57:49 INFO - Creating virtualenv /builds/slave/test/build/venv 02:57:49 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 02:57:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:57:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:57:49 INFO - Using real prefix '/tools/python27' 02:57:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:57:50 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:53 INFO - Installing pip.................done. 02:57:53 INFO - Return code: 0 02:57:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:57:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:57:53 INFO - Reading from file tmpfile_stdout 02:57:53 INFO - Output received: 02:57:53 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) 02:57:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57:53 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')]} 02:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:53 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')]} 02:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:53 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 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:57:53 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 02:57:53 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 02:57:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:57:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:53 INFO - 'HOME': '/Users/cltbld', 02:57:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:53 INFO - 'LOGNAME': 'cltbld', 02:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:53 INFO - 'MOZ_NO_REMOTE': '1', 02:57:53 INFO - 'NO_EM_RESTART': '1', 02:57:53 INFO - 'PAGER': '/bin/cat', 02:57:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:53 INFO - 'PWD': '/builds/slave/test', 02:57:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:53 INFO - 'SHELL': '/bin/bash', 02:57:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:57:53 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:57:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:53 INFO - 'USER': 'cltbld', 02:57:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:53 INFO - 'XPC_FLAGS': '0x0', 02:57:53 INFO - 'XPC_SERVICE_NAME': '0', 02:57:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:53 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 02:57:53 INFO - Cleaning up... 02:57:53 INFO - Return code: 0 02:57:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:57:53 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')]} 02:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:53 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')]} 02:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:53 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 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:57:53 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 02:57:53 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 02:57:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:57:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:53 INFO - 'HOME': '/Users/cltbld', 02:57:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:53 INFO - 'LOGNAME': 'cltbld', 02:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:53 INFO - 'MOZ_NO_REMOTE': '1', 02:57:53 INFO - 'NO_EM_RESTART': '1', 02:57:53 INFO - 'PAGER': '/bin/cat', 02:57:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:53 INFO - 'PWD': '/builds/slave/test', 02:57:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:53 INFO - 'SHELL': '/bin/bash', 02:57:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:57:53 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:57:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:53 INFO - 'USER': 'cltbld', 02:57:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:53 INFO - 'XPC_FLAGS': '0x0', 02:57:53 INFO - 'XPC_SERVICE_NAME': '0', 02:57:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:53 INFO - Downloading/unpacking psutil>=3.1.1 02:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:57:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:57 INFO - Installing collected packages: psutil 02:57:57 INFO - Running setup.py install for psutil 02:57:57 INFO - building 'psutil._psutil_osx' extension 02:57:57 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 02:57:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:57:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:57:58 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 02:57:58 INFO - building 'psutil._psutil_posix' extension 02:57:58 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 02:57:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:57:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:57:58 INFO - ^ 02:57:58 INFO - 1 warning generated. 02:57:58 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 02:57:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:59 INFO - Successfully installed psutil 02:57:59 INFO - Cleaning up... 02:57:59 INFO - Return code: 0 02:57:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:59 INFO - 'HOME': '/Users/cltbld', 02:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:59 INFO - 'LOGNAME': 'cltbld', 02:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:59 INFO - 'MOZ_NO_REMOTE': '1', 02:57:59 INFO - 'NO_EM_RESTART': '1', 02:57:59 INFO - 'PAGER': '/bin/cat', 02:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:59 INFO - 'PWD': '/builds/slave/test', 02:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:59 INFO - 'SHELL': '/bin/bash', 02:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:57:59 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:59 INFO - 'USER': 'cltbld', 02:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:59 INFO - 'XPC_FLAGS': '0x0', 02:57:59 INFO - 'XPC_SERVICE_NAME': '0', 02:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:58:01 INFO - Installing collected packages: mozsystemmonitor 02:58:01 INFO - Running setup.py install for mozsystemmonitor 02:58:02 INFO - Successfully installed mozsystemmonitor 02:58:02 INFO - Cleaning up... 02:58:02 INFO - Return code: 0 02:58:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:02 INFO - 'HOME': '/Users/cltbld', 02:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:02 INFO - 'LOGNAME': 'cltbld', 02:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:02 INFO - 'MOZ_NO_REMOTE': '1', 02:58:02 INFO - 'NO_EM_RESTART': '1', 02:58:02 INFO - 'PAGER': '/bin/cat', 02:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:02 INFO - 'PWD': '/builds/slave/test', 02:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:02 INFO - 'SHELL': '/bin/bash', 02:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:58:02 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:02 INFO - 'USER': 'cltbld', 02:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:02 INFO - 'XPC_FLAGS': '0x0', 02:58:02 INFO - 'XPC_SERVICE_NAME': '0', 02:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:02 INFO - Downloading/unpacking jsonschema==2.5.1 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:58:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:05 INFO - Downloading functools32-3.2.3-2.tar.gz 02:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:58:05 INFO - warning: no files found matching '*.txt' 02:58:05 INFO - no previously-included directories found matching 'build' 02:58:05 INFO - no previously-included directories found matching 'dist' 02:58:05 INFO - no previously-included directories found matching '.git*' 02:58:05 INFO - Installing collected packages: jsonschema, functools32 02:58:05 INFO - Running setup.py install for functools32 02:58:05 INFO - warning: no files found matching '*.txt' 02:58:05 INFO - no previously-included directories found matching 'build' 02:58:05 INFO - no previously-included directories found matching 'dist' 02:58:05 INFO - no previously-included directories found matching '.git*' 02:58:05 INFO - Successfully installed jsonschema functools32 02:58:05 INFO - Cleaning up... 02:58:05 INFO - Return code: 0 02:58:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:58:05 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')]} 02:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:05 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')]} 02:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:05 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 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:58:05 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 02:58:05 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 02:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:05 INFO - 'HOME': '/Users/cltbld', 02:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:05 INFO - 'LOGNAME': 'cltbld', 02:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:05 INFO - 'MOZ_NO_REMOTE': '1', 02:58:05 INFO - 'NO_EM_RESTART': '1', 02:58:05 INFO - 'PAGER': '/bin/cat', 02:58:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:05 INFO - 'PWD': '/builds/slave/test', 02:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:05 INFO - 'SHELL': '/bin/bash', 02:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:58:05 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:05 INFO - 'USER': 'cltbld', 02:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:05 INFO - 'XPC_FLAGS': '0x0', 02:58:05 INFO - 'XPC_SERVICE_NAME': '0', 02:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:58:06 INFO - Cleaning up... 02:58:06 INFO - Return code: 0 02:58:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:58:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:58:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:58:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:06 INFO - 'HOME': '/Users/cltbld', 02:58:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:06 INFO - 'LOGNAME': 'cltbld', 02:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:06 INFO - 'MOZ_NO_REMOTE': '1', 02:58:06 INFO - 'NO_EM_RESTART': '1', 02:58:06 INFO - 'PAGER': '/bin/cat', 02:58:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:06 INFO - 'PWD': '/builds/slave/test', 02:58:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:06 INFO - 'SHELL': '/bin/bash', 02:58:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:58:06 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:58:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:06 INFO - 'USER': 'cltbld', 02:58:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:06 INFO - 'XPC_FLAGS': '0x0', 02:58:06 INFO - 'XPC_SERVICE_NAME': '0', 02:58:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:06 INFO - Downloading/unpacking blobuploader==1.2.4 02:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:08 INFO - Downloading blobuploader-1.2.4.tar.gz 02:58:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:58:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:58:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:10 INFO - Downloading docopt-0.6.1.tar.gz 02:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:58:10 INFO - Installing collected packages: blobuploader, requests, docopt 02:58:10 INFO - Running setup.py install for blobuploader 02:58:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:58:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:58:10 INFO - Running setup.py install for requests 02:58:11 INFO - Running setup.py install for docopt 02:58:11 INFO - Successfully installed blobuploader requests docopt 02:58:11 INFO - Cleaning up... 02:58:11 INFO - Return code: 0 02:58:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:58:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:58:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:58:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:58:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:11 INFO - 'HOME': '/Users/cltbld', 02:58:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:11 INFO - 'LOGNAME': 'cltbld', 02:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:11 INFO - 'MOZ_NO_REMOTE': '1', 02:58:11 INFO - 'NO_EM_RESTART': '1', 02:58:11 INFO - 'PAGER': '/bin/cat', 02:58:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:11 INFO - 'PWD': '/builds/slave/test', 02:58:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:11 INFO - 'SHELL': '/bin/bash', 02:58:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:58:11 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:58:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:11 INFO - 'USER': 'cltbld', 02:58:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:11 INFO - 'XPC_FLAGS': '0x0', 02:58:11 INFO - 'XPC_SERVICE_NAME': '0', 02:58:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:58:11 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-5p3RMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:58:11 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-BRbsVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:58:11 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-UOrVo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:58:11 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-WOfY9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:58:11 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-UPDLox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-WwwHip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-JsnnNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-6pUxxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-ojNH67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-ag8iZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-KA7MeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-kNiDA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-MRWW3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-MMkw0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:12 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-vVp1LA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:58:13 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-PFEgDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:58:13 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-AjbHvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:58:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:58:13 INFO - Running setup.py install for manifestparser 02:58:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:58:13 INFO - Running setup.py install for mozcrash 02:58:13 INFO - Running setup.py install for mozdebug 02:58:13 INFO - Running setup.py install for mozdevice 02:58:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:58:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:58:13 INFO - Running setup.py install for mozfile 02:58:14 INFO - Running setup.py install for mozhttpd 02:58:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for mozinfo 02:58:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for mozInstall 02:58:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for mozleak 02:58:14 INFO - Running setup.py install for mozlog 02:58:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for moznetwork 02:58:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for mozprocess 02:58:15 INFO - Running setup.py install for mozprofile 02:58:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Running setup.py install for mozrunner 02:58:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Running setup.py install for mozscreenshot 02:58:15 INFO - Running setup.py install for moztest 02:58:15 INFO - Running setup.py install for mozversion 02:58:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:58:15 INFO - Cleaning up... 02:58:15 INFO - Return code: 0 02:58:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58: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')]} 02:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:15 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 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:58:15 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 02:58:15 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 02:58:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:58:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:15 INFO - 'HOME': '/Users/cltbld', 02:58:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:15 INFO - 'LOGNAME': 'cltbld', 02:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:15 INFO - 'MOZ_NO_REMOTE': '1', 02:58:15 INFO - 'NO_EM_RESTART': '1', 02:58:15 INFO - 'PAGER': '/bin/cat', 02:58:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:15 INFO - 'PWD': '/builds/slave/test', 02:58:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:15 INFO - 'SHELL': '/bin/bash', 02:58:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:58:15 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:58:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:15 INFO - 'USER': 'cltbld', 02:58:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:15 INFO - 'XPC_FLAGS': '0x0', 02:58:15 INFO - 'XPC_SERVICE_NAME': '0', 02:58:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-CwIVd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-AzVbyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-w4hegf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-Ioz0sU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-kWdSI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-xTXjLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-tSrkdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-PdrLYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:58:16 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-NmECIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:58:17 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-OgyQ5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:58:17 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-D4KP4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:58:17 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-1qCb2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:58:17 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-TFrP17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:58:17 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-CLQBjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:17 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-Lv7s5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:58:17 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-799fm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:58:17 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-Qh6GII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:58:17 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)) 02:58:17 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)) 02:58:17 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)) 02:58:17 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)) 02:58:17 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)) 02:58:17 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)) 02:58:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:58:17 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 02:58:17 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 02:58:17 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 02:58:17 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 02:58:17 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 02:58:17 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 02:58:20 INFO - Downloading blessings-1.6.tar.gz 02:58:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:58:20 INFO - Installing collected packages: blessings 02:58:20 INFO - Running setup.py install for blessings 02:58:20 INFO - Successfully installed blessings 02:58:20 INFO - Cleaning up... 02:58:20 INFO - Return code: 0 02:58:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:58:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:58:21 INFO - Reading from file tmpfile_stdout 02:58:21 INFO - Current package versions: 02:58:21 INFO - blessings == 1.6 02:58:21 INFO - blobuploader == 1.2.4 02:58:21 INFO - docopt == 0.6.1 02:58:21 INFO - functools32 == 3.2.3-2 02:58:21 INFO - jsonschema == 2.5.1 02:58:21 INFO - manifestparser == 1.1 02:58:21 INFO - mozInstall == 1.12 02:58:21 INFO - mozcrash == 1.0 02:58:21 INFO - mozdebug == 0.1 02:58:21 INFO - mozdevice == 0.48 02:58:21 INFO - mozfile == 1.2 02:58:21 INFO - mozhttpd == 0.7 02:58:21 INFO - mozinfo == 0.9 02:58:21 INFO - mozleak == 0.1 02:58:21 INFO - mozlog == 3.4 02:58:21 INFO - moznetwork == 0.27 02:58:21 INFO - mozprocess == 0.23 02:58:21 INFO - mozprofile == 0.28 02:58:21 INFO - mozrunner == 6.13 02:58:21 INFO - mozscreenshot == 0.1 02:58:21 INFO - mozsystemmonitor == 0.3 02:58:21 INFO - moztest == 0.7 02:58:21 INFO - mozversion == 1.4 02:58:21 INFO - psutil == 3.1.1 02:58:21 INFO - requests == 1.2.3 02:58:21 INFO - wsgiref == 0.1.2 02:58:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:21 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 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:58:21 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 02:58:21 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 02:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:21 INFO - 'HOME': '/Users/cltbld', 02:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:21 INFO - 'LOGNAME': 'cltbld', 02:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:21 INFO - 'MOZ_NO_REMOTE': '1', 02:58:21 INFO - 'NO_EM_RESTART': '1', 02:58:21 INFO - 'PAGER': '/bin/cat', 02:58:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:21 INFO - 'PWD': '/builds/slave/test', 02:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:21 INFO - 'SHELL': '/bin/bash', 02:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:58:21 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:21 INFO - 'USER': 'cltbld', 02:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:21 INFO - 'XPC_FLAGS': '0x0', 02:58:21 INFO - 'XPC_SERVICE_NAME': '0', 02:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 INFO - Cleaning up... 02:58:21 INFO - Return code: 0 02:58:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:21 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 0x10c230490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23d9bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, '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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 02:58:21 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 02:58:21 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 02:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:21 INFO - 'HOME': '/Users/cltbld', 02:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:21 INFO - 'LOGNAME': 'cltbld', 02:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:21 INFO - 'MOZ_NO_REMOTE': '1', 02:58:21 INFO - 'NO_EM_RESTART': '1', 02:58:21 INFO - 'PAGER': '/bin/cat', 02:58:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:21 INFO - 'PWD': '/builds/slave/test', 02:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:21 INFO - 'SHELL': '/bin/bash', 02:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:58:21 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:21 INFO - 'USER': 'cltbld', 02:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:21 INFO - 'XPC_FLAGS': '0x0', 02:58:21 INFO - 'XPC_SERVICE_NAME': '0', 02:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:58:21 INFO - Cleaning up... 02:58:21 INFO - Return code: 0 02:58:21 INFO - Running post-action listener: _resource_record_post_action 02:58:21 INFO - Running post-action listener: _start_resource_monitoring 02:58:21 INFO - Starting resource monitoring. 02:58:21 INFO - [mozharness: 2016-12-17 10:58:21.746597Z] Finished create-virtualenv step (success) 02:58:21 INFO - [mozharness: 2016-12-17 10:58:21.747601Z] Running install step. 02:58:21 INFO - Running pre-action listener: _resource_record_pre_action 02:58:21 INFO - Running main action method: install 02:58:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:58:22 INFO - Reading from file tmpfile_stdout 02:58:22 INFO - Detecting whether we're running mozinstall >=1.0... 02:58:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:58:22 INFO - Reading from file tmpfile_stdout 02:58:22 INFO - Output received: 02:58:22 INFO - Usage: mozinstall [options] installer 02:58:22 INFO - Options: 02:58:22 INFO - -h, --help show this help message and exit 02:58:22 INFO - -d DEST, --destination=DEST 02:58:22 INFO - Directory to install application into. [default: 02:58:22 INFO - "/builds/slave/test"] 02:58:22 INFO - --app=APP Application being installed. [default: firefox] 02:58:22 INFO - mkdir: /builds/slave/test/build/application 02:58:22 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'] 02:58:22 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 02:58:53 INFO - Reading from file tmpfile_stdout 02:58:53 INFO - Output received: 02:58:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:58:53 INFO - Running post-action listener: _resource_record_post_action 02:58:53 INFO - [mozharness: 2016-12-17 10:58:53.259017Z] Finished install step (success) 02:58:53 INFO - [mozharness: 2016-12-17 10:58:53.259174Z] Running run-tests step. 02:58:53 INFO - Running pre-action listener: _resource_record_pre_action 02:58:53 INFO - Running main action method: run_tests 02:58:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:58:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:58:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:58:53 INFO - 2016-12-17 02:58:53.273 screenresolution[1641:11720] starting screenresolution argv=screenresolution get 02:58:53 INFO - 2016-12-17 02:58:53.276 screenresolution[1641:11720] Display 0: 1600x1200x32@60 02:58:53 INFO - 2016-12-17 02:58:53.282 screenresolution[1642:11723] starting screenresolution argv=screenresolution list 02:58:53 INFO - Available Modes on Display 0 02:58:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:58:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:58:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:58:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:58:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:58:53 INFO - Intel Iris: 02:58:53 INFO - Chipset Model: Intel Iris 02:58:53 INFO - Type: GPU 02:58:53 INFO - Bus: Built-In 02:58:53 INFO - VRAM (Dynamic, Max): 1536 MB 02:58:53 INFO - Vendor: Intel (0x8086) 02:58:53 INFO - Device ID: 0x0a2e 02:58:53 INFO - Revision ID: 0x0009 02:58:53 INFO - Displays: 02:58:53 INFO - Display: 02:58:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:58:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:58:53 INFO - Display Serial Number: 1600x1200 60 02:58:53 INFO - Main Display: Yes 02:58:53 INFO - Mirror: Off 02:58:53 INFO - Online: Yes 02:58:53 INFO - Rotation: Supported 02:58:53 INFO - Return code: 0 02:58:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:58:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:58:53 INFO - Python 2.7.3 02:58:53 INFO - Return code: 0 02:58:53 INFO - grabbing minidump binary from tooltool 02:58:53 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')]} 02:58:53 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 0x10c044d30>, '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 0x10beaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c03cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:58:53 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 02:58:53 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 02:58:53 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 02:58:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:58:53 INFO - Return code: 0 02:58:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:58:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:58:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:58:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:58:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:58:53 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/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0208', '--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 02:58:53 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/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0208 --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 02:58:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtbRqc9ZES/Render', 02:58:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:53 INFO - 'HOME': '/Users/cltbld', 02:58:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:53 INFO - 'LOGNAME': 'cltbld', 02:58:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:53 INFO - 'MOZ_NO_REMOTE': '1', 02:58:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:53 INFO - 'NO_EM_RESTART': '1', 02:58:53 INFO - 'PAGER': '/bin/cat', 02:58:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:53 INFO - 'PWD': '/builds/slave/test', 02:58:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:53 INFO - 'SHELL': '/bin/bash', 02:58:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4ceNuXEjmM/Listeners', 02:58:53 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 02:58:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:53 INFO - 'USER': 'cltbld', 02:58:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:53 INFO - 'XPC_FLAGS': '0x0', 02:58:53 INFO - 'XPC_SERVICE_NAME': '0', 02:58:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:53 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/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0208', '--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 02:58:53 INFO - mozversion application_buildid: 20161217004004 02:58:53 INFO - mozversion application_changeset: 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 02:58:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:58:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:58:53 INFO - mozversion application_name: Firefox 02:58:53 INFO - mozversion application_remotingname: firefox-dev 02:58:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:53 INFO - mozversion application_vendor: Mozilla 02:58:53 INFO - mozversion application_version: 52.0a2 02:58:53 INFO - mozversion platform_buildid: 20161217004004 02:58:53 INFO - mozversion platform_changeset: 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 02:58:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:53 INFO - mozversion platform_version: 52.0a2 02:58:53 INFO - using testdate: 1481972333 02:58:53 INFO - actual date: 1481972333 02:58:53 INFO - starting webserver on 'localhost:49251' 02:58:53 INFO - SUITE-START | Running 2 tests 02:58:53 INFO - TEST-START | dromaeo_css 02:58:53 INFO - operating with platform_type : mac_ 02:58:53 INFO - Initialising browser for dromaeo_css test... 02:58:53 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpE2_2qG/profile http://localhost:49251/getInfo.html) 02:58:56 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 02:58:56 INFO - PROCESS | 1652 | colorDepth:24 02:58:56 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 02:58:56 INFO - PROCESS | 1652 | __metrics 02:58:56 INFO - PROCESS | 1652 | 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" 02:58:56 INFO - PROCESS | 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:58:57 INFO - TEST-INFO | 1652: exit 0 02:58:57 INFO - Browser initialized. 02:58:57 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:57 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpE2_2qG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:59:09 INFO - PROCESS | 1656 | RSS: Main: 222584832 02:59:09 INFO - PROCESS | 1656 | 03:00:00 INFO - PROCESS | 1656 | 1481972400606 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:02:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 03:02:17 INFO - PROCESS | 1656 | RSS: Main: 357691392 03:02:17 INFO - PROCESS | 1656 | 03:05:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 03:05:20 INFO - PROCESS | 1656 | RSS: Main: 348590080 03:05:20 INFO - PROCESS | 1656 | 03:08:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 03:08:23 INFO - PROCESS | 1656 | RSS: Main: 321716224 03:08:23 INFO - PROCESS | 1656 | 03:11:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 03:11:26 INFO - PROCESS | 1656 | RSS: Main: 328339456 03:11:26 INFO - PROCESS | 1656 | 03:14:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 03:14:30 INFO - PROCESS | 1656 | RSS: Main: 311029760 03:14:30 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 03:17:33 INFO - PROCESS | 1656 | RSS: Main: 388952064 03:17:33 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | __start_tp_report 03:17:33 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 03:17:33 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 03:17:33 INFO - PROCESS | 1656 | |i|pagename|runs| 03:17:33 INFO - PROCESS | 1656 | |0;dojo.html;3323.20243141646;3341.8997430077097;3362.630110687824;3375.9493607595873;3384.6446123157066;5657.51911087557;5682.971585142073;5739.942600573998;5755.827325180241;5775.797847075359;5483.479069488402;5512.283403157594;5521.365043020054;5526.668399895999;5533.08704063829;5002.0246052019875;5027.472115427882;5040.269160971657;5086.186210206368;5086.262491938666;5125.897482050356;5134.2812006319145;5168;5178.7928482860825;5180.430152683201;5176.249443830642;5226.24219488174;5237.973810130944;5239.921401178985;5257.211418287268;5219.399769026553;5243.79024839006;5286.101362768339;5296.894062118736;5303.852009592706;5062.291279220912;5160.380754309469;5243.947560524384;5244.632875698702;5253.159494480865;4933.840547471342;5521.917171242435;5582.218489411485;5615.887682246373;5637.408072152447;6189.38106189382;6290.339514351019;6303.2622671411;6303.779367722153;6309.495240380758;5298.602604804617;6293.937060629427;6351.80944571659;6354.046892966046;6370.490360771174;5214.452482489284;5464.23500709901;5785.623934444247;5807.4482924122985;5858.8535286617325;6276.560640755103;6316.242050953915;6320.810375688737;6330.0821380899515;6336.239651241788;6226;6229.0967809668255;6235.594686345373;6239.781607643801;6243.7814676486105;6071.241094863142;6203.2556093269095;6206.689665516707;6317.210348706411;6339.619622822645;6158.291796443376;6214.285357183891;6221.813345599639;6293.37066293367;6297.6536290503545;6238.594491358047;6288.811335659846;6300.306966233802;6310.842228944313;6311.684415779193;4857.368542089505;4916;4969.602431805519;4985.800567977313;5004.224345226496;4400.427944367212;4483.215437298525;4483.820647174077;4507.3689683444345;4507.977460112678;5722.37053924068;5729.369769325371;5730.770769169187;5743.052396354555;5748.741306641211;6223.564350495512;6236.6258024518675;6250.249970003629;6253.749850005949;6256.812295631138;5669.574781891375;5727.599068065196;5738.483536481736;5741.598088133791;5753.654780713171;5395.7841686332895;5415.241866138744;5415.566754659618;5417.404085550586;5419.187121931742;6223.719932603044;6283.96858015707;6284.151639528696;6291.496680265568;6298.905516417349;6311.652859092794;6323.905141422791;6326.683665816783;6330.240371155491;6338.366163383625;6187.381261873777;6308.621482711052;6308.621482711052;6309.3690630936535;6311.621302721851;6035.728392222272;6086.452219300284;6102.877942441215;6140.289530736458;6245.094461303176;6252.406021427957;6319.747210111544;6323.715432805443;6340.651264180515;6342.112104305309;6050.788222412194;6186.917289474414;6189.535784816067;6197.721102550487;6212.4408803207925;5940.732667029907;5970.134330522137;6041.909371359346;6175.752969881154;6192.783252586138;5922.73347699346;5933.317668468181;5936.970315148396;5969.074793406855;6079.3008803988105;6162.754832996664;6163.260408750799;6172.845678858064;6180;6181.381861813783;5975.55183361241;6013.849653758691;6026.186464827368;6027.842273619539;6055.818325450244 03:17:33 INFO - PROCESS | 1656 | |1;ext.html;470.5623769893997;505.05806670559406;510.1531457780086;546.229815959497;564.2226984997261;24145;31158.532622010687;31161.065168044937;31199.376012479737;38671.806640966824;39101;41034;41205.17589648213;41237;41253.17493650125;9329.860052099208;9504;9516.80966380672;9520.619175232983;9541.284403669717;9339.252859771235;9399.154076133147;9463.952680236624;9482.620695172185;9506.334556581041;9417.48203848788;9464.290178236626;9472.147506724394;9485.620575176985;9493.908200556954;9396.201322887562;9431.811363772755;9443.952780236124;9450.810983780286;9486.715398538056;9387;9468.479233642147;9472.621095156186;9480.478573678445;9486.525673716287;38427.23145537073;38564.421533677014;38597.42103868444;38613.227735445136;38722;3178.618565772099;3192.4892017277125;3231.2568109334984;3287;3623.3115708015393;28647.856760716273;29009.984650537343;29070.563941540895;29378.412431751458;30178.79284828604;3046.6496352919476;3092.1187461573345;3111.673274306188;3142.7429028388597;3146.889858854952;483244;484578;487541;487925;488553.5572322151;32654.020379388658;33568.16079598029;58577.70711146417;59008.409915900294;59033.70483147557;34296;34638.82680586581;34668.47997280043;34730.305393891984;34768.82615586957;12280.75438491243;13815.894728421703;14193.148411095368;14204.64488387778;14220.928895355664;11941.76116477683;12010.699732506582;12035.638930832089;12036.759264814655;12047.698807529707;7713.730019449405;7735.613219339123;7782.027246594176;7808.804779880548;7822.295993360625;3235.2720637856296;3245.6754324567355;3248.3340915112344;3256.5115232714807;3282.556854824616;920.2867777472378;927.2767241551601;951.2912879904377;1050.7898420315967;1112.6495154026456;28378.43243135126;28826.855865720536;28944.855275723487;29442.263943401587;29693.554596681068;1033.7002269341956;1102.1669977150254;1110.9333439993627;1121.6410748560545;1177.5407591039502;14696.926515367355;15206.923965380101;15249.923750381398;15512.612184695472;15682.607934801721;634239;843909;884641;884708;885618;42610.147797044505;56536;56551.71724141353;56621.71689141528;56681;1242.658268976039;1275.171138759795;1359.415451355887;1379.5102738527878;1407.5003373802358;27206.727932721213;29019.85490072536;29160.27099322534;29812.850935745184;29813.850930745208;47231.29153062776;47818.760906195246;47836.5216347846;47922;47973.520264796905;552346;559457;559659;560493;560565;22945.31164065081;23385.298441046794;25382.8577714007;25978.090766823072;26333.604995924692;37750.62249377471;42487.362689559064;42516.149677006906;42858.57141428671;42994.140117196854;36103;36440.635593643725;36539.45190822193;37951.62048379481;37973.620263797005;35699.64300356963;35810.10474738153;36495.45256821203;36549.81725091464;36791.44812827863;34550.7907223246;35090;35178.64821351754;35475.64524354723;35843.28313433665;27676.861615691792;31204.21989450191;31459.37081258316;33321;33989.640414383146;20694.379168624964;24678.50642987166;24903.377415564755;25260.368490787878;25276.87361563254 03:17:33 INFO - PROCESS | 1656 | |2;jquery.html;354.8704722776188;371.98326075326605;376.5124164207351;385.0873429970968;401.55427475502205;14624.780628290584;14964.476243331486;19137.03375976724;20658;20850.165993360286;20333.28833490828;20512.974351282413;20528.794712052873;20537;20796.376108716726;4952.554270115689;6192.071189321593;6195.659238741868;6204.13142160098;6205.03821907605;5689.886202275952;5963.522918166558;5976.910346344808;6161.938380616204;6168.475679567242;6086.417531441835;6088.238970128734;6092.817215483543;6098.634081955097;6107.236595425572;6015.27816661999;6090.908636370458;6523.575967562093;6587.934120658828;6603.009548567706;5598.104303311491;5803.65178089316;5829.766809327664;5831.154482600042;5849.736761845685;13080.28058456794;13301.86698133016;23006.283711839034;24600.293967663543;24927.750722492725;2143.19630138698;2227.732672079345;2274.9090036398525;2310.3992961829877;2345.7419683834764;8150.959245203795;9849.50752462374;9861.950690246575;10334.069933705927;10475.94762026185;1825.4341154242227;1830.9472053569118;1852.7961924188328;1875.3436297295973;1917.980820191808;115605;115675;121086.39456802748;125913.37043314817;126124;10756.946215268874;11164.888351116384;11223.60717374888;11611.593594224161;11618.94190529042;10775.892241077489;12706.936465317614;13469.326533673277;13475.460981560824;14281.071730337495;8194.631241594143;8247.95876020616;8353.54055526952;8426.07313195548;8514.276286515591;8155.225253600898;8174.877376839352;8181.222783835526;8307.169283071766;8318.500889946623;4152.356384760367;4392.2752745797225;4405;4419.779011049434;4561.635069194456;1623.3344328825126;1831.505493516743;1904.8476121910214;1912.0363436673101;1914.1769039313228;867.3061550759368;902.5351943748974;924.5977999570208;994.7485606890858;1037.2065369991963;16013.839861601235;19173.808261917482;20222.393328200178;20231.393058208276;20477.692834607493;929.6188562689265;930.06993006993;937.8827646544182;950.9619615215314;985.5762022330467;10287.537060832281;10709.303895246783;10825.296355736851;10832.945835270773;10867.945660271647;534173;550537;559013;560395;564951;125462;128669;133346.24399157852;133576;133581;916.8533034714548;922.7462447826902;943.7640589852537;945.5083356654501;948.1940350701987;17990.730139048188;18170.90914545419;18326.816731833045;18446.169922353794;18541.98019108962;40884.79557602193;53871.46128538664;54049;54926.52830622711;55425.614359639716;65217;72531;77577;77983;78530.6073469629;6961.990511375923;8045.879311810445;8121.675132994614;8193.590320483832;8326.12575679569;9503.762405939908;12542.686432839251;12640.494380224687;12669.113162078565;12865.397688647194;9331.673391431268;10405.115565177044;10684.679459616224;10905.672829815117;11223.719407014563;10877.728056798644;10895.945520272666;10974.176936729937;11247.156463265124;11256.017155453976;9192.724218273463;9326.580303886478;10754.892451075702;11491.597794077166;11535.423228838357;7944.801379965547;7996.960015199887;8193.836123277382;8238.052623948082;8429.367797415342;8175.836483270183;10821;10921.726956826144;11242.775144497227;11303.03924166455 03:17:33 INFO - PROCESS | 1656 | |3;mootools.html;1270.656922630889;1302.1405872124394;1409.8801601863843;1442.531177367356;1595.4814685227298;7272.418206543477;8017.238362355574;8233.547154906486;8237.711680091197;8241.876371854427;7396.371308438789;7720.112187098485;8061.516309021448;8153.91846081539;8180.672773089069;2273.772622737725;2309.3533810533077;2309.7574754650773;2310.445493081657;2314.780095890896;2293.312006398074;2327.2669109230624;2337.4273303040777;2340.1458467659354;2340.8595484270913;2266.252136794862;2270.761570035147;2298.138198175684;2299.0688771047753;2299.356180269527;2185.311626837549;2186.191109289557;2223.8220942324574;2232.5423288225875;2232.9218477353297;2286.211257116309;2309.711286089239;2310.8151347892126;2311.9653205201935;2317.1079134533156;7967.402444816604;8099.919000809976;8109.351251899834;8173.100958894517;8185.181481851827;4977.377827771528;5166.04428180784;5166.819161329373;5167.431582525919;5174.172132458789;2919.343147791751;3168.8890888818783;3329.1548261757466;3415.351083294166;3499.5450591423205;1003.2776400991347;1006.5369929832286;1014.7513859104492;1040.4121671255716;1043.3896170740127;10553.155747540179;10608.469576521144;10700.839487407695;10706.732331691692;10734.946325268402;4222.514410842731;4364.585364390377;4442.089371678798;4444.422225110715;4448.777561121931;8546.102659220693;8556.315494760403;8558.743237702878;8562.828743425096;8582.785430364165;5051.848444546669;5059.949400506021;5061.44324124346;5063.139266324733;5063.316452278968;4008.779517126586;4241.321388577812;4366.497852746911;4405.559444055569;4661.324108004321;3029.5304227844717;3592.8383222755037;3686.0047787097333;3697.574778900461;3699.2416554606243;1135.9488823002985;1137.590467431724;1140.2303445174473;1147.3574798113084;1148.62095508482;819.6434550970347;879.8284334554701;896.4014605075919;910.5492781073411;927.8608208768738;5210.76551555181;5384.138537833927;5652.943470565324;5665.923474539906;5714.314282286152;892.4600616626977;931.7654108306521;959.827231098395;1007.1992765751245;1023.8668973033458;3303.339332133583;3686.060054686038;3782.7541209821275;3848.634379733977;3903.902402439962;9527.09492598202;9955.751106222402;9990.300678952404;10006.499675016366;10039.69880903574;9431.245500360092;9889.20886329092;9898.901010989943;9902.455364954852;9920.15678667307;7622.504537205064;7678.846423071507;7688.76933691979;7816.648250828726;7819.687212511663;5442.972785136049;5537.45277172489;5545.390007099135;5717;5809.244798176379;9196.54017299119;9348.626054957998;9373.578188981648;9380.296477764332;9422.104900034486;9231.030741772018;9405.012473690163;9432.811343773223;9476.241900648069;9541.332106752461;7092.822679433056;7130.251323610946;7148.928510714826;7164.749233777001;7234.710611575689;7694.576798275924;7783.571903545359;7792.805179870548;7797.883031754414;7803.453758236869;6979.546329488567;7103.467239957127;7134.2152363238965;7163.820904477429;7243.5653860768525;6823.556468829709;6919.965400172967;6979.644781480864;7031;7151.2848715128075;6533.803985880431;6553.606783593;6557.96721016411;6646.0695502628705;6666.733330666719;4853.320535125156;4873.074115917964;4876.658633895593;4886.804527818847;4918.2376731606655;4795.9280610790165;4799.832005879777;4820.734859582757;4986.202207646759;4992.051510213048 03:17:33 INFO - PROCESS | 1656 | |4;prototype.html;263.70069970583387;281.76331881219795;303.4674146872239;307.6615722705024;311.81447039011795;4560.7913902815735;4817.662763606549;4836;5114.539691427771;5143.511366420194;4812.831550895719;5046.293518907356;5082.720450375237;5084.669496482726;5089.949100508994;3578.425492783167;3749.137698329386;3883.6893048556117;3909.6676782473523;3945.487086678728;3087.166465054434;3740.532433445819;3878.8836334909997;3903.765774053566;3911.5110611173604;3859.205469456702;3861.5945325740813;3864.7294689371756;3877.332746918824;3886.747361421513;3226.838658067111;3253.6258330291967;3840.8847734568008;3890.0663840678317;3890.533136023695;2842.9431411371656;3362.4283871741864;3373.578302712161;3381.7970921744773;3396.8471418785966;4487.394201782783;4688.546550569335;4930.767308172957;4971.254311853215;5007.599392048627;3822.7132965027736;4120.402541631447;4195.538490766014;4322.524522302544;4394.6923715339935;4534.59188673021;4673.439187297512;5113.360829896263;5295.338082739658;5380.166074258496;3579.2360586668765;3642.5993140754495;3705.370087085202;3709.7403181777286;3716.7920425861703;5581.6371935824045;5586.301712285965;5590.580706447033;5614.9438505615235;5647.265855438849;3223.066160303799;3718.200586873835;3730;3735.7198210133806;3756.9060773480883;3032.4844776388068;3523.3129539740016;3772.4529943158086;3808.9047773805323;3889.3776995680555;4153.9626551882375;4195.119025004783;4363.716358436692;4402.823887044547;4408.845690400821;4742.383490146259;4816.4220293564995;5373.623846330719;5411.404745477994;5418.593605479605;1223.7797196504537;1224.7305592769576;1229.956951506693;1236.4667811913164;1239.9566015189425;779.2441331908041;839.6809212499211;840.6637345062024;846.627483907079;850.2602735620051;3796.487474191004;3953.683705303569;4696.577308042224;4704.317873908332;4745.881352966204;4413.249747542925;5098.566621837112;5167.328247327823;5185.611079169077;5198.506141916512;3776.86780962665;4699.295105734167;4712.929306060343;4744.312074749211;4766.499517550607;2554.3486410965083;2657.282533715886;2685.5703087505904;2717.7282271772665;2761.3628984319926;4697.577218050392;5057.089723849743;5170.767315470813;5298.443663415286;5396.19057141424;4380.479309730617;4884.902301954012;5165.199394093846;5234.502722241381;5355.607542039098;4907.080287229093;5315.38553783431;5360.383555891122;5380.175407910878;5426.620136590401;4292.399064130962;5156.375741641521;5171.81898633546;5181.792728290826;5214.800595862896;4759.738214398241;4829.5653391194965;5124.820631278037;5129.410117836496;5540.944590554204;5460.972695136499;5703.69000554776;5706.629069110494;5729.320080078601;5788.739506722123;3414.309916591303;3490.5740510345067;3566.946495802617;3763.5107436034236;3769.3791669582342;5371.158673930041;5517.144842549411;5570.944290557205;5615.820677657738;5616.663000219836;4362.672799539983;4825.879353016203;4998.3002379667605;5461.508464238238;5477.342718873601;4833.709977401367;4906.650671065486;5361.37292726569;5364.6512976656395;5390.784368625211;4725.9366642505165;5064.164412871834;5291.7618707159045;5334.813281535127;5390.622656414013;4910.803567857389;4985.886643519038;5289.3652761668845;5418.810341638024;5459.235707001102;4914.9017019658695;5003.648285305901;5338.679679219259;5345.545628621455;5374.838754837361 03:17:33 INFO - PROCESS | 1656 | |5;yui.html;684.900689400037;737.8044818123199;750.2722359311466;776.5545968461765;791.5329955326358;14213.64465888352;14686.045407048548;16088.678226435464;16632.417865374715;16660.58348541287;14206.857931420684;14253.429862805502;14281.50014749484;14299.57101286963;14366.20985845778;647.6437959122487;674.5278305186389;800.9639566219535;826.2646244842095;830.4436027861348;671.543350521645;796.4424902568196;811.4401063266357;827.896512935883;828.4987582512583;860.9139086091373;863.9697610583631;864.6973559254274;866.2723312417568;866.7703058689452;646.8803271394808;859.5014891363026;859.7635650196215;860.724568138196;861.9870701939476;514.7580637100557;623.4731651754261;644.255884453457;647.7635662983024;648.0797267879575;14445.78331325031;14491.275436228147;14496.492622758258;14511.564653060424;14520.709585808225;12569.874301256961;12677.74644507114;12735.363231838372;12828.03789715775;12889.839914407748;12107.952572307404;12187;12291.93854030733;12310.26138431697;12325.137240393176;4171.833126674929;4202.201581699467;4263.424437700902;4288.270993931039;4404.7357158570585;66945;81957.59021204856;84614;84909.15090849074;85092.57453712691;9775.31572789912;9999.850002249972;10116;10202.28583999113;10330.483475826179;18619;18869.811301886806;18991.1453984571;19033.524161895788;19290;7402.629868506553;7403.407727381797;7435;7474.70101195957;7546.24537546241;4167.916641667211;4182.142660754538;4244.193603215379;4265.936010959838;4406.317020861771;3323.9501407478906;3442.7762195457217;3447.3622379859808;3450.879219227315;3453.2230248193955;632.8069938668658;649.6978904809287;654.4339146638218;663.4062514049898;670.8926571748497;535.6090054260334;579.6406228138582;583.5973178506816;589.8348462430527;651.6481100205931;7835.608219588998;8808.647654093893;9079.727608171765;9166.037566055602;9169.037251088674;632.2413104274889;645.1935081148564;659.350539718377;661.3466334164589;673.8281738156738;4216.388623649615;4447;4453.421055262795;4460.933086003713;4573.176828170961;65902.68194636176;66106.66946665331;66170.00744988733;68995;105570;61057.47356316039;63805.68097159485;65137.674311628136;77093.45813083707;81680.18319816845;85840.99999999875;86903.1309686895;87252;89903;91248.54375728079;8453.915460845314;8607.612657430305;8615.439996400464;8765.34259930495;8839.46963182211;57282.1371602916;57511.1373329392;57847.710761447604;58332;59896;62447;71317.93023104554;77367.61316193383;78096.60951695206;81484.59257703673;9986.851512075913;10294.691159265234;10831.404272765074;11212.04697600713;11362.693290271725;11808.82286765682;11907.642770716893;11947.223430476992;12018.278903265833;12237.20458170216;9853;11334.94332528332;11506.252093613888;11619.941900290445;11921.105917056082;11012.17408694367;11287.492062857027;11495.942520287344;11672.124590655905;12278.324692142018;11755.412229388325;12035.939820300842;12053.879461205275;12308.753824923273;12503.624891253277;10368.844467332845;10480.895191048297;10617.1506279499;10770.946145269223;11233.157513186381;10558.630447934285;10775.568977240822;10823.296485728402;10919.836202457129;11060.281081729661 03:17:33 INFO - PROCESS | 1656 | __end_tp_report 03:17:33 INFO - PROCESS | 1656 | __start_cc_report 03:17:33 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1407 03:17:33 INFO - PROCESS | 1656 | __end_cc_report 03:17:33 INFO - PROCESS | 1656 | __startTimestamp1481973453781__endTimestamp 03:17:33 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | ------- Summary: start ------- 03:17:33 INFO - PROCESS | 1656 | Number of tests: 6 03:17:33 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5744.04 Median:5975.55 stddev:657.83 (11.0%) stddev-sans-first:631.84 03:17:33 INFO - PROCESS | 1656 | Values: 3323.2 3341.9 3362.6 3375.9 3384.6 5657.5 5683.0 5739.9 5755.8 5775.8 5483.5 5512.3 5521.4 5526.7 5533.1 5002.0 5027.5 5040.3 5086.2 5086.3 5125.9 5134.3 5168.0 5178.8 5180.4 5176.2 5226.2 5238.0 5239.9 5257.2 5219.4 5243.8 5286.1 5296.9 5303.9 5062.3 5160.4 5243.9 5244.6 5253.2 4933.8 5521.9 5582.2 5615.9 5637.4 6189.4 6290.3 6303.3 6303.8 6309.5 5298.6 6293.9 6351.8 6354.0 6370.5 5214.5 5464.2 5785.6 5807.4 5858.9 6276.6 6316.2 6320.8 6330.1 6336.2 6226.0 6229.1 6235.6 6239.8 6243.8 6071.2 6203.3 6206.7 6317.2 6339.6 6158.3 6214.3 6221.8 6293.4 6297.7 6238.6 6288.8 6300.3 6310.8 6311.7 4857.4 4916.0 4969.6 4985.8 5004.2 4400.4 4483.2 4483.8 4507.4 4508.0 5722.4 5729.4 5730.8 5743.1 5748.7 6223.6 6236.6 6250.2 6253.7 6256.8 5669.6 5727.6 5738.5 5741.6 5753.7 5395.8 5415.2 5415.6 5417.4 5419.2 6223.7 6284.0 6284.2 6291.5 6298.9 6311.7 6323.9 6326.7 6330.2 6338.4 6187.4 6308.6 6308.6 6309.4 6311.6 6035.7 6086.5 6102.9 6140.3 6245.1 6252.4 6319.7 6323.7 6340.7 6342.1 6050.8 6186.9 6189.5 6197.7 6212.4 5940.7 5970.1 6041.9 6175.8 6192.8 5922.7 5933.3 5937.0 5969.1 6079.3 6162.8 6163.3 6172.8 6180.0 6181.4 5975.6 6013.8 6026.2 6027.8 6055.8 03:17:33 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:72072.22 Median:20031.97 stddev:173600.46 (866.6%) stddev-sans-first:174004.21 03:17:33 INFO - PROCESS | 1656 | Values: 470.6 505.1 510.2 546.2 564.2 24145.0 31158.5 31161.1 31199.4 38671.8 39101.0 41034.0 41205.2 41237.0 41253.2 9329.9 9504.0 9516.8 9520.6 9541.3 9339.3 9399.2 9464.0 9482.6 9506.3 9417.5 9464.3 9472.1 9485.6 9493.9 9396.2 9431.8 9444.0 9450.8 9486.7 9387.0 9468.5 9472.6 9480.5 9486.5 38427.2 38564.4 38597.4 38613.2 38722.0 3178.6 3192.5 3231.3 3287.0 3623.3 28647.9 29010.0 29070.6 29378.4 30178.8 3046.6 3092.1 3111.7 3142.7 3146.9 483244.0 484578.0 487541.0 487925.0 488553.6 32654.0 33568.2 58577.7 59008.4 59033.7 34296.0 34638.8 34668.5 34730.3 34768.8 12280.8 13815.9 14193.1 14204.6 14220.9 11941.8 12010.7 12035.6 12036.8 12047.7 7713.7 7735.6 7782.0 7808.8 7822.3 3235.3 3245.7 3248.3 3256.5 3282.6 920.3 927.3 951.3 1050.8 1112.6 28378.4 28826.9 28944.9 29442.3 29693.6 1033.7 1102.2 1110.9 1121.6 1177.5 14696.9 15206.9 15249.9 15512.6 15682.6 634239.0 843909.0 884641.0 884708.0 885618.0 42610.1 56536.0 56551.7 56621.7 56681.0 1242.7 1275.2 1359.4 1379.5 1407.5 27206.7 29019.9 29160.3 29812.9 29813.9 47231.3 47818.8 47836.5 47922.0 47973.5 552346.0 559457.0 559659.0 560493.0 560565.0 22945.3 23385.3 25382.9 25978.1 26333.6 37750.6 42487.4 42516.1 42858.6 42994.1 36103.0 36440.6 36539.5 37951.6 37973.6 35699.6 35810.1 36495.5 36549.8 36791.4 34550.8 35090.0 35178.6 35475.6 35843.3 27676.9 31204.2 31459.4 33321.0 33989.6 20694.4 24678.5 24903.4 25260.4 25276.9 03:17:33 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:33425.66 Median:380.80 stddev:93075.96 (24442.2%) stddev-sans-first:93303.94 03:17:33 INFO - PROCESS | 1656 | Values: 354.9 372.0 376.5 385.1 401.6 14624.8 14964.5 19137.0 20658.0 20850.2 20333.3 20513.0 20528.8 20537.0 20796.4 4952.6 6192.1 6195.7 6204.1 6205.0 5689.9 5963.5 5976.9 6161.9 6168.5 6086.4 6088.2 6092.8 6098.6 6107.2 6015.3 6090.9 6523.6 6587.9 6603.0 5598.1 5803.7 5829.8 5831.2 5849.7 13080.3 13301.9 23006.3 24600.3 24927.8 2143.2 2227.7 2274.9 2310.4 2345.7 8151.0 9849.5 9862.0 10334.1 10475.9 1825.4 1830.9 1852.8 1875.3 1918.0 115605.0 115675.0 121086.4 125913.4 126124.0 10756.9 11164.9 11223.6 11611.6 11618.9 10775.9 12706.9 13469.3 13475.5 14281.1 8194.6 8248.0 8353.5 8426.1 8514.3 8155.2 8174.9 8181.2 8307.2 8318.5 4152.4 4392.3 4405.0 4419.8 4561.6 1623.3 1831.5 1904.8 1912.0 1914.2 867.3 902.5 924.6 994.7 1037.2 16013.8 19173.8 20222.4 20231.4 20477.7 929.6 930.1 937.9 951.0 985.6 10287.5 10709.3 10825.3 10832.9 10867.9 534173.0 550537.0 559013.0 560395.0 564951.0 125462.0 128669.0 133346.2 133576.0 133581.0 916.9 922.7 943.8 945.5 948.2 17990.7 18170.9 18326.8 18446.2 18542.0 40884.8 53871.5 54049.0 54926.5 55425.6 65217.0 72531.0 77577.0 77983.0 78530.6 6962.0 8045.9 8121.7 8193.6 8326.1 9503.8 12542.7 12640.5 12669.1 12865.4 9331.7 10405.1 10684.7 10905.7 11223.7 10877.7 10895.9 10974.2 11247.2 11256.0 9192.7 9326.6 10754.9 11491.6 11535.4 7944.8 7997.0 8193.8 8238.1 8429.4 8175.8 10821.0 10921.7 11242.8 11303.0 03:17:33 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5339.81 Median:5166.43 stddev:2926.41 (56.6%) stddev-sans-first:2918.63 03:17:33 INFO - PROCESS | 1656 | Values: 1270.7 1302.1 1409.9 1442.5 1595.5 7272.4 8017.2 8233.5 8237.7 8241.9 7396.4 7720.1 8061.5 8153.9 8180.7 2273.8 2309.4 2309.8 2310.4 2314.8 2293.3 2327.3 2337.4 2340.1 2340.9 2266.3 2270.8 2298.1 2299.1 2299.4 2185.3 2186.2 2223.8 2232.5 2232.9 2286.2 2309.7 2310.8 2312.0 2317.1 7967.4 8099.9 8109.4 8173.1 8185.2 4977.4 5166.0 5166.8 5167.4 5174.2 2919.3 3168.9 3329.2 3415.4 3499.5 1003.3 1006.5 1014.8 1040.4 1043.4 10553.2 10608.5 10700.8 10706.7 10734.9 4222.5 4364.6 4442.1 4444.4 4448.8 8546.1 8556.3 8558.7 8562.8 8582.8 5051.8 5059.9 5061.4 5063.1 5063.3 4008.8 4241.3 4366.5 4405.6 4661.3 3029.5 3592.8 3686.0 3697.6 3699.2 1135.9 1137.6 1140.2 1147.4 1148.6 819.6 879.8 896.4 910.5 927.9 5210.8 5384.1 5652.9 5665.9 5714.3 892.5 931.8 959.8 1007.2 1023.9 3303.3 3686.1 3782.8 3848.6 3903.9 9527.1 9955.8 9990.3 10006.5 10039.7 9431.2 9889.2 9898.9 9902.5 9920.2 7622.5 7678.8 7688.8 7816.6 7819.7 5443.0 5537.5 5545.4 5717.0 5809.2 9196.5 9348.6 9373.6 9380.3 9422.1 9231.0 9405.0 9432.8 9476.2 9541.3 7092.8 7130.3 7148.9 7164.7 7234.7 7694.6 7783.6 7792.8 7797.9 7803.5 6979.5 7103.5 7134.2 7163.8 7243.6 6823.6 6920.0 6979.6 7031.0 7151.3 6533.8 6553.6 6558.0 6646.1 6666.7 4853.3 4873.1 4876.7 4886.8 4918.2 4795.9 4799.8 4820.7 4986.2 4992.1 03:17:33 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4255.24 Median:4752.81 stddev:1307.15 (27.5%) stddev-sans-first:1276.02 03:17:33 INFO - PROCESS | 1656 | Values: 263.7 281.8 303.5 307.7 311.8 4560.8 4817.7 4836.0 5114.5 5143.5 4812.8 5046.3 5082.7 5084.7 5089.9 3578.4 3749.1 3883.7 3909.7 3945.5 3087.2 3740.5 3878.9 3903.8 3911.5 3859.2 3861.6 3864.7 3877.3 3886.7 3226.8 3253.6 3840.9 3890.1 3890.5 2842.9 3362.4 3373.6 3381.8 3396.8 4487.4 4688.5 4930.8 4971.3 5007.6 3822.7 4120.4 4195.5 4322.5 4394.7 4534.6 4673.4 5113.4 5295.3 5380.2 3579.2 3642.6 3705.4 3709.7 3716.8 5581.6 5586.3 5590.6 5614.9 5647.3 3223.1 3718.2 3730.0 3735.7 3756.9 3032.5 3523.3 3772.5 3808.9 3889.4 4154.0 4195.1 4363.7 4402.8 4408.8 4742.4 4816.4 5373.6 5411.4 5418.6 1223.8 1224.7 1230.0 1236.5 1240.0 779.2 839.7 840.7 846.6 850.3 3796.5 3953.7 4696.6 4704.3 4745.9 4413.2 5098.6 5167.3 5185.6 5198.5 3776.9 4699.3 4712.9 4744.3 4766.5 2554.3 2657.3 2685.6 2717.7 2761.4 4697.6 5057.1 5170.8 5298.4 5396.2 4380.5 4884.9 5165.2 5234.5 5355.6 4907.1 5315.4 5360.4 5380.2 5426.6 4292.4 5156.4 5171.8 5181.8 5214.8 4759.7 4829.6 5124.8 5129.4 5540.9 5461.0 5703.7 5706.6 5729.3 5788.7 3414.3 3490.6 3566.9 3763.5 3769.4 5371.2 5517.1 5570.9 5615.8 5616.7 4362.7 4825.9 4998.3 5461.5 5477.3 4833.7 4906.7 5361.4 5364.7 5390.8 4725.9 5064.2 5291.8 5334.8 5390.6 4910.8 4985.9 5289.4 5418.8 5459.2 4914.9 5003.6 5338.7 5345.5 5374.8 03:17:33 INFO - PROCESS | 1656 | 03:17:33 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:18637.92 Median:57679.42 stddev:25954.47 (45.0%) stddev-sans-first:25992.27 03:17:33 INFO - PROCESS | 1656 | Values: 684.9 737.8 750.3 776.6 791.5 14213.6 14686.0 16088.7 16632.4 16660.6 14206.9 14253.4 14281.5 14299.6 14366.2 647.6 674.5 801.0 826.3 830.4 671.5 796.4 811.4 827.9 828.5 860.9 864.0 864.7 866.3 866.8 646.9 859.5 859.8 860.7 862.0 514.8 623.5 644.3 647.8 648.1 14445.8 14491.3 14496.5 14511.6 14520.7 12569.9 12677.7 12735.4 12828.0 12889.8 12108.0 12187.0 12291.9 12310.3 12325.1 4171.8 4202.2 4263.4 4288.3 4404.7 66945.0 81957.6 84614.0 84909.2 85092.6 9775.3 9999.9 10116.0 10202.3 10330.5 18619.0 18869.8 18991.1 19033.5 19290.0 7402.6 7403.4 7435.0 7474.7 7546.2 4167.9 4182.1 4244.2 4265.9 4406.3 3324.0 3442.8 3447.4 3450.9 3453.2 632.8 649.7 654.4 663.4 670.9 535.6 579.6 583.6 589.8 651.6 7835.6 8808.6 9079.7 9166.0 9169.0 632.2 645.2 659.4 661.3 673.8 4216.4 4447.0 4453.4 4460.9 4573.2 65902.7 66106.7 66170.0 68995.0 105570.0 61057.5 63805.7 65137.7 77093.5 81680.2 85841.0 86903.1 87252.0 89903.0 91248.5 8453.9 8607.6 8615.4 8765.3 8839.5 57282.1 57511.1 57847.7 58332.0 59896.0 62447.0 71317.9 77367.6 78096.6 81484.6 9986.9 10294.7 10831.4 11212.0 11362.7 11808.8 11907.6 11947.2 12018.3 12237.2 9853.0 11334.9 11506.3 11619.9 11921.1 11012.2 11287.5 11495.9 11672.1 12278.3 11755.4 12035.9 12053.9 12308.8 12503.6 10368.8 10480.9 10617.2 10770.9 11233.2 10558.6 10775.6 10823.3 10919.8 11060.3 03:17:33 INFO - PROCESS | 1656 | -------- Summary: end -------- 03:17:33 INFO - PROCESS | 1656 | 03:17:34 INFO - TEST-INFO | 1656: exit 0 03:17:34 INFO - TEST-OK | dromaeo_css | took 1120556ms 03:17:34 INFO - TEST-START | kraken 03:17:34 INFO - operating with platform_type : mac_ 03:17:34 INFO - Initialising browser for kraken test... 03:17:34 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpBeKvxp/profile http://localhost:49251/getInfo.html) 03:17:37 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 03:17:37 INFO - PROCESS | 1662 | colorDepth:24 03:17:37 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 03:17:37 INFO - PROCESS | 1662 | __metrics 03:17:37 INFO - PROCESS | 1662 | 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" 03:17:37 INFO - PROCESS | 1662 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:17:37 INFO - TEST-INFO | 1662: exit 0 03:17:37 INFO - Browser initialized. 03:17:37 INFO - Running cycle 1/1 for kraken test... 03:17:38 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpBeKvxp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:50 INFO - PROCESS | 1664 | RSS: Main: 222531584 03:17:50 INFO - PROCESS | 1664 | 03:17:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:53 INFO - PROCESS | 1664 | RSS: Main: 270442496 03:17:53 INFO - PROCESS | 1664 | 03:17:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 03:17:57 INFO - PROCESS | 1664 | RSS: Main: 284680192 03:17:57 INFO - PROCESS | 1664 | 03:18:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 03:18:00 INFO - PROCESS | 1664 | RSS: Main: 380297216 03:18:00 INFO - PROCESS | 1664 | 03:18:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 03:18:03 INFO - PROCESS | 1664 | RSS: Main: 284078080 03:18:03 INFO - PROCESS | 1664 | 03:18:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:18:06 INFO - PROCESS | 1664 | RSS: Main: 285118464 03:18:06 INFO - PROCESS | 1664 | 03:18:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 03:18:09 INFO - PROCESS | 1664 | RSS: Main: 325767168 03:18:09 INFO - PROCESS | 1664 | 03:18:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 03:18:12 INFO - PROCESS | 1664 | RSS: Main: 326569984 03:18:12 INFO - PROCESS | 1664 | 03:18:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 03:18:16 INFO - PROCESS | 1664 | RSS: Main: 323477504 03:18:16 INFO - PROCESS | 1664 | 03:18:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:18:18 INFO - PROCESS | 1664 | RSS: Main: 298889216 03:18:18 INFO - PROCESS | 1664 | 03:18:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:18:21 INFO - PROCESS | 1664 | RSS: Main: 300613632 03:18:21 INFO - PROCESS | 1664 | 03:18:23 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:18:23 INFO - PROCESS | 1664 | RSS: Main: 303157248 03:18:23 INFO - PROCESS | 1664 | 03:18:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:18:27 INFO - PROCESS | 1664 | RSS: Main: 322170880 03:18:27 INFO - PROCESS | 1664 | 03:18:30 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:18:31 INFO - PROCESS | 1664 | RSS: Main: 315744256 03:18:31 INFO - PROCESS | 1664 | 03:18:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 03:18:33 INFO - PROCESS | 1664 | RSS: Main: 359575552 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | __start_tp_report 03:18:33 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 03:18:33 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 03:18:33 INFO - PROCESS | 1664 | |i|pagename|runs| 03:18:33 INFO - PROCESS | 1664 | |0;ai-astar;104;114;114;115;114;114;113;114;114;112 03:18:33 INFO - PROCESS | 1664 | |1;audio-beat-detection;140;110;106;144;111;107;113;135;174;109 03:18:33 INFO - PROCESS | 1664 | |2;audio-dft;181;183;184;186;184;182;182;184;186;182 03:18:33 INFO - PROCESS | 1664 | |3;audio-fft;80;59;58;90;62;58;60;59;83;58 03:18:33 INFO - PROCESS | 1664 | |4;audio-oscillator;88;70;93;70;68;70;88;67;91;122 03:18:33 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;86;89;87;89;86;90;87;89;85 03:18:33 INFO - PROCESS | 1664 | |6;imaging-darkroom;94;94;95;95;94;93;95;94;93;94 03:18:33 INFO - PROCESS | 1664 | |7;imaging-desaturate;90;123;80;79;84;80;86;84;80;80 03:18:33 INFO - PROCESS | 1664 | |8;json-parse-financial;57;53;53;53;57;59;58;58;56;57 03:18:33 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;71;81;76;73;69;69;71;70;71 03:18:33 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;83;79;84;84;74;89;100;88;84;85 03:18:33 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;143;141;140;152;137;155;158;154;181;155 03:18:33 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;164;226;227;169;165;166;163;163;165;165 03:18:33 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;61;65;66;68;68;66;65;65;75;84 03:18:33 INFO - PROCESS | 1664 | __end_tp_report 03:18:33 INFO - PROCESS | 1664 | __start_cc_report 03:18:33 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2027 03:18:33 INFO - PROCESS | 1664 | __end_cc_report 03:18:33 INFO - PROCESS | 1664 | __startTimestamp1481973513072__endTimestamp 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | ------- Summary: start ------- 03:18:33 INFO - PROCESS | 1664 | Number of tests: 14 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.19 (2.8%) stddev-sans-first:0.83 03:18:33 INFO - PROCESS | 1664 | Values: 104.0 114.0 114.0 115.0 114.0 114.0 113.0 114.0 114.0 112.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:124.00 stddev:22.59 (18.2%) stddev-sans-first:23.29 03:18:33 INFO - PROCESS | 1664 | Values: 140.0 110.0 106.0 144.0 111.0 107.0 113.0 135.0 174.0 109.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.71 (0.9%) stddev-sans-first:1.58 03:18:33 INFO - PROCESS | 1664 | Values: 181.0 183.0 184.0 186.0 184.0 182.0 182.0 184.0 186.0 182.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.00 stddev:12.46 (20.4%) stddev-sans-first:12.26 03:18:33 INFO - PROCESS | 1664 | Values: 80.0 59.0 58.0 90.0 62.0 58.0 60.0 59.0 83.0 58.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:82.70 Median:79.00 stddev:17.39 (22.0%) stddev-sans-first:18.34 03:18:33 INFO - PROCESS | 1664 | Values: 88.0 70.0 93.0 70.0 68.0 70.0 88.0 67.0 91.0 122.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:89.00 stddev:1.70 (1.9%) stddev-sans-first:1.74 03:18:33 INFO - PROCESS | 1664 | Values: 89.0 86.0 89.0 87.0 89.0 86.0 90.0 87.0 89.0 85.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 03:18:33 INFO - PROCESS | 1664 | Values: 94.0 94.0 95.0 95.0 94.0 93.0 95.0 94.0 93.0 94.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:86.60 Median:82.00 stddev:13.26 (16.2%) stddev-sans-first:14.01 03:18:33 INFO - PROCESS | 1664 | Values: 90.0 123.0 80.0 79.0 84.0 80.0 86.0 84.0 80.0 80.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:2.28 (4.0%) stddev-sans-first:2.40 03:18:33 INFO - PROCESS | 1664 | Values: 57.0 53.0 53.0 53.0 57.0 59.0 58.0 58.0 56.0 57.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.00 stddev:4.35 (6.1%) stddev-sans-first:3.91 03:18:33 INFO - PROCESS | 1664 | Values: 65.0 71.0 81.0 76.0 73.0 69.0 69.0 71.0 70.0 71.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:84.00 stddev:6.78 (8.1%) stddev-sans-first:7.16 03:18:33 INFO - PROCESS | 1664 | Values: 83.0 79.0 84.0 84.0 74.0 89.0 100.0 88.0 84.0 85.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:154.50 stddev:12.77 (8.3%) stddev-sans-first:13.16 03:18:33 INFO - PROCESS | 1664 | Values: 143.0 141.0 140.0 152.0 137.0 155.0 158.0 154.0 181.0 155.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:165.50 stddev:25.99 (15.7%) stddev-sans-first:27.11 03:18:33 INFO - PROCESS | 1664 | Values: 164.0 226.0 227.0 169.0 165.0 166.0 163.0 163.0 165.0 165.0 03:18:33 INFO - PROCESS | 1664 | 03:18:33 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.00 stddev:6.57 (9.8%) stddev-sans-first:6.41 03:18:33 INFO - PROCESS | 1664 | Values: 61.0 65.0 66.0 68.0 68.0 66.0 65.0 65.0 75.0 84.0 03:18:33 INFO - PROCESS | 1664 | -------- Summary: end -------- 03:18:33 INFO - PROCESS | 1664 | 03:18:34 INFO - TEST-INFO | 1664: exit 0 03:18:34 INFO - TEST-OK | kraken | took 59750ms 03:18:34 INFO - SUITE-END | took 1180s 03:18:34 INFO - Completed test suite (00:19:40) 03:18:34 INFO - javascript benchmark 03:18:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.20243141646, 3341.8997430077097, 3362.630110687824, 3375.9493607595873, 3384.6446123157066, 5657.51911087557, 5682.971585142073, 5739.942600573998, 5755.827325180241, 5775.797847075359, 5483.479069488402, 5512.283403157594, 5521.365043020054, 5526.668399895999, 5533.08704063829, 5002.0246052019875, 5027.472115427882, 5040.269160971657, 5086.186210206368, 5086.262491938666, 5125.897482050356, 5134.2812006319145, 5168.0, 5178.7928482860825, 5180.430152683201, 5176.249443830642, 5226.24219488174, 5237.973810130944, 5239.921401178985, 5257.211418287268, 5219.399769026553, 5243.79024839006, 5286.101362768339, 5296.894062118736, 5303.852009592706, 5062.291279220912, 5160.380754309469, 5243.947560524384, 5244.632875698702, 5253.159494480865, 4933.840547471342, 5521.917171242435, 5582.218489411485, 5615.887682246373, 5637.408072152447, 6189.38106189382, 6290.339514351019, 6303.2622671411, 6303.779367722153, 6309.495240380758, 5298.602604804617, 6293.937060629427, 6351.80944571659, 6354.046892966046, 6370.490360771174, 5214.452482489284, 5464.23500709901, 5785.623934444247, 5807.4482924122985, 5858.8535286617325, 6276.560640755103, 6316.242050953915, 6320.810375688737, 6330.0821380899515, 6336.239651241788, 6226.0, 6229.0967809668255, 6235.594686345373, 6239.781607643801, 6243.7814676486105, 6071.241094863142, 6203.2556093269095, 6206.689665516707, 6317.210348706411, 6339.619622822645, 6158.291796443376, 6214.285357183891, 6221.813345599639, 6293.37066293367, 6297.6536290503545, 6238.594491358047, 6288.811335659846, 6300.306966233802, 6310.842228944313, 6311.684415779193, 4857.368542089505, 4916.0, 4969.602431805519, 4985.800567977313, 5004.224345226496, 4400.427944367212, 4483.215437298525, 4483.820647174077, 4507.3689683444345, 4507.977460112678, 5722.37053924068, 5729.369769325371, 5730.770769169187, 5743.052396354555, 5748.741306641211, 6223.564350495512, 6236.6258024518675, 6250.249970003629, 6253.749850005949, 6256.812295631138, 5669.574781891375, 5727.599068065196, 5738.483536481736, 5741.598088133791, 5753.654780713171, 5395.7841686332895, 5415.241866138744, 5415.566754659618, 5417.404085550586, 5419.187121931742, 6223.719932603044, 6283.96858015707, 6284.151639528696, 6291.496680265568, 6298.905516417349, 6311.652859092794, 6323.905141422791, 6326.683665816783, 6330.240371155491, 6338.366163383625, 6187.381261873777, 6308.621482711052, 6308.621482711052, 6309.3690630936535, 6311.621302721851, 6035.728392222272, 6086.452219300284, 6102.877942441215, 6140.289530736458, 6245.094461303176, 6252.406021427957, 6319.747210111544, 6323.715432805443, 6340.651264180515, 6342.112104305309, 6050.788222412194, 6186.917289474414, 6189.535784816067, 6197.721102550487, 6212.4408803207925, 5940.732667029907, 5970.134330522137, 6041.909371359346, 6175.752969881154, 6192.783252586138, 5922.73347699346, 5933.317668468181, 5936.970315148396, 5969.074793406855, 6079.3008803988105, 6162.754832996664, 6163.260408750799, 6172.845678858064, 6180.0, 6181.381861813783, 5975.55183361241, 6013.849653758691, 6026.186464827368, 6027.842273619539, 6055.818325450244], "value": 5700.932330947412, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.5623769893997, 505.05806670559406, 510.1531457780086, 546.229815959497, 564.2226984997261, 24145.0, 31158.532622010687, 31161.065168044937, 31199.376012479737, 38671.806640966824, 39101.0, 41034.0, 41205.17589648213, 41237.0, 41253.17493650125, 9329.860052099208, 9504.0, 9516.80966380672, 9520.619175232983, 9541.284403669717, 9339.252859771235, 9399.154076133147, 9463.952680236624, 9482.620695172185, 9506.334556581041, 9417.48203848788, 9464.290178236626, 9472.147506724394, 9485.620575176985, 9493.908200556954, 9396.201322887562, 9431.811363772755, 9443.952780236124, 9450.810983780286, 9486.715398538056, 9387.0, 9468.479233642147, 9472.621095156186, 9480.478573678445, 9486.525673716287, 38427.23145537073, 38564.421533677014, 38597.42103868444, 38613.227735445136, 38722.0, 3178.618565772099, 3192.4892017277125, 3231.2568109334984, 3287.0, 3623.3115708015393, 28647.856760716273, 29009.984650537343, 29070.563941540895, 29378.412431751458, 30178.79284828604, 3046.6496352919476, 3092.1187461573345, 3111.673274306188, 3142.7429028388597, 3146.889858854952, 483244.0, 484578.0, 487541.0, 487925.0, 488553.5572322151, 32654.020379388658, 33568.16079598029, 58577.70711146417, 59008.409915900294, 59033.70483147557, 34296.0, 34638.82680586581, 34668.47997280043, 34730.305393891984, 34768.82615586957, 12280.75438491243, 13815.894728421703, 14193.148411095368, 14204.64488387778, 14220.928895355664, 11941.76116477683, 12010.699732506582, 12035.638930832089, 12036.759264814655, 12047.698807529707, 7713.730019449405, 7735.613219339123, 7782.027246594176, 7808.804779880548, 7822.295993360625, 3235.2720637856296, 3245.6754324567355, 3248.3340915112344, 3256.5115232714807, 3282.556854824616, 920.2867777472378, 927.2767241551601, 951.2912879904377, 1050.7898420315967, 1112.6495154026456, 28378.43243135126, 28826.855865720536, 28944.855275723487, 29442.263943401587, 29693.554596681068, 1033.7002269341956, 1102.1669977150254, 1110.9333439993627, 1121.6410748560545, 1177.5407591039502, 14696.926515367355, 15206.923965380101, 15249.923750381398, 15512.612184695472, 15682.607934801721, 634239.0, 843909.0, 884641.0, 884708.0, 885618.0, 42610.147797044505, 56536.0, 56551.71724141353, 56621.71689141528, 56681.0, 1242.658268976039, 1275.171138759795, 1359.415451355887, 1379.5102738527878, 1407.5003373802358, 27206.727932721213, 29019.85490072536, 29160.27099322534, 29812.850935745184, 29813.850930745208, 47231.29153062776, 47818.760906195246, 47836.5216347846, 47922.0, 47973.520264796905, 552346.0, 559457.0, 559659.0, 560493.0, 560565.0, 22945.31164065081, 23385.298441046794, 25382.8577714007, 25978.090766823072, 26333.604995924692, 37750.62249377471, 42487.362689559064, 42516.149677006906, 42858.57141428671, 42994.140117196854, 36103.0, 36440.635593643725, 36539.45190822193, 37951.62048379481, 37973.620263797005, 35699.64300356963, 35810.10474738153, 36495.45256821203, 36549.81725091464, 36791.44812827863, 34550.7907223246, 35090.0, 35178.64821351754, 35475.64524354723, 35843.28313433665, 27676.861615691792, 31204.21989450191, 31459.37081258316, 33321.0, 33989.640414383146, 20694.379168624964, 24678.50642987166, 24903.377415564755, 25260.368490787878, 25276.87361563254], "value": 18035.659423141926, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.8704722776188, 371.98326075326605, 376.5124164207351, 385.0873429970968, 401.55427475502205, 14624.780628290584, 14964.476243331486, 19137.03375976724, 20658.0, 20850.165993360286, 20333.28833490828, 20512.974351282413, 20528.794712052873, 20537.0, 20796.376108716726, 4952.554270115689, 6192.071189321593, 6195.659238741868, 6204.13142160098, 6205.03821907605, 5689.886202275952, 5963.522918166558, 5976.910346344808, 6161.938380616204, 6168.475679567242, 6086.417531441835, 6088.238970128734, 6092.817215483543, 6098.634081955097, 6107.236595425572, 6015.27816661999, 6090.908636370458, 6523.575967562093, 6587.934120658828, 6603.009548567706, 5598.104303311491, 5803.65178089316, 5829.766809327664, 5831.154482600042, 5849.736761845685, 13080.28058456794, 13301.86698133016, 23006.283711839034, 24600.293967663543, 24927.750722492725, 2143.19630138698, 2227.732672079345, 2274.9090036398525, 2310.3992961829877, 2345.7419683834764, 8150.959245203795, 9849.50752462374, 9861.950690246575, 10334.069933705927, 10475.94762026185, 1825.4341154242227, 1830.9472053569118, 1852.7961924188328, 1875.3436297295973, 1917.980820191808, 115605.0, 115675.0, 121086.39456802748, 125913.37043314817, 126124.0, 10756.946215268874, 11164.888351116384, 11223.60717374888, 11611.593594224161, 11618.94190529042, 10775.892241077489, 12706.936465317614, 13469.326533673277, 13475.460981560824, 14281.071730337495, 8194.631241594143, 8247.95876020616, 8353.54055526952, 8426.07313195548, 8514.276286515591, 8155.225253600898, 8174.877376839352, 8181.222783835526, 8307.169283071766, 8318.500889946623, 4152.356384760367, 4392.2752745797225, 4405.0, 4419.779011049434, 4561.635069194456, 1623.3344328825126, 1831.505493516743, 1904.8476121910214, 1912.0363436673101, 1914.1769039313228, 867.3061550759368, 902.5351943748974, 924.5977999570208, 994.7485606890858, 1037.2065369991963, 16013.839861601235, 19173.808261917482, 20222.393328200178, 20231.393058208276, 20477.692834607493, 929.6188562689265, 930.06993006993, 937.8827646544182, 950.9619615215314, 985.5762022330467, 10287.537060832281, 10709.303895246783, 10825.296355736851, 10832.945835270773, 10867.945660271647, 534173.0, 550537.0, 559013.0, 560395.0, 564951.0, 125462.0, 128669.0, 133346.24399157852, 133576.0, 133581.0, 916.8533034714548, 922.7462447826902, 943.7640589852537, 945.5083356654501, 948.1940350701987, 17990.730139048188, 18170.90914545419, 18326.816731833045, 18446.169922353794, 18541.98019108962, 40884.79557602193, 53871.46128538664, 54049.0, 54926.52830622711, 55425.614359639716, 65217.0, 72531.0, 77577.0, 77983.0, 78530.6073469629, 6961.990511375923, 8045.879311810445, 8121.675132994614, 8193.590320483832, 8326.12575679569, 9503.762405939908, 12542.686432839251, 12640.494380224687, 12669.113162078565, 12865.397688647194, 9331.673391431268, 10405.115565177044, 10684.679459616224, 10905.672829815117, 11223.719407014563, 10877.728056798644, 10895.945520272666, 10974.176936729937, 11247.156463265124, 11256.017155453976, 9192.724218273463, 9326.580303886478, 10754.892451075702, 11491.597794077166, 11535.423228838357, 7944.801379965547, 7996.960015199887, 8193.836123277382, 8238.052623948082, 8429.367797415342, 8175.836483270183, 10821.0, 10921.726956826144, 11242.775144497227, 11303.03924166455], "value": 9315.958574763381, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1270.656922630889, 1302.1405872124394, 1409.8801601863843, 1442.531177367356, 1595.4814685227298, 7272.418206543477, 8017.238362355574, 8233.547154906486, 8237.711680091197, 8241.876371854427, 7396.371308438789, 7720.112187098485, 8061.516309021448, 8153.91846081539, 8180.672773089069, 2273.772622737725, 2309.3533810533077, 2309.7574754650773, 2310.445493081657, 2314.780095890896, 2293.312006398074, 2327.2669109230624, 2337.4273303040777, 2340.1458467659354, 2340.8595484270913, 2266.252136794862, 2270.761570035147, 2298.138198175684, 2299.0688771047753, 2299.356180269527, 2185.311626837549, 2186.191109289557, 2223.8220942324574, 2232.5423288225875, 2232.9218477353297, 2286.211257116309, 2309.711286089239, 2310.8151347892126, 2311.9653205201935, 2317.1079134533156, 7967.402444816604, 8099.919000809976, 8109.351251899834, 8173.100958894517, 8185.181481851827, 4977.377827771528, 5166.04428180784, 5166.819161329373, 5167.431582525919, 5174.172132458789, 2919.343147791751, 3168.8890888818783, 3329.1548261757466, 3415.351083294166, 3499.5450591423205, 1003.2776400991347, 1006.5369929832286, 1014.7513859104492, 1040.4121671255716, 1043.3896170740127, 10553.155747540179, 10608.469576521144, 10700.839487407695, 10706.732331691692, 10734.946325268402, 4222.514410842731, 4364.585364390377, 4442.089371678798, 4444.422225110715, 4448.777561121931, 8546.102659220693, 8556.315494760403, 8558.743237702878, 8562.828743425096, 8582.785430364165, 5051.848444546669, 5059.949400506021, 5061.44324124346, 5063.139266324733, 5063.316452278968, 4008.779517126586, 4241.321388577812, 4366.497852746911, 4405.559444055569, 4661.324108004321, 3029.5304227844717, 3592.8383222755037, 3686.0047787097333, 3697.574778900461, 3699.2416554606243, 1135.9488823002985, 1137.590467431724, 1140.2303445174473, 1147.3574798113084, 1148.62095508482, 819.6434550970347, 879.8284334554701, 896.4014605075919, 910.5492781073411, 927.8608208768738, 5210.76551555181, 5384.138537833927, 5652.943470565324, 5665.923474539906, 5714.314282286152, 892.4600616626977, 931.7654108306521, 959.827231098395, 1007.1992765751245, 1023.8668973033458, 3303.339332133583, 3686.060054686038, 3782.7541209821275, 3848.634379733977, 3903.902402439962, 9527.09492598202, 9955.751106222402, 9990.300678952404, 10006.499675016366, 10039.69880903574, 9431.245500360092, 9889.20886329092, 9898.901010989943, 9902.455364954852, 9920.15678667307, 7622.504537205064, 7678.846423071507, 7688.76933691979, 7816.648250828726, 7819.687212511663, 5442.972785136049, 5537.45277172489, 5545.390007099135, 5717.0, 5809.244798176379, 9196.54017299119, 9348.626054957998, 9373.578188981648, 9380.296477764332, 9422.104900034486, 9231.030741772018, 9405.012473690163, 9432.811343773223, 9476.241900648069, 9541.332106752461, 7092.822679433056, 7130.251323610946, 7148.928510714826, 7164.749233777001, 7234.710611575689, 7694.576798275924, 7783.571903545359, 7792.805179870548, 7797.883031754414, 7803.453758236869, 6979.546329488567, 7103.467239957127, 7134.2152363238965, 7163.820904477429, 7243.5653860768525, 6823.556468829709, 6919.965400172967, 6979.644781480864, 7031.0, 7151.2848715128075, 6533.803985880431, 6553.606783593, 6557.96721016411, 6646.0695502628705, 6666.733330666719, 4853.320535125156, 4873.074115917964, 4876.658633895593, 4886.804527818847, 4918.2376731606655, 4795.9280610790165, 4799.832005879777, 4820.734859582757, 4986.202207646759, 4992.051510213048], "value": 4322.927962270499, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.70069970583387, 281.76331881219795, 303.4674146872239, 307.6615722705024, 311.81447039011795, 4560.7913902815735, 4817.662763606549, 4836.0, 5114.539691427771, 5143.511366420194, 4812.831550895719, 5046.293518907356, 5082.720450375237, 5084.669496482726, 5089.949100508994, 3578.425492783167, 3749.137698329386, 3883.6893048556117, 3909.6676782473523, 3945.487086678728, 3087.166465054434, 3740.532433445819, 3878.8836334909997, 3903.765774053566, 3911.5110611173604, 3859.205469456702, 3861.5945325740813, 3864.7294689371756, 3877.332746918824, 3886.747361421513, 3226.838658067111, 3253.6258330291967, 3840.8847734568008, 3890.0663840678317, 3890.533136023695, 2842.9431411371656, 3362.4283871741864, 3373.578302712161, 3381.7970921744773, 3396.8471418785966, 4487.394201782783, 4688.546550569335, 4930.767308172957, 4971.254311853215, 5007.599392048627, 3822.7132965027736, 4120.402541631447, 4195.538490766014, 4322.524522302544, 4394.6923715339935, 4534.59188673021, 4673.439187297512, 5113.360829896263, 5295.338082739658, 5380.166074258496, 3579.2360586668765, 3642.5993140754495, 3705.370087085202, 3709.7403181777286, 3716.7920425861703, 5581.6371935824045, 5586.301712285965, 5590.580706447033, 5614.9438505615235, 5647.265855438849, 3223.066160303799, 3718.200586873835, 3730.0, 3735.7198210133806, 3756.9060773480883, 3032.4844776388068, 3523.3129539740016, 3772.4529943158086, 3808.9047773805323, 3889.3776995680555, 4153.9626551882375, 4195.119025004783, 4363.716358436692, 4402.823887044547, 4408.845690400821, 4742.383490146259, 4816.4220293564995, 5373.623846330719, 5411.404745477994, 5418.593605479605, 1223.7797196504537, 1224.7305592769576, 1229.956951506693, 1236.4667811913164, 1239.9566015189425, 779.2441331908041, 839.6809212499211, 840.6637345062024, 846.627483907079, 850.2602735620051, 3796.487474191004, 3953.683705303569, 4696.577308042224, 4704.317873908332, 4745.881352966204, 4413.249747542925, 5098.566621837112, 5167.328247327823, 5185.611079169077, 5198.506141916512, 3776.86780962665, 4699.295105734167, 4712.929306060343, 4744.312074749211, 4766.499517550607, 2554.3486410965083, 2657.282533715886, 2685.5703087505904, 2717.7282271772665, 2761.3628984319926, 4697.577218050392, 5057.089723849743, 5170.767315470813, 5298.443663415286, 5396.19057141424, 4380.479309730617, 4884.902301954012, 5165.199394093846, 5234.502722241381, 5355.607542039098, 4907.080287229093, 5315.38553783431, 5360.383555891122, 5380.175407910878, 5426.620136590401, 4292.399064130962, 5156.375741641521, 5171.81898633546, 5181.792728290826, 5214.800595862896, 4759.738214398241, 4829.5653391194965, 5124.820631278037, 5129.410117836496, 5540.944590554204, 5460.972695136499, 5703.69000554776, 5706.629069110494, 5729.320080078601, 5788.739506722123, 3414.309916591303, 3490.5740510345067, 3566.946495802617, 3763.5107436034236, 3769.3791669582342, 5371.158673930041, 5517.144842549411, 5570.944290557205, 5615.820677657738, 5616.663000219836, 4362.672799539983, 4825.879353016203, 4998.3002379667605, 5461.508464238238, 5477.342718873601, 4833.709977401367, 4906.650671065486, 5361.37292726569, 5364.6512976656395, 5390.784368625211, 4725.9366642505165, 5064.164412871834, 5291.7618707159045, 5334.813281535127, 5390.622656414013, 4910.803567857389, 4985.886643519038, 5289.3652761668845, 5418.810341638024, 5459.235707001102, 4914.9017019658695, 5003.648285305901, 5338.679679219259, 5345.545628621455, 5374.838754837361], "value": 3840.0411353119985, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.900689400037, 737.8044818123199, 750.2722359311466, 776.5545968461765, 791.5329955326358, 14213.64465888352, 14686.045407048548, 16088.678226435464, 16632.417865374715, 16660.58348541287, 14206.857931420684, 14253.429862805502, 14281.50014749484, 14299.57101286963, 14366.20985845778, 647.6437959122487, 674.5278305186389, 800.9639566219535, 826.2646244842095, 830.4436027861348, 671.543350521645, 796.4424902568196, 811.4401063266357, 827.896512935883, 828.4987582512583, 860.9139086091373, 863.9697610583631, 864.6973559254274, 866.2723312417568, 866.7703058689452, 646.8803271394808, 859.5014891363026, 859.7635650196215, 860.724568138196, 861.9870701939476, 514.7580637100557, 623.4731651754261, 644.255884453457, 647.7635662983024, 648.0797267879575, 14445.78331325031, 14491.275436228147, 14496.492622758258, 14511.564653060424, 14520.709585808225, 12569.874301256961, 12677.74644507114, 12735.363231838372, 12828.03789715775, 12889.839914407748, 12107.952572307404, 12187.0, 12291.93854030733, 12310.26138431697, 12325.137240393176, 4171.833126674929, 4202.201581699467, 4263.424437700902, 4288.270993931039, 4404.7357158570585, 66945.0, 81957.59021204856, 84614.0, 84909.15090849074, 85092.57453712691, 9775.31572789912, 9999.850002249972, 10116.0, 10202.28583999113, 10330.483475826179, 18619.0, 18869.811301886806, 18991.1453984571, 19033.524161895788, 19290.0, 7402.629868506553, 7403.407727381797, 7435.0, 7474.70101195957, 7546.24537546241, 4167.916641667211, 4182.142660754538, 4244.193603215379, 4265.936010959838, 4406.317020861771, 3323.9501407478906, 3442.7762195457217, 3447.3622379859808, 3450.879219227315, 3453.2230248193955, 632.8069938668658, 649.6978904809287, 654.4339146638218, 663.4062514049898, 670.8926571748497, 535.6090054260334, 579.6406228138582, 583.5973178506816, 589.8348462430527, 651.6481100205931, 7835.608219588998, 8808.647654093893, 9079.727608171765, 9166.037566055602, 9169.037251088674, 632.2413104274889, 645.1935081148564, 659.350539718377, 661.3466334164589, 673.8281738156738, 4216.388623649615, 4447.0, 4453.421055262795, 4460.933086003713, 4573.176828170961, 65902.68194636176, 66106.66946665331, 66170.00744988733, 68995.0, 105570.0, 61057.47356316039, 63805.68097159485, 65137.674311628136, 77093.45813083707, 81680.18319816845, 85840.99999999875, 86903.1309686895, 87252.0, 89903.0, 91248.54375728079, 8453.915460845314, 8607.612657430305, 8615.439996400464, 8765.34259930495, 8839.46963182211, 57282.1371602916, 57511.1373329392, 57847.710761447604, 58332.0, 59896.0, 62447.0, 71317.93023104554, 77367.61316193383, 78096.60951695206, 81484.59257703673, 9986.851512075913, 10294.691159265234, 10831.404272765074, 11212.04697600713, 11362.693290271725, 11808.82286765682, 11907.642770716893, 11947.223430476992, 12018.278903265833, 12237.20458170216, 9853.0, 11334.94332528332, 11506.252093613888, 11619.941900290445, 11921.105917056082, 11012.17408694367, 11287.492062857027, 11495.942520287344, 11672.124590655905, 12278.324692142018, 11755.412229388325, 12035.939820300842, 12053.879461205275, 12308.753824923273, 12503.624891253277, 10368.844467332845, 10480.895191048297, 10617.1506279499, 10770.946145269223, 11233.157513186381, 10558.630447934285, 10775.568977240822, 10823.296485728402, 10919.836202457129, 11060.281081729661], "value": 7010.2360390574895, "unit": "score"}], "name": "dromaeo_css", "value": 6937.448792455107, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0, 114.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 106.0, 144.0, 111.0, 107.0, 113.0, 135.0, 174.0, 109.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 184.0, 186.0, 184.0, 182.0, 182.0, 184.0, 186.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 90.0, 62.0, 58.0, 60.0, 59.0, 83.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 93.0, 70.0, 68.0, 70.0, 88.0, 67.0, 91.0, 122.0], "value": 82.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 93.0, 95.0, 94.0, 93.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 123.0, 80.0, 79.0, 84.0, 80.0, 86.0, 84.0, 80.0, 80.0], "value": 86.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 53.0, 53.0, 57.0, 59.0, 58.0, 58.0, 56.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 81.0, 76.0, 73.0, 69.0, 69.0, 71.0, 70.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 84.0, 84.0, 74.0, 89.0, 100.0, 88.0, 84.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 140.0, 152.0, 137.0, 155.0, 158.0, 154.0, 181.0, 155.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 226.0, 227.0, 169.0, 165.0, 166.0, 163.0, 163.0, 165.0, 165.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 66.0, 68.0, 68.0, 66.0, 65.0, 65.0, 75.0, 84.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1448.8, "alertThreshold": 2.0}]} 03:18:34 INFO - Return code: 0 03:18:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:18:34 INFO - # TBPL SUCCESS # 03:18:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:18:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:18:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:18:34 INFO - 2016-12-17 03:18:34.361 screenresolution[1668:14127] starting screenresolution argv=screenresolution get 03:18:34 INFO - 2016-12-17 03:18:34.364 screenresolution[1668:14127] Display 0: 1600x1200x32@60 03:18:34 INFO - 2016-12-17 03:18:34.370 screenresolution[1669:14130] starting screenresolution argv=screenresolution list 03:18:34 INFO - Available Modes on Display 0 03:18:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:18:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:18:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:18:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:18:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:18:34 INFO - Intel Iris: 03:18:34 INFO - Chipset Model: Intel Iris 03:18:34 INFO - Type: GPU 03:18:34 INFO - Bus: Built-In 03:18:34 INFO - VRAM (Dynamic, Max): 1536 MB 03:18:34 INFO - Vendor: Intel (0x8086) 03:18:34 INFO - Device ID: 0x0a2e 03:18:34 INFO - Revision ID: 0x0009 03:18:34 INFO - Displays: 03:18:34 INFO - Display: 03:18:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:18:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:18:34 INFO - Display Serial Number: 1600x1200 60 03:18:34 INFO - Main Display: Yes 03:18:34 INFO - Mirror: Off 03:18:34 INFO - Online: Yes 03:18:34 INFO - Rotation: Supported 03:18:34 INFO - Return code: 0 03:18:34 INFO - Running post-action listener: _resource_record_post_action 03:18:34 INFO - [mozharness: 2016-12-17 11:18:34.615192Z] Finished run-tests step (success) 03:18:34 INFO - Running post-run listener: _resource_record_post_run 03:18:34 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32435200; Write bytes: 660664832; Read time: 199; Write time: 1895 03:18:34 INFO - TinderboxPrint: CPU usage
53.5% 03:18:34 INFO - TinderboxPrint: I/O read bytes / time
32,435,200 / 199 03:18:34 INFO - TinderboxPrint: I/O write bytes / time
660,664,832 / 1,895 03:18:34 INFO - TinderboxPrint: CPU idle
2,256.8 (46.5%) 03:18:34 INFO - TinderboxPrint: CPU system
971.4 (20.0%) 03:18:34 INFO - TinderboxPrint: CPU user
1,621.4 (33.4%) 03:18:34 INFO - TinderboxPrint: Swap in / out
376,537,088 / 0 03:18:34 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344705536; Write bytes: 346310656; Read time: 26929; Write time: 712 03:18:34 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 30473216; Write bytes: 307476992; Read time: 182; Write time: 1154 03:18:35 INFO - Running post-run listener: _upload_blobber_files 03:18:35 INFO - Blob upload gear active. 03:18:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:18:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:18:35 INFO - (blobuploader) - INFO - Open directory for files ... 03:18:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:18:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:18:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:36 INFO - (blobuploader) - INFO - Done attempting. 03:18:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:18:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:18:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:37 INFO - (blobuploader) - INFO - Done attempting. 03:18:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:18:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:18:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:39 INFO - (blobuploader) - INFO - Done attempting. 03:18:39 INFO - (blobuploader) - INFO - Iteration through files over. 03:18:39 INFO - Return code: 0 03:18:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:18:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:18:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6c5a79c2532417cfb07f316c2155e30d2a7048d80c7c8550251af6cbc6b5b0f95c109bb6cdfd09752d8545d899890c870d3dec7c0ab4bff06ceab9203865c7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e14283977b7e12ff50d2cc5528ade90bd11edc9a4db116d41492481d54a3cd6eae79176484994eb7ff25e440d012b8daab8aac33da4763bd7cd0b0310792ba1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89a32542a2c76cf6f58673f35c86276443f244b6b9d97e5e84419f6edcd7c3969f2e1cfceb2557286c9e204415d3cbbc92deede1e6531f206a5e35226a760821"} 03:18:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:18:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:18:39 INFO - Contents: 03:18:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6c5a79c2532417cfb07f316c2155e30d2a7048d80c7c8550251af6cbc6b5b0f95c109bb6cdfd09752d8545d899890c870d3dec7c0ab4bff06ceab9203865c7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e14283977b7e12ff50d2cc5528ade90bd11edc9a4db116d41492481d54a3cd6eae79176484994eb7ff25e440d012b8daab8aac33da4763bd7cd0b0310792ba1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89a32542a2c76cf6f58673f35c86276443f244b6b9d97e5e84419f6edcd7c3969f2e1cfceb2557286c9e204415d3cbbc92deede1e6531f206a5e35226a760821"} 03:18:39 INFO - Running post-run listener: copy_logs_to_upload_dir 03:18:39 INFO - Copying logs to upload dir... 03:18:39 INFO - mkdir: /builds/slave/test/build/upload/logs 03:18:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.715391 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-17 03:18:39.609963) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 03:18:39.614045) ========= 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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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/d6c5a79c2532417cfb07f316c2155e30d2a7048d80c7c8550251af6cbc6b5b0f95c109bb6cdfd09752d8545d899890c870d3dec7c0ab4bff06ceab9203865c7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e14283977b7e12ff50d2cc5528ade90bd11edc9a4db116d41492481d54a3cd6eae79176484994eb7ff25e440d012b8daab8aac33da4763bd7cd0b0310792ba1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89a32542a2c76cf6f58673f35c86276443f244b6b9d97e5e84419f6edcd7c3969f2e1cfceb2557286c9e204415d3cbbc92deede1e6531f206a5e35226a760821"} build_url:https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011219 build_url: 'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/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/d6c5a79c2532417cfb07f316c2155e30d2a7048d80c7c8550251af6cbc6b5b0f95c109bb6cdfd09752d8545d899890c870d3dec7c0ab4bff06ceab9203865c7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e14283977b7e12ff50d2cc5528ade90bd11edc9a4db116d41492481d54a3cd6eae79176484994eb7ff25e440d012b8daab8aac33da4763bd7cd0b0310792ba1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89a32542a2c76cf6f58673f35c86276443f244b6b9d97e5e84419f6edcd7c3969f2e1cfceb2557286c9e204415d3cbbc92deede1e6531f206a5e35226a760821"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 03:18:39.649156) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 03:18:39.649507) ========= 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.QtbRqc9ZES/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.4ceNuXEjmM/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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.005093 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 03:18:39.698555) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 03:18:39.698878) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 03:18:39.699215) ========= ========= Total master_lag: 0.33 =========