builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0169 starttime: 1482445539.52 results: success (0) buildid: 20161222121953 builduid: 4e4f87ca59264d839b87be033a0e1938 revision: 20654fa125a90d0ca839bbf15b81612b8667c43b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:39.515727) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:39.516154) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:39.516426) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:39.546189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:39.546533) ========= 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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-22 14:25:39-- 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% 9.02M=0.001s 2016-12-22 14:25:39 (9.02 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093189 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:39.666633) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:39.666964) ========= 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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.027045 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:39.715504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-22 14:25:39.718876) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20654fa125a90d0ca839bbf15b81612b8667c43b --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 20654fa125a90d0ca839bbf15b81612b8667c43b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2HSdhoTFhr/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.YLYH7aw9TB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-22 14:25:39,788 truncating revision to first 12 chars 2016-12-22 14:25:39,788 Setting DEBUG logging. 2016-12-22 14:25:39,789 attempt 1/10 2016-12-22 14:25:39,789 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/20654fa125a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 14:25:41,674 unpacking tar archive at: mozilla-aurora-20654fa125a9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.167587 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-22 14:25:41.912719) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:41.913040) ========= script_repo_revision: 20654fa125a90d0ca839bbf15b81612b8667c43b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:41.913419) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:41.913690) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:41.933264) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-22 14:25:41.933662) ========= /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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:25:42 INFO - MultiFileLogger online at 20161222 14:25:42 in /builds/slave/test 14:25:42 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 14:25:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:25:42 INFO - {'append_to_log': False, 14:25:42 INFO - 'base_work_dir': '/builds/slave/test', 14:25:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:25:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:25:42 INFO - 'branch': 'Mozilla-Aurora', 14:25:42 INFO - 'buildbot_json_path': 'buildprops.json', 14:25:42 INFO - 'config_files': ('talos/mac_config.py',), 14:25:42 INFO - 'default_actions': ('clobber', 14:25:42 INFO - 'read-buildbot-config', 14:25:42 INFO - 'download-and-extract', 14:25:42 INFO - 'populate-webroot', 14:25:42 INFO - 'create-virtualenv', 14:25:42 INFO - 'install', 14:25:42 INFO - 'run-tests'), 14:25:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:25:42 INFO - 'download_minidump_stackwalk': True, 14:25:42 INFO - 'download_symbols': 'ondemand', 14:25:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:25:42 INFO - 'tooltool.py': '/tools/tooltool.py', 14:25:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:25:42 INFO - '/tools/misc-python/virtualenv.py')}, 14:25:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:25:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:25:42 INFO - 'installer_path': 'installer.exe', 14:25:42 INFO - 'log_level': 'info', 14:25:42 INFO - 'log_name': 'talos', 14:25:42 INFO - 'log_to_console': True, 14:25:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:25:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:25:42 INFO - 'opt_config_files': (), 14:25:42 INFO - 'pip_index': False, 14:25:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:25:42 INFO - 'cmd': ('bash', 14:25:42 INFO - '-c', 14:25:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:25:42 INFO - 'enabled': True, 14:25:42 INFO - 'halt_on_failure': False, 14:25:42 INFO - 'name': 'check_screen_resolution'},), 14:25:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:25:42 INFO - 'cmd': ('bash', 14:25:42 INFO - '-c', 14:25:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:25:42 INFO - 'enabled': True, 14:25:42 INFO - 'halt_on_failure': False, 14:25:42 INFO - 'name': 'check_screen_resolution'},), 14:25:42 INFO - 'run_cmd_checks_enabled': True, 14:25:42 INFO - 'sps_profile': False, 14:25:42 INFO - 'sps_profile_interval': 0, 14:25:42 INFO - 'suite': 'dromaeojs', 14:25:42 INFO - 'system_bits': '32', 14:25:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:25:42 INFO - 'title': 't-yosemite-r7-0169', 14:25:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:25:42 INFO - 'use_talos_json': True, 14:25:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:25:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:25:42 INFO - 'work_dir': 'build'} 14:25:42 INFO - [mozharness: 2016-12-22 22:25:42.116896Z] Running clobber step. 14:25:42 INFO - Running pre-action listener: _resource_record_pre_action 14:25:42 INFO - Running main action method: clobber 14:25:42 INFO - rmtree: /builds/slave/test/build 14:25:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:25:44 INFO - Running post-action listener: _resource_record_post_action 14:25:44 INFO - [mozharness: 2016-12-22 22:25:44.458661Z] Finished clobber step (success) 14:25:44 INFO - [mozharness: 2016-12-22 22:25:44.458771Z] Running read-buildbot-config step. 14:25:44 INFO - Running pre-action listener: _resource_record_pre_action 14:25:44 INFO - Running main action method: read_buildbot_config 14:25:44 INFO - Using buildbot properties: 14:25:44 INFO - { 14:25:44 INFO - "project": "", 14:25:44 INFO - "product": "firefox", 14:25:44 INFO - "script_repo_revision": "production", 14:25:44 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:25:44 INFO - "repository": "", 14:25:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:25:44 INFO - "buildid": "20161222121953", 14:25:44 INFO - "pgo_build": "False", 14:25:44 INFO - "basedir": "/builds/slave/test", 14:25:44 INFO - "buildnumber": 8, 14:25:44 INFO - "slavename": "t-yosemite-r7-0169", 14:25:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:25:44 INFO - "platform": "yosemite_r7", 14:25:44 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:25:44 INFO - "branch": "mozilla-aurora", 14:25:44 INFO - "repo_path": "releases/mozilla-aurora", 14:25:44 INFO - "revision": "20654fa125a90d0ca839bbf15b81612b8667c43b", 14:25:44 INFO - "stage_platform": "macosx64", 14:25:44 INFO - "builduid": "4e4f87ca59264d839b87be033a0e1938", 14:25:44 INFO - "slavebuilddir": "test" 14:25:44 INFO - } 14:25:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:25:44 INFO - Running post-action listener: _resource_record_post_action 14:25:44 INFO - [mozharness: 2016-12-22 22:25:44.461762Z] Finished read-buildbot-config step (success) 14:25:44 INFO - [mozharness: 2016-12-22 22:25:44.461923Z] Running download-and-extract step. 14:25:44 INFO - Running pre-action listener: _resource_record_pre_action 14:25:44 INFO - Running main action method: download_and_extract 14:25:44 INFO - mkdir: /builds/slave/test/build/tests 14:25: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')]} 14:25:44 INFO - trying https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:25:44 INFO - Downloading https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/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 14:25:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/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 14:25:45 INFO - Downloaded 1199 bytes. 14:25:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:25:45 INFO - Using the following test package requirements: 14:25:45 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:25:45 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:45 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:25:45 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:45 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:25:45 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:45 INFO - u'jsshell-mac.zip'], 14:25:45 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:45 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:25:45 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:25:45 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:45 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:25:45 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:45 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:25:45 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:45 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:25:45 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:45 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:25:45 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:25:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:25:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:25:45 INFO - Fetch https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:25:46 INFO - Http code: 200 14:25:46 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 14:25:46 INFO - x-amz-cf-id: FZLuKzhqfVXLrAvxupT_885zNczYlk9jqnehCDD7gHN5PRNJKq_OKQ== 14:25:46 INFO - x-amz-version-id: MqMEl1.QtVWrFqtAfJKDe1TZkgFn37_x 14:25:46 INFO - Expected file size: 16271077 14:25:46 INFO - Obtained file size: 16271077 14:25:53 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 14:25:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:25:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:25:53 INFO - Fetch https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:25:53 INFO - Http code: 200 14:25:53 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 14:25:53 INFO - x-amz-cf-id: aSkBkkoRyeShiwOFZvoGzpoa1HI7cIol74y_uKbcgAepgmwmyCKOiw== 14:25:53 INFO - x-amz-version-id: MqMEl1.QtVWrFqtAfJKDe1TZkgFn37_x 14:25:54 INFO - Expected file size: 16271077 14:25:54 INFO - Obtained file size: 16271077 14:26:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:26:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:26:00 INFO - Fetch https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:26:01 INFO - Http code: 200 14:26:01 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 14:26:01 INFO - x-amz-cf-id: TIYJ2PIXhi-wtpov0ls-vuPmVITmKgu2vE9LDquJgAAky1dY6V-i7A== 14:26:01 INFO - x-amz-version-id: RYEYGk2RHNLype8CVkmPvFB02hZutDyF 14:26:01 INFO - Expected file size: 14409038 14:26:01 INFO - Obtained file size: 14409038 14:26: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')]} 14:26:02 INFO - trying https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:26:02 INFO - Downloading https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:26:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/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 14:26:05 INFO - Downloaded 109785141 bytes. 14:26:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:26:05 INFO - mkdir: /builds/slave/test/properties 14:26:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:26:05 INFO - Writing to file /builds/slave/test/properties/build_url 14:26:05 INFO - Contents: 14:26:05 INFO - build_url:https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:26:05 INFO - Running post-action listener: _resource_record_post_action 14:26:05 INFO - Running post-action listener: set_extra_try_arguments 14:26:05 INFO - [mozharness: 2016-12-22 22:26:05.718258Z] Finished download-and-extract step (success) 14:26:05 INFO - [mozharness: 2016-12-22 22:26:05.718373Z] Running populate-webroot step. 14:26:05 INFO - Running pre-action listener: _resource_record_pre_action 14:26:05 INFO - Running main action method: populate_webroot 14:26:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:26:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:26:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:26:05 INFO - '--tppagecycles', 14:26:05 INFO - '7'], 14:26:05 INFO - 'tests': ['tsvgm']}}, 14:26:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:26:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:26:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:26:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:26:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:05 INFO - 'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:26:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:26:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:05 INFO - 'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['damp', 'tps']}, 14:26:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:05 INFO - 'tests': ['damp', 'tps']}, 14:26:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['dromaeo_dom']}, 14:26:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:26:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['basic_compositor_video']}, 14:26:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:26:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['a11yr', 14:26:05 INFO - 'ts_paint', 14:26:05 INFO - 'tpaint', 14:26:05 INFO - 'sessionrestore', 14:26:05 INFO - 'sessionrestore_no_auto_restore', 14:26:05 INFO - 'tabpaint']}, 14:26:05 INFO - 'other-e10s': {'tests': ['a11yr', 14:26:05 INFO - 'ts_paint', 14:26:05 INFO - 'tpaint', 14:26:05 INFO - 'sessionrestore', 14:26:05 INFO - 'sessionrestore_no_auto_restore', 14:26:05 INFO - 'tabpaint']}, 14:26:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:26:05 INFO - 'ts_paint', 14:26:05 INFO - 'tpaint', 14:26:05 INFO - 'sessionrestore', 14:26:05 INFO - 'sessionrestore_no_auto_restore', 14:26:05 INFO - 'tabpaint']}, 14:26:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:26:05 INFO - 'ts_paint', 14:26:05 INFO - 'tpaint', 14:26:05 INFO - 'sessionrestore', 14:26:05 INFO - 'sessionrestore_no_auto_restore', 14:26:05 INFO - 'tabpaint']}, 14:26:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['a11yr', 14:26:05 INFO - 'ts_paint', 14:26:05 INFO - 'tpaint', 14:26:05 INFO - 'sessionrestore', 14:26:05 INFO - 'sessionrestore_no_auto_restore', 14:26:05 INFO - 'tabpaint']}, 14:26:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['a11yr', 14:26:05 INFO - 'ts_paint', 14:26:05 INFO - 'tpaint', 14:26:05 INFO - 'sessionrestore', 14:26:05 INFO - 'sessionrestore_no_auto_restore', 14:26:05 INFO - 'tabpaint']}, 14:26:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['tsvgx', 14:26:05 INFO - 'tsvgr_opacity', 14:26:05 INFO - 'tart', 14:26:05 INFO - 'tscrollx', 14:26:05 INFO - 'cart']}, 14:26:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:26:05 INFO - 'tsvgr_opacity', 14:26:05 INFO - 'tart', 14:26:05 INFO - 'tscrollx', 14:26:05 INFO - 'cart']}, 14:26:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:05 INFO - 'talos_options': ['--disable-e10s'], 14:26:05 INFO - 'tests': ['tp5o']}, 14:26:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:05 INFO - 'tests': ['tp5o']}, 14:26:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:05 INFO - 'talos_options': ['--disable-e10s', 14:26:05 INFO - '--xperf_path', 14:26:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:26:05 INFO - 'tests': ['tp5n']}, 14:26:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:05 INFO - 'talos_options': ['--xperf_path', 14:26:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:26:05 INFO - 'tests': ['tp5n']}}, 14:26:05 INFO - 'talos.zip': {'path': '', 14:26:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:26:05 INFO - Running post-action listener: _resource_record_post_action 14:26:05 INFO - [mozharness: 2016-12-22 22:26:05.737258Z] Finished populate-webroot step (success) 14:26:05 INFO - [mozharness: 2016-12-22 22:26:05.737389Z] Running create-virtualenv step. 14:26:05 INFO - Running pre-action listener: _resource_record_pre_action 14:26:05 INFO - Running main action method: create_virtualenv 14:26:05 INFO - Creating virtualenv /builds/slave/test/build/venv 14:26:05 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 14:26:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:26:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:26:05 INFO - Using real prefix '/tools/python27' 14:26:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:26:06 INFO - Installing distribute.............................................................................................................................................................................................done. 14:26:09 INFO - Installing pip.................done. 14:26:09 INFO - Return code: 0 14:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:26:09 INFO - Reading from file tmpfile_stdout 14:26:09 INFO - Output received: 14:26:09 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) 14:26:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:09 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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:09 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 14:26:09 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 14:26:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:09 INFO - 'HOME': '/Users/cltbld', 14:26:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:09 INFO - 'LOGNAME': 'cltbld', 14:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:09 INFO - 'MOZ_NO_REMOTE': '1', 14:26:09 INFO - 'NO_EM_RESTART': '1', 14:26:09 INFO - 'PAGER': '/bin/cat', 14:26:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:09 INFO - 'PWD': '/builds/slave/test', 14:26:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:09 INFO - 'SHELL': '/bin/bash', 14:26:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:09 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:09 INFO - 'USER': 'cltbld', 14:26:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:09 INFO - 'XPC_FLAGS': '0x0', 14:26:09 INFO - 'XPC_SERVICE_NAME': '0', 14:26:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:09 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 14:26:09 INFO - Cleaning up... 14:26:10 INFO - Return code: 0 14:26:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:26:10 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')]} 14:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:10 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')]} 14:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:10 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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:10 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 14:26:10 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 14:26:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:10 INFO - 'HOME': '/Users/cltbld', 14:26:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:10 INFO - 'LOGNAME': 'cltbld', 14:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:10 INFO - 'MOZ_NO_REMOTE': '1', 14:26:10 INFO - 'NO_EM_RESTART': '1', 14:26:10 INFO - 'PAGER': '/bin/cat', 14:26:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:10 INFO - 'PWD': '/builds/slave/test', 14:26:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:10 INFO - 'SHELL': '/bin/bash', 14:26:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:10 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:10 INFO - 'USER': 'cltbld', 14:26:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:10 INFO - 'XPC_FLAGS': '0x0', 14:26:10 INFO - 'XPC_SERVICE_NAME': '0', 14:26:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:10 INFO - Downloading/unpacking psutil>=3.1.1 14:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:26:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:26:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:26:13 INFO - Installing collected packages: psutil 14:26:13 INFO - Running setup.py install for psutil 14:26:13 INFO - building 'psutil._psutil_osx' extension 14:26:13 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 14:26:14 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 14:26:14 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 14:26:14 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 14:26:14 INFO - building 'psutil._psutil_posix' extension 14:26:14 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 14:26:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:26:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:26:14 INFO - ^ 14:26:14 INFO - 1 warning generated. 14:26:14 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 14:26:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:26:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:26:14 INFO - Successfully installed psutil 14:26:14 INFO - Cleaning up... 14:26:15 INFO - Return code: 0 14:26:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:26: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')]} 14:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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')]} 14:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:15 INFO - 'HOME': '/Users/cltbld', 14:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:15 INFO - 'LOGNAME': 'cltbld', 14:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:15 INFO - 'MOZ_NO_REMOTE': '1', 14:26:15 INFO - 'NO_EM_RESTART': '1', 14:26:15 INFO - 'PAGER': '/bin/cat', 14:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:15 INFO - 'PWD': '/builds/slave/test', 14:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:15 INFO - 'SHELL': '/bin/bash', 14:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:15 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:15 INFO - 'USER': 'cltbld', 14:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:15 INFO - 'XPC_FLAGS': '0x0', 14:26:15 INFO - 'XPC_SERVICE_NAME': '0', 14:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:26:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:26:18 INFO - Installing collected packages: mozsystemmonitor 14:26:18 INFO - Running setup.py install for mozsystemmonitor 14:26:18 INFO - Successfully installed mozsystemmonitor 14:26:18 INFO - Cleaning up... 14:26:18 INFO - Return code: 0 14:26:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:26:18 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')]} 14:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:18 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')]} 14:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:18 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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:18 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 14:26:18 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 14:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:18 INFO - 'HOME': '/Users/cltbld', 14:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:18 INFO - 'LOGNAME': 'cltbld', 14:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:18 INFO - 'MOZ_NO_REMOTE': '1', 14:26:18 INFO - 'NO_EM_RESTART': '1', 14:26:18 INFO - 'PAGER': '/bin/cat', 14:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:18 INFO - 'PWD': '/builds/slave/test', 14:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:18 INFO - 'SHELL': '/bin/bash', 14:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:18 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:18 INFO - 'USER': 'cltbld', 14:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:18 INFO - 'XPC_FLAGS': '0x0', 14:26:18 INFO - 'XPC_SERVICE_NAME': '0', 14:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:18 INFO - Downloading/unpacking jsonschema==2.5.1 14:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:26:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:26:21 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 14:26:21 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 14:26:21 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 14:26:21 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 14:26:21 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 14:26:21 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 14:26:21 INFO - Downloading functools32-3.2.3-2.tar.gz 14:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:26:21 INFO - warning: no files found matching '*.txt' 14:26:21 INFO - no previously-included directories found matching 'build' 14:26:21 INFO - no previously-included directories found matching 'dist' 14:26:21 INFO - no previously-included directories found matching '.git*' 14:26:21 INFO - Installing collected packages: jsonschema, functools32 14:26:21 INFO - Running setup.py install for functools32 14:26:21 INFO - warning: no files found matching '*.txt' 14:26:21 INFO - no previously-included directories found matching 'build' 14:26:21 INFO - no previously-included directories found matching 'dist' 14:26:21 INFO - no previously-included directories found matching '.git*' 14:26:21 INFO - Successfully installed jsonschema functools32 14:26:21 INFO - Cleaning up... 14:26:21 INFO - Return code: 0 14:26:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:26: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')]} 14:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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')]} 14:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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', '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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:26: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 functools32==3.2.3-2 14:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:21 INFO - 'HOME': '/Users/cltbld', 14:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:21 INFO - 'LOGNAME': 'cltbld', 14:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:21 INFO - 'MOZ_NO_REMOTE': '1', 14:26:21 INFO - 'NO_EM_RESTART': '1', 14:26:21 INFO - 'PAGER': '/bin/cat', 14:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:21 INFO - 'PWD': '/builds/slave/test', 14:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:21 INFO - 'SHELL': '/bin/bash', 14:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:21 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:21 INFO - 'USER': 'cltbld', 14:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:21 INFO - 'XPC_FLAGS': '0x0', 14:26:21 INFO - 'XPC_SERVICE_NAME': '0', 14:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:26:22 INFO - Cleaning up... 14:26:22 INFO - Return code: 0 14:26:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:26:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:22 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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:22 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 14:26:22 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 14:26:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:22 INFO - 'HOME': '/Users/cltbld', 14:26:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:22 INFO - 'LOGNAME': 'cltbld', 14:26:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:22 INFO - 'MOZ_NO_REMOTE': '1', 14:26:22 INFO - 'NO_EM_RESTART': '1', 14:26:22 INFO - 'PAGER': '/bin/cat', 14:26:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:22 INFO - 'PWD': '/builds/slave/test', 14:26:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:22 INFO - 'SHELL': '/bin/bash', 14:26:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:22 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:22 INFO - 'USER': 'cltbld', 14:26:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:22 INFO - 'XPC_FLAGS': '0x0', 14:26:22 INFO - 'XPC_SERVICE_NAME': '0', 14:26:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:22 INFO - Downloading/unpacking blobuploader==1.2.4 14:26:22 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 14:26:22 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 14:26:22 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 14:26:22 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 14:26:22 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 14:26:22 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 14:26:25 INFO - Downloading blobuploader-1.2.4.tar.gz 14:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:26:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:26:25 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 14:26:25 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 14:26:25 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 14:26:25 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 14:26:25 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 14:26:25 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 14:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:26:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:26:25 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 14:26:25 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 14:26:25 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 14:26:25 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 14:26:25 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 14:26:25 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 14:26:26 INFO - Downloading docopt-0.6.1.tar.gz 14:26:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:26:26 INFO - Installing collected packages: blobuploader, requests, docopt 14:26:26 INFO - Running setup.py install for blobuploader 14:26:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:26:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:26:26 INFO - Running setup.py install for requests 14:26:27 INFO - Running setup.py install for docopt 14:26:27 INFO - Successfully installed blobuploader requests docopt 14:26:27 INFO - Cleaning up... 14:26:27 INFO - Return code: 0 14:26:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26: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')]} 14:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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')]} 14:26:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:27 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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:27 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 14:26:27 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 14:26:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:27 INFO - 'HOME': '/Users/cltbld', 14:26:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:27 INFO - 'LOGNAME': 'cltbld', 14:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:27 INFO - 'MOZ_NO_REMOTE': '1', 14:26:27 INFO - 'NO_EM_RESTART': '1', 14:26:27 INFO - 'PAGER': '/bin/cat', 14:26:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:27 INFO - 'PWD': '/builds/slave/test', 14:26:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:27 INFO - 'SHELL': '/bin/bash', 14:26:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:27 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:27 INFO - 'USER': 'cltbld', 14:26:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:27 INFO - 'XPC_FLAGS': '0x0', 14:26:27 INFO - 'XPC_SERVICE_NAME': '0', 14:26:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:27 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-rlcDgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:27 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-m2EweN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:27 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-vOf9Er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:27 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-_JU_Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-zwiUg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-xgkZtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-mRZ3l0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-0mTu87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-VLNZBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-zTnhj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-WoM3Ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-HTBFaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:28 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-9OolaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:26:29 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-dayZZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:29 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-yrMg8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:26:29 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-sY_mmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:26:29 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-tqV1pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:26:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:26:29 INFO - Running setup.py install for manifestparser 14:26:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:26:29 INFO - Running setup.py install for mozcrash 14:26:29 INFO - Running setup.py install for mozdebug 14:26:29 INFO - Running setup.py install for mozdevice 14:26:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Running setup.py install for mozfile 14:26:30 INFO - Running setup.py install for mozhttpd 14:26:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Running setup.py install for mozinfo 14:26:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Running setup.py install for mozInstall 14:26:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Running setup.py install for mozleak 14:26:30 INFO - Running setup.py install for mozlog 14:26:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:26:30 INFO - Running setup.py install for moznetwork 14:26:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:26:31 INFO - Running setup.py install for mozprocess 14:26:31 INFO - Running setup.py install for mozprofile 14:26:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:26:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:26:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:26:31 INFO - Running setup.py install for mozrunner 14:26:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:26:31 INFO - Running setup.py install for mozscreenshot 14:26:31 INFO - Running setup.py install for moztest 14:26:31 INFO - Running setup.py install for mozversion 14:26:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:26:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:26:32 INFO - Cleaning up... 14:26:32 INFO - Return code: 0 14:26:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:32 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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:32 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 14:26:32 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 14:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:32 INFO - 'HOME': '/Users/cltbld', 14:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:32 INFO - 'LOGNAME': 'cltbld', 14:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:32 INFO - 'MOZ_NO_REMOTE': '1', 14:26:32 INFO - 'NO_EM_RESTART': '1', 14:26:32 INFO - 'PAGER': '/bin/cat', 14:26:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:32 INFO - 'PWD': '/builds/slave/test', 14:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:32 INFO - 'SHELL': '/bin/bash', 14:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:32 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:32 INFO - 'USER': 'cltbld', 14:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:32 INFO - 'XPC_FLAGS': '0x0', 14:26:32 INFO - 'XPC_SERVICE_NAME': '0', 14:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-GVUbEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:33 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)) 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-1aXTj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:33 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)) 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-E9hU3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:33 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)) 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-c6dLUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:33 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)) 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-GKkhtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:33 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)) 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-sl0xrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:33 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)) 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-2PmJXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:33 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)) 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-dNFti_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:33 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)) 14:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:33 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-m_7BNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:34 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)) 14:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-63GoG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:34 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)) 14:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-3oTqWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:34 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)) 14:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-nlQGlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:34 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)) 14:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-x6m28z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:26:34 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)) 14:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:26:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-pznmOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:26:34 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)) 14:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-jBEANv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:34 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)) 14:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:26:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-_bvecl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:26:34 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)) 14:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:26:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-eO9FCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:26:34 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)) 14:26:34 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)) 14:26:34 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)) 14:26:34 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)) 14:26:34 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)) 14:26:34 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)) 14:26:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:26:34 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 14:26:34 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 14:26:34 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 14:26:34 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 14:26:34 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 14:26:34 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 14:26:37 INFO - Downloading blessings-1.6.tar.gz 14:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:26:38 INFO - Installing collected packages: blessings 14:26:38 INFO - Running setup.py install for blessings 14:26:38 INFO - Successfully installed blessings 14:26:38 INFO - Cleaning up... 14:26:38 INFO - Return code: 0 14:26:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:26:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:26:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:26:38 INFO - Reading from file tmpfile_stdout 14:26:38 INFO - Current package versions: 14:26:38 INFO - blessings == 1.6 14:26:38 INFO - blobuploader == 1.2.4 14:26:38 INFO - docopt == 0.6.1 14:26:38 INFO - functools32 == 3.2.3-2 14:26:38 INFO - jsonschema == 2.5.1 14:26:38 INFO - manifestparser == 1.1 14:26:38 INFO - mozInstall == 1.12 14:26:38 INFO - mozcrash == 1.0 14:26:38 INFO - mozdebug == 0.1 14:26:38 INFO - mozdevice == 0.48 14:26:38 INFO - mozfile == 1.2 14:26:38 INFO - mozhttpd == 0.7 14:26:38 INFO - mozinfo == 0.9 14:26:38 INFO - mozleak == 0.1 14:26:38 INFO - mozlog == 3.4 14:26:38 INFO - moznetwork == 0.27 14:26:38 INFO - mozprocess == 0.23 14:26:38 INFO - mozprofile == 0.28 14:26:38 INFO - mozrunner == 6.13 14:26:38 INFO - mozscreenshot == 0.1 14:26:38 INFO - mozsystemmonitor == 0.3 14:26:38 INFO - moztest == 0.7 14:26:38 INFO - mozversion == 1.4 14:26:38 INFO - psutil == 3.1.1 14:26:38 INFO - requests == 1.2.3 14:26:38 INFO - wsgiref == 0.1.2 14:26:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:38 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')]} 14:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:38 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')]} 14:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:38 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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:38 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 14:26:38 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 14:26:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:38 INFO - 'HOME': '/Users/cltbld', 14:26:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:38 INFO - 'LOGNAME': 'cltbld', 14:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:38 INFO - 'MOZ_NO_REMOTE': '1', 14:26:38 INFO - 'NO_EM_RESTART': '1', 14:26:38 INFO - 'PAGER': '/bin/cat', 14:26:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:38 INFO - 'PWD': '/builds/slave/test', 14:26:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:38 INFO - 'SHELL': '/bin/bash', 14:26:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:38 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:38 INFO - 'USER': 'cltbld', 14:26:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:38 INFO - 'XPC_FLAGS': '0x0', 14:26:38 INFO - 'XPC_SERVICE_NAME': '0', 14:26:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 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)) 14:26:38 INFO - Cleaning up... 14:26:38 INFO - Return code: 0 14:26:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:26:38 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')]} 14:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:38 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')]} 14:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:38 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 0x106086490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ffc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb933599ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, '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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 14:26:38 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 14:26:38 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 14:26:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:26:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:38 INFO - 'HOME': '/Users/cltbld', 14:26:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:38 INFO - 'LOGNAME': 'cltbld', 14:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:38 INFO - 'MOZ_NO_REMOTE': '1', 14:26:38 INFO - 'NO_EM_RESTART': '1', 14:26:38 INFO - 'PAGER': '/bin/cat', 14:26:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:38 INFO - 'PWD': '/builds/slave/test', 14:26:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:38 INFO - 'SHELL': '/bin/bash', 14:26:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:26:38 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:26:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:38 INFO - 'USER': 'cltbld', 14:26:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:38 INFO - 'XPC_FLAGS': '0x0', 14:26:38 INFO - 'XPC_SERVICE_NAME': '0', 14:26:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:26:39 INFO - Cleaning up... 14:26:39 INFO - Return code: 0 14:26:39 INFO - Running post-action listener: _resource_record_post_action 14:26:39 INFO - Running post-action listener: _start_resource_monitoring 14:26:39 INFO - Starting resource monitoring. 14:26:39 INFO - [mozharness: 2016-12-22 22:26:39.112831Z] Finished create-virtualenv step (success) 14:26:39 INFO - [mozharness: 2016-12-22 22:26:39.113886Z] Running install step. 14:26:39 INFO - Running pre-action listener: _resource_record_pre_action 14:26:39 INFO - Running main action method: install 14:26:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:26:39 INFO - Reading from file tmpfile_stdout 14:26:39 INFO - Detecting whether we're running mozinstall >=1.0... 14:26:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:26:39 INFO - Reading from file tmpfile_stdout 14:26:39 INFO - Output received: 14:26:39 INFO - Usage: mozinstall [options] installer 14:26:39 INFO - Options: 14:26:39 INFO - -h, --help show this help message and exit 14:26:39 INFO - -d DEST, --destination=DEST 14:26:39 INFO - Directory to install application into. [default: 14:26:39 INFO - "/builds/slave/test"] 14:26:39 INFO - --app=APP Application being installed. [default: firefox] 14:26:39 INFO - mkdir: /builds/slave/test/build/application 14:26:39 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'] 14:26:39 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 14:27:10 INFO - Reading from file tmpfile_stdout 14:27:10 INFO - Output received: 14:27:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:27:10 INFO - Running post-action listener: _resource_record_post_action 14:27:10 INFO - [mozharness: 2016-12-22 22:27:10.881447Z] Finished install step (success) 14:27:10 INFO - [mozharness: 2016-12-22 22:27:10.881626Z] Running run-tests step. 14:27:10 INFO - Running pre-action listener: _resource_record_pre_action 14:27:10 INFO - Running main action method: run_tests 14:27:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:27:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:27:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:27:10 INFO - 2016-12-22 14:27:10.896 screenresolution[1625:7732] starting screenresolution argv=screenresolution get 14:27:10 INFO - 2016-12-22 14:27:10.899 screenresolution[1625:7732] Display 0: 1600x1200x32@60 14:27:10 INFO - 2016-12-22 14:27:10.904 screenresolution[1626:7736] starting screenresolution argv=screenresolution list 14:27:10 INFO - Available Modes on Display 0 14:27:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:27:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:27:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:27:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:27:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:27:11 INFO - Intel Iris: 14:27:11 INFO - Chipset Model: Intel Iris 14:27:11 INFO - Type: GPU 14:27:11 INFO - Bus: Built-In 14:27:11 INFO - VRAM (Dynamic, Max): 1536 MB 14:27:11 INFO - Vendor: Intel (0x8086) 14:27:11 INFO - Device ID: 0x0a2e 14:27:11 INFO - Revision ID: 0x0009 14:27:11 INFO - Displays: 14:27:11 INFO - Display: 14:27:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:27:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:27:11 INFO - Display Serial Number: 1600x1200 60 14:27:11 INFO - Main Display: Yes 14:27:11 INFO - Mirror: Off 14:27:11 INFO - Online: Yes 14:27:11 INFO - Rotation: Supported 14:27:11 INFO - Return code: 0 14:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:27:11 INFO - Python 2.7.3 14:27:11 INFO - Return code: 0 14:27:11 INFO - grabbing minidump binary from tooltool 14:27: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')]} 14:27:11 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 0x105e9ad30>, '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 0x105d00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e92b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:27:11 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 14:27:11 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 14:27:11 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 14:27:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:27:11 INFO - Return code: 0 14:27:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:27:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:27:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:27:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:27:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:27:11 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/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--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 14:27:11 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/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0169 --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 14:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2HSdhoTFhr/Render', 14:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:11 INFO - 'HOME': '/Users/cltbld', 14:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:11 INFO - 'LOGNAME': 'cltbld', 14:27:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:27:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:11 INFO - 'MOZ_NO_REMOTE': '1', 14:27:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:27:11 INFO - 'NO_EM_RESTART': '1', 14:27:11 INFO - 'PAGER': '/bin/cat', 14:27:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:11 INFO - 'PWD': '/builds/slave/test', 14:27:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:11 INFO - 'SHELL': '/bin/bash', 14:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLYH7aw9TB/Listeners', 14:27:11 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:11 INFO - 'USER': 'cltbld', 14:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:11 INFO - 'XPC_FLAGS': '0x0', 14:27:11 INFO - 'XPC_SERVICE_NAME': '0', 14:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:11 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/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--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 14:27:11 INFO - mozversion application_buildid: 20161222121953 14:27:11 INFO - mozversion application_changeset: 20654fa125a90d0ca839bbf15b81612b8667c43b 14:27:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:27:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:27:11 INFO - mozversion application_name: Firefox 14:27:11 INFO - mozversion application_remotingname: firefox-dev 14:27:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:27:11 INFO - mozversion application_vendor: Mozilla 14:27:11 INFO - mozversion application_version: 52.0a2 14:27:11 INFO - mozversion platform_buildid: 20161222121953 14:27:11 INFO - mozversion platform_changeset: 20654fa125a90d0ca839bbf15b81612b8667c43b 14:27:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:27:11 INFO - mozversion platform_version: 52.0a2 14:27:11 INFO - using testdate: 1482445631 14:27:11 INFO - actual date: 1482445631 14:27:11 INFO - starting webserver on 'localhost:49249' 14:27:11 INFO - SUITE-START | Running 2 tests 14:27:11 INFO - TEST-START | dromaeo_css 14:27:11 INFO - operating with platform_type : mac_ 14:27:11 INFO - Initialising browser for dromaeo_css test... 14:27:11 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpVrLMFA/profile http://localhost:49249/getInfo.html) 14:27:14 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 14:27:14 INFO - PROCESS | 1636 | colorDepth:24 14:27:14 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 14:27:14 INFO - PROCESS | 1636 | __metrics 14:27:14 INFO - PROCESS | 1636 | 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" 14:27:14 INFO - PROCESS | 1636 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:27:15 INFO - TEST-INFO | 1636: exit 0 14:27:15 INFO - Browser initialized. 14:27:15 INFO - Running cycle 1/1 for dromaeo_css test... 14:27:15 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpVrLMFA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:27:27 INFO - PROCESS | 1638 | RSS: Main: 222932992 14:27:27 INFO - PROCESS | 1638 | 14:28:18 INFO - PROCESS | 1638 | 1482445698396 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] 14:30:32 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 14:30:33 INFO - PROCESS | 1638 | RSS: Main: 336146432 14:30:33 INFO - PROCESS | 1638 | 14:33:36 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 14:33:37 INFO - PROCESS | 1638 | RSS: Main: 351879168 14:33:37 INFO - PROCESS | 1638 | 14:36:39 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 14:36:40 INFO - PROCESS | 1638 | RSS: Main: 321429504 14:36:40 INFO - PROCESS | 1638 | 14:39:42 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 14:39:43 INFO - PROCESS | 1638 | RSS: Main: 315985920 14:39:43 INFO - PROCESS | 1638 | 14:42:46 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 14:42:47 INFO - PROCESS | 1638 | RSS: Main: 307720192 14:42:47 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 14:45:50 INFO - PROCESS | 1638 | RSS: Main: 447479808 14:45:50 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | __start_tp_report 14:45:50 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 14:45:50 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 14:45:50 INFO - PROCESS | 1638 | |i|pagename|runs| 14:45:50 INFO - PROCESS | 1638 | |0;dojo.html;3236.838158092095;3269.5586095877065;3270.0189943017094;3286.5891763529557;3330.9500357494626;5441.401445840959;5494.066008778507;5496.065668836308;5499.697516636588;5515.944840551593;5521.254630624864;5543.223948647183;5603.355614104379;5610.018246806802;5685.1756495308255;5108.106081435743;5131.563817075553;5147.48525147486;5219.973900130513;5286.867828304304;4211.68412369072;5110.361204849394;5131.230315452674;5137.126688462951;5156.458571849939;5166.664166829163;5181.222816577525;5219.060569097561;5220.504052115043;5238.685678859261;5190.143626301656;5192;5215.191645294985;5250.894982100336;5251.396089449724;4972.627052971042;5019.447860735316;5138.820141295074;5157;5176.844694659167;5509.173623956399;5533.861653458655;5638.097904335286;5642.492175704166;5683.943160568383;6110.358412366726;6123.387661233885;6124.479419249369;6143.969280153616;6214.005759078571;6210.192674952273;6261.154744109578;6266.686665666744;6271.592346497463;6282.842928926813;5455.699936503451;5565.66606003633;5680.829575112753;5728.940146072948;5775;6299.275583307886;6321.715522801485;6330.746770129143;6336.904946425807;6338.873222535523;6064.36324185963;6087.421694938974;6092.695365231721;6105.572609917352;6109.633421994695;6087.1173679816175;6102.389761023951;6121.81881567809;6131.193168498116;6235.314115447297;6121.785737499255;6136.304638670889;6156.29202641702;6157.353477884847;6166.260048794174;6110.511159107261;6147.44672979434;6154.292256390483;6160.291566469824;6184.752609895554;4785.1625965455905;4844.781984810622;4861.513848615181;4905.607551395879;4907.6564640475535;4410.514843367227;4437.156940181355;4442.844500442469;4454.5990860822685;4471.597556219891;5659;5661.858453538694;5674.517665998354;5675.829725108253;5675.943240567624;6130.031268545464;6132.9980441463495;6163.506919446433;6165.167702360124;6170.598911070766;5636.774529018875;5697.373288938214;5725.2843394575675;5725.341585717612;5741.224934633771;5352.4915133062295;5358.356997160366;5363.007843548956;5363.919541206885;5379.54273886724;6221.782237621662;6292.2449306081735;6296.150019747367;6303.873922521616;6478.838029049406;6030.276366835832;6137.070562331885;6165.951788196107;6199.597026193283;6261.812145635638;6286.27707813589;6292.748290068529;6298.149749783721;6302;6309.148264984168;6147.631142131487;6152.16945712341;6164.229471316085;6165.260168779596;6165.691715414301;6054.878902422015;6093.451589356801;6114.449440885012;6124.869201255775;6218.595791273553;6089.2997305310455;6099.359567245376;6116.335771508327;6117.99053156242;6136.969315153396;5921.171036055042;5964.194833697396;6044.9395506044375;6075.756969721162;6115.235595550556;5706.914396284142;5765.7693692253515;5780.277465316835;5899.498542623754;5924.7037648118285;5965.70171491415;6072.78745243932;6085.939140608714;6090.3605121461605;6125.724342404692;5888.911666324922;5901.970490147522;5906.616069955439;5907.556933230111;5955.6724380157775 14:45:50 INFO - PROCESS | 1638 | |1;ext.html;445.50103883650314;495.8041573578439;496.55806332364205;498.44423467833354;559.0663591801693;29792.553111703313;39920.60079399205;40025.199496010064;40029.99925001873;40035.99910002244;33837.15407114823;40864.38703419451;41405.37891931624;41494.58505414945;41541.37687934683;9273.397229180115;9451.763705907339;9454.338196326225;9460.432374057544;9464.053594640549;9272.211861991738;9384.953075234615;9385.906140938605;9388.812223755554;9417.81164376712;9367.39111957724;9417.717468475958;9422.811543769121;9435.15083642472;9438.528073596293;8360.829483872263;9291.48896810675;9367.906320936772;9378.109079637457;9379.390339627971;9058.23005044572;9395.201407880339;9397.859032114524;9403;9407.43555386679;39273;39479.21041579181;39550.011249718984;39613.603863961565;39619.40570891439;3105.2702614885484;3302.091924720697;3312.8840490582716;3348.41402754517;3670.7063434925144;26888.327791805164;28735.856320718263;29050.12849614515;29076.728185002154;30583.92956246543;2925.7951943363973;3223.693749093856;3223.9844448998724;3236.1909522619344;3241.9027429177163;348870;350352;350489;350524;351817.24091379636;59624.70187649034;59905;60012.49968750729;60074.699626501584;60124.69937650371;34695.82652086774;35723.46414803728;63430.68284658547;63439;63602.68198658977;13157.671058223621;15366.462173824086;15810.367585296459;15911.920440397955;15945.963512371658;12150.331731754839;12223.205491643015;12231.082668799876;12239.755204895851;12256.203346782431;18850.81149188518;18882.24471021171;18906.716399254023;18914.527136821413;18937.90531047336;6977.33715297046;7179.712811487482;7194.532355396883;7375.483716139926;7420.740274090381;742.3319012888336;895.3150839607705;923.3305853256336;952.0859974424492;983.9950800245953;28645.57031644527;28883.277918052216;29532;29631.555526667118;29729.702702972692;742.5322047110286;1001.8647482589903;1035.7358873487212;1146.4038699876019;1156.5662876421343;12471.939885109687;12837.743245135045;14236.789872860716;14851.554453366416;15539;647559;688836;690473;690539;691614;57972;58499.83000339969;58576.707116464146;58745.412545873995;58896;1124.6232512108536;1204.2473454091194;1256.1960345378964;1318.9868101318864;1339.8459177194748;28223;30728.385432291678;30878.91923782657;31265;31432.528512072793;49569.25646115383;50363.24455133249;50381.49618503915;50389.24416133688;50422.99154016826;526560;527909;527925;528572;530472.3476382593;21374.57250854943;25502.489950201263;26880;27773.166804995883;27896.302592435353;45590.54409455863;46327.768361159324;46415.76792116018;46572;46584.53415465802;31099.84450077735;37858.07844980135;38413.423798642485;38446.423303649906;38454;37468.2506349866;38180.42729359117;38190;39135.41296880606;39600.00999975024;32965.67034329722;32969.01092967215;33035.669643303256;34722.131946701535;37969.671061512716;22618.886905565367;33425.83287083549;33878.830605846815;34780;34861.82569087138;24330;24535;25276.74723252744;25664.23007309709;26217.868910655325 14:45:50 INFO - PROCESS | 1638 | |2;jquery.html;368.52092280035953;377.10813925067225;380.8419505905049;390.16115351993227;399.31716764332987;15021.399144034225;15333.156676382794;17991.820081799193;19932.402027939166;20138.99999999998;14649.780253296209;16195.271212795424;17561.73657395137;19231.615367692637;19737.802621973777;5078.263651770491;5550.944490555084;5776.797812076578;5863.120531920204;5874.1776151338845;5060.772265248054;5213.765380557865;5710.800121995731;5728.856778580548;5806.361300256968;5719.28508936383;5752;5761.567882408815;5767.250257466523;5772.913406298909;5556.605556605552;5578.944210557904;5646.632968857011;5664.773409063674;5674.404187560288;4989.600831933437;4997.375328083989;5007.574356179729;5010.123228435009;5010.348654674905;23377.649335259986;23920.521589568285;24346.904389302523;24675.753242467526;24877.253682389557;2249.606318894187;2317.814574834026;2350.9412264693347;2510.418436385084;2957.438086763508;9635.325527213097;10155.339902906362;14136.434542618386;14316.57050288493;14682.70634587313;1812.3022636284993;1847.4827048426462;1861.553227225456;1878.7275845002534;1905.6569817432855;105266;109158;112622;118175;118625.81374186234;9451.905480945103;9693;9989.40169572865;10000.249981251436;10140.239482038876;12160.391980401122;12387.62837114888;12419.75160496785;12422;12439.75120497585;7888.40836937232;7940.205979402013;8040.035195776544;8140.5929703514585;8215.753527394188;7960.6417711201975;8065.193480652005;8198;8264.132266112092;8496.957515212385;3799.848006079726;3924.862629807943;3950.644442000234;3985.4221137934837;4006.399040144002;1777.7688900443118;1880.4546681462223;1922.1830721943118;1925.7303977443169;1948.4057362504288;721.585088574072;800.2477670989251;825.3397282174236;856.4647095565272;874.1346067393235;16974.91512542454;17322.740158897628;19121.852688838713;19999.500012499513;21281.042353094148;872.581161042703;913.2830727878584;926.3700683535261;932.4125800745487;977.1889331854544;8936.374453788172;10967.341959482457;10976;10978.890211097787;11014.559417623206;581381.0930945318;584225;592758;600268;600788;124801;126624;130511.11355084588;130894;135040.32479837537;946.0539460539461;953.8664586957832;962.3696478806393;965.4014511003223;968.8982656820932;12214.022878169548;15266.160361180242;19159.233630654617;19180.232790688216;19249.807501925363;50987.49012509976;51639.967200655024;53193.20210196928;54229;54465.91068178693;68084.31915680978;71370;71852.64073679807;73251.26748732658;74496.13759656053;7853.646585903533;7917.802054948673;8024.518528888286;8204.671813127408;8388.622511986854;6773.390394864486;9338.346315757832;10939.179561532757;10941.945290273497;12461.127721059438;10296.330738501973;10845.349279042966;10996.670099897015;11276.210665253353;11290.661280161608;10395.16838652921;10699.679009629723;10879.238453308193;11024.559017639525;11241.718957025812;10893.455327233765;11036.006759391694;11138.27601205919;11320.433978301216;11455.484503197542;6357.90463143044;6402.231732191981;8137.918620813953;8233.547154906537;8366.079731229682;9150.771230719285;10666.679999600025;10846.023857852831;10856.185786065918;11174.776504469703 14:45:50 INFO - PROCESS | 1638 | |3;mootools.html;1458.6572155543445;1492.6044598181481;1648.6537827056318;1810.4930619426548;1832.221305944974;8001.359891208704;8405.285550728195;8410.990681118266;8417.70538031169;8433.45182563133;7467;7710.190430004853;7728.343090837286;8037.678492860293;8220.794480137985;2318.7217533895914;2345.2026311054237;2364.3498038039593;2365.550545396369;2367.609344458171;2354.4231663242444;2370.845895016827;2386.2364043506086;2402.459446624513;2403.387136280246;2268.965965510519;2307.9192228272013;2332.5917964356254;2345.2846881701075;2417.746136655652;2302.539492101578;2308.3075077476856;2323.1984965186984;2326.7440581536016;2329.743728189898;2323.012719594166;2343.0627748900406;2348.1664009276633;2349.2130136404326;2350.1656911796235;7990.200979902022;8046.517208967422;8061;8066.4353495255355;8066.7579972600915;5056.165732654108;5211.895762084737;5220.4779522047875;5222.895542089137;5226;2797.678266999301;2947.970520294791;3131;3452;3464.6708562686504;1037.5745944162932;1054.6835949215206;1068.0921216965517;1077.7467295185625;1083.377058191543;10673.679789606247;10708.464576771208;10810.081143102845;10812.972767587145;10815.405152716598;4461;4602.815887364535;4676.69601475903;4721.551452612065;4731.95268047322;9320.394174378644;9329.207017403462;9331.16019558243;9335;9345.766355841159;4397.73613583186;5153.561947234452;5161.071007218661;5162.8967420652125;5169.948300517022;3927.4697915781003;4208.642265407475;4736.621070314367;4744.501827308152;4792.976035119872;3746.194568167857;3774.150816066418;3820.3123437781464;3855.7108216883844;3872.903177420587;1262.9873701263054;1264.8418947631546;1268.9111762176558;1282.0640932119604;1283.5507572349602;807.5760225881342;894.0121166111484;895.4582477601087;905.8233644439402;909.6179604566098;5459.808906688327;5505.201745746925;5615.101583746581;5668.9716551417805;5789.305283366023;435.9978200108979;436.5394508793254;911.7173676160412;954.1889394015031;956.5982287439292;3758.755680880734;3762.755420897633;3775.867844625425;3789.607664732624;3874.7093967952515;9056.366054376262;10018.549165287579;10102.747431314276;10117.988201179824;10132.594696212069;10076.395416275047;10079.395236285847;10091.899081009096;10100.545475453622;10112.292139550162;7629.809254768564;7765.72819951299;7786.688532458751;7790.493617914817;7800.843983120306;5252.632315737862;5340.679559226459;5402.59480538969;5559.2217089606675;5564.19319198722;9208.815823683623;9230.538473076454;9330.813383732424;9424.198943089914;9469.147776699854;9386.718398448058;9415.19970802462;9447.291453140904;9487.905120948702;9532.571034303335;6235.844103897439;6269.279032911274;6337.7781777637565;6480.773172938924;6485.44873685742;6696.866062678817;7031.929680703128;7940.205979402013;8022.679092836222;8031.1567285434185;6998.090248267693;7144.4641651877355;7202.423806095395;7209.459290553124;7246.7101315946775;6772.627505487048;6972.337627925339;6985.5808651481075;7014.68433920485;7044.753433629799;6347.873042539216;6524.119243902012;6606.735730570724;6609.834754131185;6670.366315200048;4699.506551812011;4796.136695394723;4815.711057336571;4850.248211527219;4859.562639362474;4054.7972601369224;4758.357621721023;4787.3776409066595;4972.0801651694555;5042.117629414911 14:45:50 INFO - PROCESS | 1638 | |4;prototype.html;258.69603216220935;278.8368804249514;299.37132022752223;310.54970293075047;311.30734116590577;4646.9079766254945;5104.626348747832;5199.168133098705;5292.417834038252;5312.521873031426;5103.413107492639;5196.948030519694;5226.216067589863;5236.581073514119;5245.239440281157;3438.690517853399;4037.4145748866395;4058.289799285128;4098.549159592442;4129.339305711087;3539.3629146753574;3910.687145028405;4097.590240975908;4102.733322334054;4109.4246805447265;3681.5582130144285;4034.475518182632;4058.8579399721016;4068.9796551017353;4113.33409968963;3573.4997100405963;3810.2189051244427;3909.5113110861284;4029.9597004030024;4063.1873625274766;3173.873045078194;3223.258650510396;3298.983505082483;3538.8236234715464;3564.7682900611376;4772.403449568804;5123.385193776733;5151.587872970191;5204.245384419276;5207.453217412193;4194.622483976426;4528.139653465831;4559.749213793273;4632.490426053131;4642.908916404642;4366.934495982563;4802.5197480252045;5521.972390138064;5551.3893471718075;5556.86107847303;3793.563740169888;3837.369118124786;3839.5392552893554;3861.942070868939;3875.65119139276;4873.756312184377;5810.8256752297075;5874.6181498202905;5876.382979787104;6013.278406591237;3714.294284086069;3798.1454172810895;3830.3105441020325;3859.286032084073;3879.9612003880166;3266.379387916288;3491.284286721216;3886.8639597613947;3912.237113762789;3961.623645753649;3690.5642276098183;3995.001249687578;4498.572635599613;4503.437070366204;4534.841280555164;5519.779208831682;5535.280413546294;5544.63959842609;5568.220449137043;5616.1014237721765;1147.9152201169982;1203.4524291447547;1233.9259644421363;1261.2306493039239;1278.8657190994998;837.7738010737066;844.4420045016934;864.3301441382853;870.1950695606539;875.5622188905547;4290.377895205178;4318.330658747899;4423.690341676116;4635.58279754817;4847.957689096861;4448.488423831236;4608.6082682971655;5082.415522214993;5186.81846135391;5231.866825208119;4386.385905973167;4403.647708183337;4596.839110631112;4836;4907.607391408679;2465.7041155061506;2514.6856642919633;2793.4133831895124;2865.7390748070784;2875.597416361711;5616.354119276336;5628.774849005994;5644.6330988485615;5650.378458369576;5773;5502.80740174092;5568.860778480489;5571.470710282517;5596.300462442195;6024.879502409927;5037.798488060436;5634.281374292947;6006.819795406145;6012.39876012404;6030.547708921936;5076.441591425014;5147.6654017488745;5805.82582522525;5822.970885145547;5927.547750801082;4698.506656800987;5468.56251499887;5519.420460851553;5732.34078081026;5796.246487956539;5026.235570436318;5047.911191934545;5931.13998470228;5974.520573961757;6032.306284777132;3346.5816772903386;3698.537682789651;3833.4058220975794;3857.556381016219;3905.4922860028014;5663.801766938137;5758.596898217084;5807.37039350406;5872.559558033079;5873.353931067664;4780.832670856504;5668.404817494269;5695.487406133468;5731.570132240182;5734.741936612946;4945.97527012377;5204.323437953042;5396.163594642837;5534.52956498686;5625.88748225025;4586.2661974083985;5007.5993920487;5478.178273259043;5479.397266300624;5487;5267.762950667306;5581.297704200096;5610.485169004428;5646.887062258814;5688.175214593944;5474.675006744627;5482.341064583754;5500.614956952975;5572.832815015556;5575.1880638791745 14:45:50 INFO - PROCESS | 1638 | |5;yui.html;703.5919166883207;708.2917082917086;745.0761155115347;770.4452793988327;783.4006984656735;13845.792313115298;14300;14363.210023448706;14497.782533262009;14499.420023199073;12555.309457979807;12631.557895473661;12640.115191936567;12661.30362830044;12667.493300268;640.3372509452718;655.2726473614284;656.6880731652475;772;797.0634504457269;659.1200747002733;664.694240649302;769.5228958045991;799.4843326054691;802.1296892871242;843.9071702112763;845.3870943565927;845.6279237135672;846.2510678049939;846.2510678049939;661.4443867151616;684.8698747238009;839.8782176584392;840.5587066789923;840.7940054686579;599.1073300781848;615.5414216408759;617.9443850053517;620.9006558950591;622.5735371270649;12631.178973366794;12656.746865062647;12685.873141268654;12741.980641548747;12751.87248127516;11825;11967.40162991847;11987;12094.516219351215;12358.567450139291;11647.276048284619;11692.356920369377;11747.823782643267;11968.581099661471;12128.45421956014;3818.236352729465;4374.015846434559;4407.515173330931;4490.730556166608;4518.796654150538;84615.15384846195;84847.57576212079;84979.57510212471;84988;85561;9999;10033.097021267975;10082.445465499468;10119.544620492095;10120.392776433437;18944.71582926257;18981.905090474458;19083.61832763337;19091.904540477488;19154.52113697169;6797.830054248683;7289.453291003196;7389.261073892675;7458.73894413693;7491.925080749232;4024.396340548941;4138.213739389506;4165.729227600196;4315.4605674290715;4328.653707703376;2708.268767432782;3093.6906309368883;3164.351307981858;3171.4291427542803;3184.952225716616;591.9704014799242;592.6977241606726;596.3946594206883;600.9008513595295;611.0925275965188;488.4992882295675;492.72653677208774;533.9012282727708;596.8836076965036;603.4478452216173;7774.611269436506;8554.91445085541;8567;8599.527026013504;8823.924096559202;608.6439432931785;614.1494030767419;620.8013435700623;624.950003999679;629.1946308724839;3934.960650393459;4117.21772863155;4179.958200418017;4201.4729870001365;4212.789360531961;82513.58743206246;111481.44259278652;111543;111585.44207278911;114565.42717286361;73830;78422;82429.17570824215;96815;98539;75887;79781;80354;82978;84104.57947710344;7791.92208077912;8384.83230335402;8840.513771742359;9314.347995640512;9337.405099036783;38159.809200953816;38555.6144438552;46393.07213855637;46552.534474656175;52510.94978100493;67429.66285168739;72470.63764681143;72795.63602181955;73708;74254.02983880584;11825.822612660644;11876.465559049691;12055.879441205476;12179.330136842558;12273.69315767113;12959.287239201934;13830.65423364424;13893.58319250424;14484.420623175376;14648.926755366156;11993.700157496132;12376.566820161252;12911.483540658268;12956.222626642431;12982.548784193787;12178.75642487163;12275.93862030684;12950.093493455744;12955;13124.278164701032;12204.511819527119;12456;12574.497020119094;13368.197908125543;13395.531156409479;10377.584896604052;10548.578056877639;10596.470176491299;10777.946110269711;10789.784204315712;10156.476528520781;10716.89283107159;10834.891651083704;11107.418998070745;11131.332120072822 14:45:50 INFO - PROCESS | 1638 | __end_tp_report 14:45:50 INFO - PROCESS | 1638 | __start_cc_report 14:45:50 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,1607 14:45:50 INFO - PROCESS | 1638 | __end_cc_report 14:45:50 INFO - PROCESS | 1638 | __startTimestamp1482446750578__endTimestamp 14:45:50 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | ------- Summary: start ------- 14:45:50 INFO - PROCESS | 1638 | Number of tests: 6 14:45:50 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5687.45 Median:5907.56 stddev:650.41 (11.0%) stddev-sans-first:623.34 14:45:50 INFO - PROCESS | 1638 | Values: 3236.8 3269.6 3270.0 3286.6 3331.0 5441.4 5494.1 5496.1 5499.7 5515.9 5521.3 5543.2 5603.4 5610.0 5685.2 5108.1 5131.6 5147.5 5220.0 5286.9 4211.7 5110.4 5131.2 5137.1 5156.5 5166.7 5181.2 5219.1 5220.5 5238.7 5190.1 5192.0 5215.2 5250.9 5251.4 4972.6 5019.4 5138.8 5157.0 5176.8 5509.2 5533.9 5638.1 5642.5 5683.9 6110.4 6123.4 6124.5 6144.0 6214.0 6210.2 6261.2 6266.7 6271.6 6282.8 5455.7 5565.7 5680.8 5728.9 5775.0 6299.3 6321.7 6330.7 6336.9 6338.9 6064.4 6087.4 6092.7 6105.6 6109.6 6087.1 6102.4 6121.8 6131.2 6235.3 6121.8 6136.3 6156.3 6157.4 6166.3 6110.5 6147.4 6154.3 6160.3 6184.8 4785.2 4844.8 4861.5 4905.6 4907.7 4410.5 4437.2 4442.8 4454.6 4471.6 5659.0 5661.9 5674.5 5675.8 5675.9 6130.0 6133.0 6163.5 6165.2 6170.6 5636.8 5697.4 5725.3 5725.3 5741.2 5352.5 5358.4 5363.0 5363.9 5379.5 6221.8 6292.2 6296.2 6303.9 6478.8 6030.3 6137.1 6166.0 6199.6 6261.8 6286.3 6292.7 6298.1 6302.0 6309.1 6147.6 6152.2 6164.2 6165.3 6165.7 6054.9 6093.5 6114.4 6124.9 6218.6 6089.3 6099.4 6116.3 6118.0 6137.0 5921.2 5964.2 6044.9 6075.8 6115.2 5706.9 5765.8 5780.3 5899.5 5924.7 5965.7 6072.8 6085.9 6090.4 6125.7 5888.9 5902.0 5906.6 5907.6 5955.7 14:45:50 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:65299.99 Median:38794.71 stddev:144082.24 (371.4%) stddev-sans-first:144404.14 14:45:50 INFO - PROCESS | 1638 | Values: 445.5 495.8 496.6 498.4 559.1 29792.6 39920.6 40025.2 40030.0 40036.0 33837.2 40864.4 41405.4 41494.6 41541.4 9273.4 9451.8 9454.3 9460.4 9464.1 9272.2 9385.0 9385.9 9388.8 9417.8 9367.4 9417.7 9422.8 9435.2 9438.5 8360.8 9291.5 9367.9 9378.1 9379.4 9058.2 9395.2 9397.9 9403.0 9407.4 39273.0 39479.2 39550.0 39613.6 39619.4 3105.3 3302.1 3312.9 3348.4 3670.7 26888.3 28735.9 29050.1 29076.7 30583.9 2925.8 3223.7 3224.0 3236.2 3241.9 348870.0 350352.0 350489.0 350524.0 351817.2 59624.7 59905.0 60012.5 60074.7 60124.7 34695.8 35723.5 63430.7 63439.0 63602.7 13157.7 15366.5 15810.4 15911.9 15946.0 12150.3 12223.2 12231.1 12239.8 12256.2 18850.8 18882.2 18906.7 18914.5 18937.9 6977.3 7179.7 7194.5 7375.5 7420.7 742.3 895.3 923.3 952.1 984.0 28645.6 28883.3 29532.0 29631.6 29729.7 742.5 1001.9 1035.7 1146.4 1156.6 12471.9 12837.7 14236.8 14851.6 15539.0 647559.0 688836.0 690473.0 690539.0 691614.0 57972.0 58499.8 58576.7 58745.4 58896.0 1124.6 1204.2 1256.2 1319.0 1339.8 28223.0 30728.4 30878.9 31265.0 31432.5 49569.3 50363.2 50381.5 50389.2 50423.0 526560.0 527909.0 527925.0 528572.0 530472.3 21374.6 25502.5 26880.0 27773.2 27896.3 45590.5 46327.8 46415.8 46572.0 46584.5 31099.8 37858.1 38413.4 38446.4 38454.0 37468.3 38180.4 38190.0 39135.4 39600.0 32965.7 32969.0 33035.7 34722.1 37969.7 22618.9 33425.8 33878.8 34780.0 34861.8 24330.0 24535.0 25276.7 25664.2 26217.9 14:45:50 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:34051.15 Median:385.50 stddev:98816.07 (25633.1%) stddev-sans-first:99060.91 14:45:50 INFO - PROCESS | 1638 | Values: 368.5 377.1 380.8 390.2 399.3 15021.4 15333.2 17991.8 19932.4 20139.0 14649.8 16195.3 17561.7 19231.6 19737.8 5078.3 5550.9 5776.8 5863.1 5874.2 5060.8 5213.8 5710.8 5728.9 5806.4 5719.3 5752.0 5761.6 5767.3 5772.9 5556.6 5578.9 5646.6 5664.8 5674.4 4989.6 4997.4 5007.6 5010.1 5010.3 23377.6 23920.5 24346.9 24675.8 24877.3 2249.6 2317.8 2350.9 2510.4 2957.4 9635.3 10155.3 14136.4 14316.6 14682.7 1812.3 1847.5 1861.6 1878.7 1905.7 105266.0 109158.0 112622.0 118175.0 118625.8 9451.9 9693.0 9989.4 10000.2 10140.2 12160.4 12387.6 12419.8 12422.0 12439.8 7888.4 7940.2 8040.0 8140.6 8215.8 7960.6 8065.2 8198.0 8264.1 8497.0 3799.8 3924.9 3950.6 3985.4 4006.4 1777.8 1880.5 1922.2 1925.7 1948.4 721.6 800.2 825.3 856.5 874.1 16974.9 17322.7 19121.9 19999.5 21281.0 872.6 913.3 926.4 932.4 977.2 8936.4 10967.3 10976.0 10978.9 11014.6 581381.1 584225.0 592758.0 600268.0 600788.0 124801.0 126624.0 130511.1 130894.0 135040.3 946.1 953.9 962.4 965.4 968.9 12214.0 15266.2 19159.2 19180.2 19249.8 50987.5 51640.0 53193.2 54229.0 54465.9 68084.3 71370.0 71852.6 73251.3 74496.1 7853.6 7917.8 8024.5 8204.7 8388.6 6773.4 9338.3 10939.2 10941.9 12461.1 10296.3 10845.3 10996.7 11276.2 11290.7 10395.2 10699.7 10879.2 11024.6 11241.7 10893.5 11036.0 11138.3 11320.4 11455.5 6357.9 6402.2 8137.9 8233.5 8366.1 9150.8 10666.7 10846.0 10856.2 11174.8 14:45:50 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5371.76 Median:5007.10 stddev:2943.48 (58.8%) stddev-sans-first:2937.04 14:45:50 INFO - PROCESS | 1638 | Values: 1458.7 1492.6 1648.7 1810.5 1832.2 8001.4 8405.3 8411.0 8417.7 8433.5 7467.0 7710.2 7728.3 8037.7 8220.8 2318.7 2345.2 2364.3 2365.6 2367.6 2354.4 2370.8 2386.2 2402.5 2403.4 2269.0 2307.9 2332.6 2345.3 2417.7 2302.5 2308.3 2323.2 2326.7 2329.7 2323.0 2343.1 2348.2 2349.2 2350.2 7990.2 8046.5 8061.0 8066.4 8066.8 5056.2 5211.9 5220.5 5222.9 5226.0 2797.7 2948.0 3131.0 3452.0 3464.7 1037.6 1054.7 1068.1 1077.7 1083.4 10673.7 10708.5 10810.1 10813.0 10815.4 4461.0 4602.8 4676.7 4721.6 4732.0 9320.4 9329.2 9331.2 9335.0 9345.8 4397.7 5153.6 5161.1 5162.9 5169.9 3927.5 4208.6 4736.6 4744.5 4793.0 3746.2 3774.2 3820.3 3855.7 3872.9 1263.0 1264.8 1268.9 1282.1 1283.6 807.6 894.0 895.5 905.8 909.6 5459.8 5505.2 5615.1 5669.0 5789.3 436.0 436.5 911.7 954.2 956.6 3758.8 3762.8 3775.9 3789.6 3874.7 9056.4 10018.5 10102.7 10118.0 10132.6 10076.4 10079.4 10091.9 10100.5 10112.3 7629.8 7765.7 7786.7 7790.5 7800.8 5252.6 5340.7 5402.6 5559.2 5564.2 9208.8 9230.5 9330.8 9424.2 9469.1 9386.7 9415.2 9447.3 9487.9 9532.6 6235.8 6269.3 6337.8 6480.8 6485.4 6696.9 7031.9 7940.2 8022.7 8031.2 6998.1 7144.5 7202.4 7209.5 7246.7 6772.6 6972.3 6985.6 7014.7 7044.8 6347.9 6524.1 6606.7 6609.8 6670.4 4699.5 4796.1 4815.7 4850.2 4859.6 4054.8 4758.4 4787.4 4972.1 5042.1 14:45:50 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4458.82 Median:4976.79 stddev:1395.87 (28.0%) stddev-sans-first:1363.72 14:45:50 INFO - PROCESS | 1638 | Values: 258.7 278.8 299.4 310.5 311.3 4646.9 5104.6 5199.2 5292.4 5312.5 5103.4 5196.9 5226.2 5236.6 5245.2 3438.7 4037.4 4058.3 4098.5 4129.3 3539.4 3910.7 4097.6 4102.7 4109.4 3681.6 4034.5 4058.9 4069.0 4113.3 3573.5 3810.2 3909.5 4030.0 4063.2 3173.9 3223.3 3299.0 3538.8 3564.8 4772.4 5123.4 5151.6 5204.2 5207.5 4194.6 4528.1 4559.7 4632.5 4642.9 4366.9 4802.5 5522.0 5551.4 5556.9 3793.6 3837.4 3839.5 3861.9 3875.7 4873.8 5810.8 5874.6 5876.4 6013.3 3714.3 3798.1 3830.3 3859.3 3880.0 3266.4 3491.3 3886.9 3912.2 3961.6 3690.6 3995.0 4498.6 4503.4 4534.8 5519.8 5535.3 5544.6 5568.2 5616.1 1147.9 1203.5 1233.9 1261.2 1278.9 837.8 844.4 864.3 870.2 875.6 4290.4 4318.3 4423.7 4635.6 4848.0 4448.5 4608.6 5082.4 5186.8 5231.9 4386.4 4403.6 4596.8 4836.0 4907.6 2465.7 2514.7 2793.4 2865.7 2875.6 5616.4 5628.8 5644.6 5650.4 5773.0 5502.8 5568.9 5571.5 5596.3 6024.9 5037.8 5634.3 6006.8 6012.4 6030.5 5076.4 5147.7 5805.8 5823.0 5927.5 4698.5 5468.6 5519.4 5732.3 5796.2 5026.2 5047.9 5931.1 5974.5 6032.3 3346.6 3698.5 3833.4 3857.6 3905.5 5663.8 5758.6 5807.4 5872.6 5873.4 4780.8 5668.4 5695.5 5731.6 5734.7 4946.0 5204.3 5396.2 5534.5 5625.9 4586.3 5007.6 5478.2 5479.4 5487.0 5267.8 5581.3 5610.5 5646.9 5688.2 5474.7 5482.3 5500.6 5572.8 5575.2 14:45:50 INFO - PROCESS | 1638 | 14:45:50 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19419.15 Median:4351.33 stddev:28419.88 (653.1%) stddev-sans-first:28464.86 14:45:50 INFO - PROCESS | 1638 | Values: 703.6 708.3 745.1 770.4 783.4 13845.8 14300.0 14363.2 14497.8 14499.4 12555.3 12631.6 12640.1 12661.3 12667.5 640.3 655.3 656.7 772.0 797.1 659.1 664.7 769.5 799.5 802.1 843.9 845.4 845.6 846.3 846.3 661.4 684.9 839.9 840.6 840.8 599.1 615.5 617.9 620.9 622.6 12631.2 12656.7 12685.9 12742.0 12751.9 11825.0 11967.4 11987.0 12094.5 12358.6 11647.3 11692.4 11747.8 11968.6 12128.5 3818.2 4374.0 4407.5 4490.7 4518.8 84615.2 84847.6 84979.6 84988.0 85561.0 9999.0 10033.1 10082.4 10119.5 10120.4 18944.7 18981.9 19083.6 19091.9 19154.5 6797.8 7289.5 7389.3 7458.7 7491.9 4024.4 4138.2 4165.7 4315.5 4328.7 2708.3 3093.7 3164.4 3171.4 3185.0 592.0 592.7 596.4 600.9 611.1 488.5 492.7 533.9 596.9 603.4 7774.6 8554.9 8567.0 8599.5 8823.9 608.6 614.1 620.8 625.0 629.2 3935.0 4117.2 4180.0 4201.5 4212.8 82513.6 111481.4 111543.0 111585.4 114565.4 73830.0 78422.0 82429.2 96815.0 98539.0 75887.0 79781.0 80354.0 82978.0 84104.6 7791.9 8384.8 8840.5 9314.3 9337.4 38159.8 38555.6 46393.1 46552.5 52510.9 67429.7 72470.6 72795.6 73708.0 74254.0 11825.8 11876.5 12055.9 12179.3 12273.7 12959.3 13830.7 13893.6 14484.4 14648.9 11993.7 12376.6 12911.5 12956.2 12982.5 12178.8 12275.9 12950.1 12955.0 13124.3 12204.5 12456.0 12574.5 13368.2 13395.5 10377.6 10548.6 10596.5 10777.9 10789.8 10156.5 10716.9 10834.9 11107.4 11131.3 14:45:50 INFO - PROCESS | 1638 | -------- Summary: end -------- 14:45:50 INFO - PROCESS | 1638 | 14:45:51 INFO - TEST-INFO | 1638: exit 0 14:45:51 INFO - TEST-OK | dromaeo_css | took 1119768ms 14:45:51 INFO - TEST-START | kraken 14:45:51 INFO - operating with platform_type : mac_ 14:45:51 INFO - Initialising browser for kraken test... 14:45:51 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpN7P3kr/profile http://localhost:49249/getInfo.html) 14:45:53 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 14:45:53 INFO - PROCESS | 1648 | colorDepth:24 14:45:53 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 14:45:53 INFO - PROCESS | 1648 | __metrics 14:45:53 INFO - PROCESS | 1648 | 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" 14:45:53 INFO - PROCESS | 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:45:54 INFO - TEST-INFO | 1648: exit 0 14:45:54 INFO - Browser initialized. 14:45:54 INFO - Running cycle 1/1 for kraken test... 14:45:54 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpN7P3kr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:46:07 INFO - PROCESS | 1649 | RSS: Main: 223039488 14:46:07 INFO - PROCESS | 1649 | 14:46:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 14:46:10 INFO - PROCESS | 1649 | RSS: Main: 271089664 14:46:10 INFO - PROCESS | 1649 | 14:46:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 14:46:13 INFO - PROCESS | 1649 | RSS: Main: 284532736 14:46:13 INFO - PROCESS | 1649 | 14:46:16 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 14:46:17 INFO - PROCESS | 1649 | RSS: Main: 381628416 14:46:17 INFO - PROCESS | 1649 | 14:46:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 14:46:20 INFO - PROCESS | 1649 | RSS: Main: 284696576 14:46:20 INFO - PROCESS | 1649 | 14:46:22 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:46:22 INFO - PROCESS | 1649 | RSS: Main: 286253056 14:46:22 INFO - PROCESS | 1649 | 14:46:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 14:46:26 INFO - PROCESS | 1649 | RSS: Main: 327712768 14:46:26 INFO - PROCESS | 1649 | 14:46:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 14:46:29 INFO - PROCESS | 1649 | RSS: Main: 327614464 14:46:29 INFO - PROCESS | 1649 | 14:46:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 14:46:33 INFO - PROCESS | 1649 | RSS: Main: 325414912 14:46:33 INFO - PROCESS | 1649 | 14:46:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:46:35 INFO - PROCESS | 1649 | RSS: Main: 300728320 14:46:35 INFO - PROCESS | 1649 | 14:46:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:46:38 INFO - PROCESS | 1649 | RSS: Main: 300257280 14:46:38 INFO - PROCESS | 1649 | 14:46:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:46:40 INFO - PROCESS | 1649 | RSS: Main: 303071232 14:46:40 INFO - PROCESS | 1649 | 14:46:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:46:44 INFO - PROCESS | 1649 | RSS: Main: 320872448 14:46:44 INFO - PROCESS | 1649 | 14:46:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:46:47 INFO - PROCESS | 1649 | RSS: Main: 313434112 14:46:47 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 14:46:49 INFO - PROCESS | 1649 | RSS: Main: 357724160 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | __start_tp_report 14:46:49 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 14:46:49 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 14:46:49 INFO - PROCESS | 1649 | |i|pagename|runs| 14:46:49 INFO - PROCESS | 1649 | |0;ai-astar;104;113;112;112;113;113;113;114;114;113 14:46:49 INFO - PROCESS | 1649 | |1;audio-beat-detection;144;111;112;145;111;110;110;108;140;111 14:46:49 INFO - PROCESS | 1649 | |2;audio-dft;182;182;181;183;185;183;183;185;182;183 14:46:49 INFO - PROCESS | 1649 | |3;audio-fft;82;59;59;90;63;58;59;58;84;58 14:46:49 INFO - PROCESS | 1649 | |4;audio-oscillator;86;72;95;70;69;68;89;68;69;92 14:46:49 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;86;89;101;114;84;89;85;92;130 14:46:49 INFO - PROCESS | 1649 | |6;imaging-darkroom;96;95;96;96;96;96;97;97;95;96 14:46:49 INFO - PROCESS | 1649 | |7;imaging-desaturate;81;81;80;80;80;81;83;84;83;81 14:46:49 INFO - PROCESS | 1649 | |8;json-parse-financial;57;54;53;77;90;90;86;57;57;58 14:46:49 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;63;75;80;75;72;70;69;70;70;72 14:46:49 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;81;83;84;84;97;100;83;83;87;85 14:46:49 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;151;132;146;151;157;131;141;141;188;136 14:46:49 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;168;164;165;164;164;164;165;169;165;164 14:46:49 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;65;66;66;66;66;64;67;67;66;65 14:46:49 INFO - PROCESS | 1649 | __end_tp_report 14:46:49 INFO - PROCESS | 1649 | __start_cc_report 14:46:49 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1997 14:46:49 INFO - PROCESS | 1649 | __end_cc_report 14:46:49 INFO - PROCESS | 1649 | __startTimestamp1482446809896__endTimestamp 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | ------- Summary: start ------- 14:46:49 INFO - PROCESS | 1649 | Number of tests: 14 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.92 (2.6%) stddev-sans-first:0.71 14:46:49 INFO - PROCESS | 1649 | Values: 104.0 113.0 112.0 112.0 113.0 113.0 113.0 114.0 114.0 113.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:120.20 Median:111.50 stddev:15.82 (14.2%) stddev-sans-first:14.24 14:46:49 INFO - PROCESS | 1649 | Values: 144.0 111.0 112.0 145.0 111.0 110.0 110.0 108.0 140.0 111.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.29 (0.7%) stddev-sans-first:1.32 14:46:49 INFO - PROCESS | 1649 | Values: 182.0 182.0 181.0 183.0 185.0 183.0 183.0 185.0 182.0 183.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:67.00 Median:61.00 stddev:12.88 (21.1%) stddev-sans-first:12.47 14:46:49 INFO - PROCESS | 1649 | Values: 82.0 59.0 59.0 90.0 63.0 58.0 59.0 58.0 84.0 58.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:11.21 (14.2%) stddev-sans-first:11.49 14:46:49 INFO - PROCESS | 1649 | Values: 86.0 72.0 95.0 70.0 69.0 68.0 89.0 68.0 69.0 92.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:87.50 stddev:15.03 (17.2%) stddev-sans-first:15.73 14:46:49 INFO - PROCESS | 1649 | Values: 89.0 86.0 89.0 101.0 114.0 84.0 89.0 85.0 92.0 130.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 14:46:49 INFO - PROCESS | 1649 | Values: 96.0 95.0 96.0 96.0 96.0 96.0 97.0 97.0 95.0 96.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:81.40 Median:81.00 stddev:1.43 (1.8%) stddev-sans-first:1.51 14:46:49 INFO - PROCESS | 1649 | Values: 81.0 81.0 80.0 80.0 80.0 81.0 83.0 84.0 83.0 81.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:67.50 stddev:15.84 (23.5%) stddev-sans-first:16.30 14:46:49 INFO - PROCESS | 1649 | Values: 57.0 54.0 53.0 77.0 90.0 90.0 86.0 57.0 57.0 58.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:4.50 (6.3%) stddev-sans-first:3.54 14:46:49 INFO - PROCESS | 1649 | Values: 63.0 75.0 80.0 75.0 72.0 70.0 69.0 70.0 70.0 72.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:84.00 stddev:6.45 (7.7%) stddev-sans-first:6.50 14:46:49 INFO - PROCESS | 1649 | Values: 81.0 83.0 84.0 84.0 97.0 100.0 83.0 83.0 87.0 85.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:148.50 stddev:16.62 (11.2%) stddev-sans-first:17.58 14:46:49 INFO - PROCESS | 1649 | Values: 151.0 132.0 146.0 151.0 157.0 131.0 141.0 141.0 188.0 136.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.00 stddev:1.81 (1.1%) stddev-sans-first:1.62 14:46:49 INFO - PROCESS | 1649 | Values: 168.0 164.0 165.0 164.0 164.0 164.0 165.0 169.0 165.0 164.0 14:46:49 INFO - PROCESS | 1649 | 14:46:49 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 14:46:49 INFO - PROCESS | 1649 | Values: 65.0 66.0 66.0 66.0 66.0 64.0 67.0 67.0 66.0 65.0 14:46:49 INFO - PROCESS | 1649 | -------- Summary: end -------- 14:46:49 INFO - PROCESS | 1649 | 14:46:50 INFO - TEST-INFO | 1649: exit 0 14:46:50 INFO - TEST-OK | kraken | took 59704ms 14:46:50 INFO - SUITE-END | took 1179s 14:46:51 INFO - Completed test suite (00:19:39) 14:46:51 INFO - javascript benchmark 14:46:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3236.838158092095, 3269.5586095877065, 3270.0189943017094, 3286.5891763529557, 3330.9500357494626, 5441.401445840959, 5494.066008778507, 5496.065668836308, 5499.697516636588, 5515.944840551593, 5521.254630624864, 5543.223948647183, 5603.355614104379, 5610.018246806802, 5685.1756495308255, 5108.106081435743, 5131.563817075553, 5147.48525147486, 5219.973900130513, 5286.867828304304, 4211.68412369072, 5110.361204849394, 5131.230315452674, 5137.126688462951, 5156.458571849939, 5166.664166829163, 5181.222816577525, 5219.060569097561, 5220.504052115043, 5238.685678859261, 5190.143626301656, 5192.0, 5215.191645294985, 5250.894982100336, 5251.396089449724, 4972.627052971042, 5019.447860735316, 5138.820141295074, 5157.0, 5176.844694659167, 5509.173623956399, 5533.861653458655, 5638.097904335286, 5642.492175704166, 5683.943160568383, 6110.358412366726, 6123.387661233885, 6124.479419249369, 6143.969280153616, 6214.005759078571, 6210.192674952273, 6261.154744109578, 6266.686665666744, 6271.592346497463, 6282.842928926813, 5455.699936503451, 5565.66606003633, 5680.829575112753, 5728.940146072948, 5775.0, 6299.275583307886, 6321.715522801485, 6330.746770129143, 6336.904946425807, 6338.873222535523, 6064.36324185963, 6087.421694938974, 6092.695365231721, 6105.572609917352, 6109.633421994695, 6087.1173679816175, 6102.389761023951, 6121.81881567809, 6131.193168498116, 6235.314115447297, 6121.785737499255, 6136.304638670889, 6156.29202641702, 6157.353477884847, 6166.260048794174, 6110.511159107261, 6147.44672979434, 6154.292256390483, 6160.291566469824, 6184.752609895554, 4785.1625965455905, 4844.781984810622, 4861.513848615181, 4905.607551395879, 4907.6564640475535, 4410.514843367227, 4437.156940181355, 4442.844500442469, 4454.5990860822685, 4471.597556219891, 5659.0, 5661.858453538694, 5674.517665998354, 5675.829725108253, 5675.943240567624, 6130.031268545464, 6132.9980441463495, 6163.506919446433, 6165.167702360124, 6170.598911070766, 5636.774529018875, 5697.373288938214, 5725.2843394575675, 5725.341585717612, 5741.224934633771, 5352.4915133062295, 5358.356997160366, 5363.007843548956, 5363.919541206885, 5379.54273886724, 6221.782237621662, 6292.2449306081735, 6296.150019747367, 6303.873922521616, 6478.838029049406, 6030.276366835832, 6137.070562331885, 6165.951788196107, 6199.597026193283, 6261.812145635638, 6286.27707813589, 6292.748290068529, 6298.149749783721, 6302.0, 6309.148264984168, 6147.631142131487, 6152.16945712341, 6164.229471316085, 6165.260168779596, 6165.691715414301, 6054.878902422015, 6093.451589356801, 6114.449440885012, 6124.869201255775, 6218.595791273553, 6089.2997305310455, 6099.359567245376, 6116.335771508327, 6117.99053156242, 6136.969315153396, 5921.171036055042, 5964.194833697396, 6044.9395506044375, 6075.756969721162, 6115.235595550556, 5706.914396284142, 5765.7693692253515, 5780.277465316835, 5899.498542623754, 5924.7037648118285, 5965.70171491415, 6072.78745243932, 6085.939140608714, 6090.3605121461605, 6125.724342404692, 5888.911666324922, 5901.970490147522, 5906.616069955439, 5907.556933230111, 5955.6724380157775], "value": 5644.05995708554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.50103883650314, 495.8041573578439, 496.55806332364205, 498.44423467833354, 559.0663591801693, 29792.553111703313, 39920.60079399205, 40025.199496010064, 40029.99925001873, 40035.99910002244, 33837.15407114823, 40864.38703419451, 41405.37891931624, 41494.58505414945, 41541.37687934683, 9273.397229180115, 9451.763705907339, 9454.338196326225, 9460.432374057544, 9464.053594640549, 9272.211861991738, 9384.953075234615, 9385.906140938605, 9388.812223755554, 9417.81164376712, 9367.39111957724, 9417.717468475958, 9422.811543769121, 9435.15083642472, 9438.528073596293, 8360.829483872263, 9291.48896810675, 9367.906320936772, 9378.109079637457, 9379.390339627971, 9058.23005044572, 9395.201407880339, 9397.859032114524, 9403.0, 9407.43555386679, 39273.0, 39479.21041579181, 39550.011249718984, 39613.603863961565, 39619.40570891439, 3105.2702614885484, 3302.091924720697, 3312.8840490582716, 3348.41402754517, 3670.7063434925144, 26888.327791805164, 28735.856320718263, 29050.12849614515, 29076.728185002154, 30583.92956246543, 2925.7951943363973, 3223.693749093856, 3223.9844448998724, 3236.1909522619344, 3241.9027429177163, 348870.0, 350352.0, 350489.0, 350524.0, 351817.24091379636, 59624.70187649034, 59905.0, 60012.49968750729, 60074.699626501584, 60124.69937650371, 34695.82652086774, 35723.46414803728, 63430.68284658547, 63439.0, 63602.68198658977, 13157.671058223621, 15366.462173824086, 15810.367585296459, 15911.920440397955, 15945.963512371658, 12150.331731754839, 12223.205491643015, 12231.082668799876, 12239.755204895851, 12256.203346782431, 18850.81149188518, 18882.24471021171, 18906.716399254023, 18914.527136821413, 18937.90531047336, 6977.33715297046, 7179.712811487482, 7194.532355396883, 7375.483716139926, 7420.740274090381, 742.3319012888336, 895.3150839607705, 923.3305853256336, 952.0859974424492, 983.9950800245953, 28645.57031644527, 28883.277918052216, 29532.0, 29631.555526667118, 29729.702702972692, 742.5322047110286, 1001.8647482589903, 1035.7358873487212, 1146.4038699876019, 1156.5662876421343, 12471.939885109687, 12837.743245135045, 14236.789872860716, 14851.554453366416, 15539.0, 647559.0, 688836.0, 690473.0, 690539.0, 691614.0, 57972.0, 58499.83000339969, 58576.707116464146, 58745.412545873995, 58896.0, 1124.6232512108536, 1204.2473454091194, 1256.1960345378964, 1318.9868101318864, 1339.8459177194748, 28223.0, 30728.385432291678, 30878.91923782657, 31265.0, 31432.528512072793, 49569.25646115383, 50363.24455133249, 50381.49618503915, 50389.24416133688, 50422.99154016826, 526560.0, 527909.0, 527925.0, 528572.0, 530472.3476382593, 21374.57250854943, 25502.489950201263, 26880.0, 27773.166804995883, 27896.302592435353, 45590.54409455863, 46327.768361159324, 46415.76792116018, 46572.0, 46584.53415465802, 31099.84450077735, 37858.07844980135, 38413.423798642485, 38446.423303649906, 38454.0, 37468.2506349866, 38180.42729359117, 38190.0, 39135.41296880606, 39600.00999975024, 32965.67034329722, 32969.01092967215, 33035.669643303256, 34722.131946701535, 37969.671061512716, 22618.886905565367, 33425.83287083549, 33878.830605846815, 34780.0, 34861.82569087138, 24330.0, 24535.0, 25276.74723252744, 25664.23007309709, 26217.868910655325], "value": 19092.118804787024, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.52092280035953, 377.10813925067225, 380.8419505905049, 390.16115351993227, 399.31716764332987, 15021.399144034225, 15333.156676382794, 17991.820081799193, 19932.402027939166, 20138.99999999998, 14649.780253296209, 16195.271212795424, 17561.73657395137, 19231.615367692637, 19737.802621973777, 5078.263651770491, 5550.944490555084, 5776.797812076578, 5863.120531920204, 5874.1776151338845, 5060.772265248054, 5213.765380557865, 5710.800121995731, 5728.856778580548, 5806.361300256968, 5719.28508936383, 5752.0, 5761.567882408815, 5767.250257466523, 5772.913406298909, 5556.605556605552, 5578.944210557904, 5646.632968857011, 5664.773409063674, 5674.404187560288, 4989.600831933437, 4997.375328083989, 5007.574356179729, 5010.123228435009, 5010.348654674905, 23377.649335259986, 23920.521589568285, 24346.904389302523, 24675.753242467526, 24877.253682389557, 2249.606318894187, 2317.814574834026, 2350.9412264693347, 2510.418436385084, 2957.438086763508, 9635.325527213097, 10155.339902906362, 14136.434542618386, 14316.57050288493, 14682.70634587313, 1812.3022636284993, 1847.4827048426462, 1861.553227225456, 1878.7275845002534, 1905.6569817432855, 105266.0, 109158.0, 112622.0, 118175.0, 118625.81374186234, 9451.905480945103, 9693.0, 9989.40169572865, 10000.249981251436, 10140.239482038876, 12160.391980401122, 12387.62837114888, 12419.75160496785, 12422.0, 12439.75120497585, 7888.40836937232, 7940.205979402013, 8040.035195776544, 8140.5929703514585, 8215.753527394188, 7960.6417711201975, 8065.193480652005, 8198.0, 8264.132266112092, 8496.957515212385, 3799.848006079726, 3924.862629807943, 3950.644442000234, 3985.4221137934837, 4006.399040144002, 1777.7688900443118, 1880.4546681462223, 1922.1830721943118, 1925.7303977443169, 1948.4057362504288, 721.585088574072, 800.2477670989251, 825.3397282174236, 856.4647095565272, 874.1346067393235, 16974.91512542454, 17322.740158897628, 19121.852688838713, 19999.500012499513, 21281.042353094148, 872.581161042703, 913.2830727878584, 926.3700683535261, 932.4125800745487, 977.1889331854544, 8936.374453788172, 10967.341959482457, 10976.0, 10978.890211097787, 11014.559417623206, 581381.0930945318, 584225.0, 592758.0, 600268.0, 600788.0, 124801.0, 126624.0, 130511.11355084588, 130894.0, 135040.32479837537, 946.0539460539461, 953.8664586957832, 962.3696478806393, 965.4014511003223, 968.8982656820932, 12214.022878169548, 15266.160361180242, 19159.233630654617, 19180.232790688216, 19249.807501925363, 50987.49012509976, 51639.967200655024, 53193.20210196928, 54229.0, 54465.91068178693, 68084.31915680978, 71370.0, 71852.64073679807, 73251.26748732658, 74496.13759656053, 7853.646585903533, 7917.802054948673, 8024.518528888286, 8204.671813127408, 8388.622511986854, 6773.390394864486, 9338.346315757832, 10939.179561532757, 10941.945290273497, 12461.127721059438, 10296.330738501973, 10845.349279042966, 10996.670099897015, 11276.210665253353, 11290.661280161608, 10395.16838652921, 10699.679009629723, 10879.238453308193, 11024.559017639525, 11241.718957025812, 10893.455327233765, 11036.006759391694, 11138.27601205919, 11320.433978301216, 11455.484503197542, 6357.90463143044, 6402.231732191981, 8137.918620813953, 8233.547154906537, 8366.079731229682, 9150.771230719285, 10666.679999600025, 10846.023857852831, 10856.185786065918, 11174.776504469703], "value": 9109.016070933356, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.6572155543445, 1492.6044598181481, 1648.6537827056318, 1810.4930619426548, 1832.221305944974, 8001.359891208704, 8405.285550728195, 8410.990681118266, 8417.70538031169, 8433.45182563133, 7467.0, 7710.190430004853, 7728.343090837286, 8037.678492860293, 8220.794480137985, 2318.7217533895914, 2345.2026311054237, 2364.3498038039593, 2365.550545396369, 2367.609344458171, 2354.4231663242444, 2370.845895016827, 2386.2364043506086, 2402.459446624513, 2403.387136280246, 2268.965965510519, 2307.9192228272013, 2332.5917964356254, 2345.2846881701075, 2417.746136655652, 2302.539492101578, 2308.3075077476856, 2323.1984965186984, 2326.7440581536016, 2329.743728189898, 2323.012719594166, 2343.0627748900406, 2348.1664009276633, 2349.2130136404326, 2350.1656911796235, 7990.200979902022, 8046.517208967422, 8061.0, 8066.4353495255355, 8066.7579972600915, 5056.165732654108, 5211.895762084737, 5220.4779522047875, 5222.895542089137, 5226.0, 2797.678266999301, 2947.970520294791, 3131.0, 3452.0, 3464.6708562686504, 1037.5745944162932, 1054.6835949215206, 1068.0921216965517, 1077.7467295185625, 1083.377058191543, 10673.679789606247, 10708.464576771208, 10810.081143102845, 10812.972767587145, 10815.405152716598, 4461.0, 4602.815887364535, 4676.69601475903, 4721.551452612065, 4731.95268047322, 9320.394174378644, 9329.207017403462, 9331.16019558243, 9335.0, 9345.766355841159, 4397.73613583186, 5153.561947234452, 5161.071007218661, 5162.8967420652125, 5169.948300517022, 3927.4697915781003, 4208.642265407475, 4736.621070314367, 4744.501827308152, 4792.976035119872, 3746.194568167857, 3774.150816066418, 3820.3123437781464, 3855.7108216883844, 3872.903177420587, 1262.9873701263054, 1264.8418947631546, 1268.9111762176558, 1282.0640932119604, 1283.5507572349602, 807.5760225881342, 894.0121166111484, 895.4582477601087, 905.8233644439402, 909.6179604566098, 5459.808906688327, 5505.201745746925, 5615.101583746581, 5668.9716551417805, 5789.305283366023, 435.9978200108979, 436.5394508793254, 911.7173676160412, 954.1889394015031, 956.5982287439292, 3758.755680880734, 3762.755420897633, 3775.867844625425, 3789.607664732624, 3874.7093967952515, 9056.366054376262, 10018.549165287579, 10102.747431314276, 10117.988201179824, 10132.594696212069, 10076.395416275047, 10079.395236285847, 10091.899081009096, 10100.545475453622, 10112.292139550162, 7629.809254768564, 7765.72819951299, 7786.688532458751, 7790.493617914817, 7800.843983120306, 5252.632315737862, 5340.679559226459, 5402.59480538969, 5559.2217089606675, 5564.19319198722, 9208.815823683623, 9230.538473076454, 9330.813383732424, 9424.198943089914, 9469.147776699854, 9386.718398448058, 9415.19970802462, 9447.291453140904, 9487.905120948702, 9532.571034303335, 6235.844103897439, 6269.279032911274, 6337.7781777637565, 6480.773172938924, 6485.44873685742, 6696.866062678817, 7031.929680703128, 7940.205979402013, 8022.679092836222, 8031.1567285434185, 6998.090248267693, 7144.4641651877355, 7202.423806095395, 7209.459290553124, 7246.7101315946775, 6772.627505487048, 6972.337627925339, 6985.5808651481075, 7014.68433920485, 7044.753433629799, 6347.873042539216, 6524.119243902012, 6606.735730570724, 6609.834754131185, 6670.366315200048, 4699.506551812011, 4796.136695394723, 4815.711057336571, 4850.248211527219, 4859.562639362474, 4054.7972601369224, 4758.357621721023, 4787.3776409066595, 4972.0801651694555, 5042.117629414911], "value": 4353.721027233632, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.69603216220935, 278.8368804249514, 299.37132022752223, 310.54970293075047, 311.30734116590577, 4646.9079766254945, 5104.626348747832, 5199.168133098705, 5292.417834038252, 5312.521873031426, 5103.413107492639, 5196.948030519694, 5226.216067589863, 5236.581073514119, 5245.239440281157, 3438.690517853399, 4037.4145748866395, 4058.289799285128, 4098.549159592442, 4129.339305711087, 3539.3629146753574, 3910.687145028405, 4097.590240975908, 4102.733322334054, 4109.4246805447265, 3681.5582130144285, 4034.475518182632, 4058.8579399721016, 4068.9796551017353, 4113.33409968963, 3573.4997100405963, 3810.2189051244427, 3909.5113110861284, 4029.9597004030024, 4063.1873625274766, 3173.873045078194, 3223.258650510396, 3298.983505082483, 3538.8236234715464, 3564.7682900611376, 4772.403449568804, 5123.385193776733, 5151.587872970191, 5204.245384419276, 5207.453217412193, 4194.622483976426, 4528.139653465831, 4559.749213793273, 4632.490426053131, 4642.908916404642, 4366.934495982563, 4802.5197480252045, 5521.972390138064, 5551.3893471718075, 5556.86107847303, 3793.563740169888, 3837.369118124786, 3839.5392552893554, 3861.942070868939, 3875.65119139276, 4873.756312184377, 5810.8256752297075, 5874.6181498202905, 5876.382979787104, 6013.278406591237, 3714.294284086069, 3798.1454172810895, 3830.3105441020325, 3859.286032084073, 3879.9612003880166, 3266.379387916288, 3491.284286721216, 3886.8639597613947, 3912.237113762789, 3961.623645753649, 3690.5642276098183, 3995.001249687578, 4498.572635599613, 4503.437070366204, 4534.841280555164, 5519.779208831682, 5535.280413546294, 5544.63959842609, 5568.220449137043, 5616.1014237721765, 1147.9152201169982, 1203.4524291447547, 1233.9259644421363, 1261.2306493039239, 1278.8657190994998, 837.7738010737066, 844.4420045016934, 864.3301441382853, 870.1950695606539, 875.5622188905547, 4290.377895205178, 4318.330658747899, 4423.690341676116, 4635.58279754817, 4847.957689096861, 4448.488423831236, 4608.6082682971655, 5082.415522214993, 5186.81846135391, 5231.866825208119, 4386.385905973167, 4403.647708183337, 4596.839110631112, 4836.0, 4907.607391408679, 2465.7041155061506, 2514.6856642919633, 2793.4133831895124, 2865.7390748070784, 2875.597416361711, 5616.354119276336, 5628.774849005994, 5644.6330988485615, 5650.378458369576, 5773.0, 5502.80740174092, 5568.860778480489, 5571.470710282517, 5596.300462442195, 6024.879502409927, 5037.798488060436, 5634.281374292947, 6006.819795406145, 6012.39876012404, 6030.547708921936, 5076.441591425014, 5147.6654017488745, 5805.82582522525, 5822.970885145547, 5927.547750801082, 4698.506656800987, 5468.56251499887, 5519.420460851553, 5732.34078081026, 5796.246487956539, 5026.235570436318, 5047.911191934545, 5931.13998470228, 5974.520573961757, 6032.306284777132, 3346.5816772903386, 3698.537682789651, 3833.4058220975794, 3857.556381016219, 3905.4922860028014, 5663.801766938137, 5758.596898217084, 5807.37039350406, 5872.559558033079, 5873.353931067664, 4780.832670856504, 5668.404817494269, 5695.487406133468, 5731.570132240182, 5734.741936612946, 4945.97527012377, 5204.323437953042, 5396.163594642837, 5534.52956498686, 5625.88748225025, 4586.2661974083985, 5007.5993920487, 5478.178273259043, 5479.397266300624, 5487.0, 5267.762950667306, 5581.297704200096, 5610.485169004428, 5646.887062258814, 5688.175214593944, 5474.675006744627, 5482.341064583754, 5500.614956952975, 5572.832815015556, 5575.1880638791745], "value": 4007.2556237798362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.5919166883207, 708.2917082917086, 745.0761155115347, 770.4452793988327, 783.4006984656735, 13845.792313115298, 14300.0, 14363.210023448706, 14497.782533262009, 14499.420023199073, 12555.309457979807, 12631.557895473661, 12640.115191936567, 12661.30362830044, 12667.493300268, 640.3372509452718, 655.2726473614284, 656.6880731652475, 772.0, 797.0634504457269, 659.1200747002733, 664.694240649302, 769.5228958045991, 799.4843326054691, 802.1296892871242, 843.9071702112763, 845.3870943565927, 845.6279237135672, 846.2510678049939, 846.2510678049939, 661.4443867151616, 684.8698747238009, 839.8782176584392, 840.5587066789923, 840.7940054686579, 599.1073300781848, 615.5414216408759, 617.9443850053517, 620.9006558950591, 622.5735371270649, 12631.178973366794, 12656.746865062647, 12685.873141268654, 12741.980641548747, 12751.87248127516, 11825.0, 11967.40162991847, 11987.0, 12094.516219351215, 12358.567450139291, 11647.276048284619, 11692.356920369377, 11747.823782643267, 11968.581099661471, 12128.45421956014, 3818.236352729465, 4374.015846434559, 4407.515173330931, 4490.730556166608, 4518.796654150538, 84615.15384846195, 84847.57576212079, 84979.57510212471, 84988.0, 85561.0, 9999.0, 10033.097021267975, 10082.445465499468, 10119.544620492095, 10120.392776433437, 18944.71582926257, 18981.905090474458, 19083.61832763337, 19091.904540477488, 19154.52113697169, 6797.830054248683, 7289.453291003196, 7389.261073892675, 7458.73894413693, 7491.925080749232, 4024.396340548941, 4138.213739389506, 4165.729227600196, 4315.4605674290715, 4328.653707703376, 2708.268767432782, 3093.6906309368883, 3164.351307981858, 3171.4291427542803, 3184.952225716616, 591.9704014799242, 592.6977241606726, 596.3946594206883, 600.9008513595295, 611.0925275965188, 488.4992882295675, 492.72653677208774, 533.9012282727708, 596.8836076965036, 603.4478452216173, 7774.611269436506, 8554.91445085541, 8567.0, 8599.527026013504, 8823.924096559202, 608.6439432931785, 614.1494030767419, 620.8013435700623, 624.950003999679, 629.1946308724839, 3934.960650393459, 4117.21772863155, 4179.958200418017, 4201.4729870001365, 4212.789360531961, 82513.58743206246, 111481.44259278652, 111543.0, 111585.44207278911, 114565.42717286361, 73830.0, 78422.0, 82429.17570824215, 96815.0, 98539.0, 75887.0, 79781.0, 80354.0, 82978.0, 84104.57947710344, 7791.92208077912, 8384.83230335402, 8840.513771742359, 9314.347995640512, 9337.405099036783, 38159.809200953816, 38555.6144438552, 46393.07213855637, 46552.534474656175, 52510.94978100493, 67429.66285168739, 72470.63764681143, 72795.63602181955, 73708.0, 74254.02983880584, 11825.822612660644, 11876.465559049691, 12055.879441205476, 12179.330136842558, 12273.69315767113, 12959.287239201934, 13830.65423364424, 13893.58319250424, 14484.420623175376, 14648.926755366156, 11993.700157496132, 12376.566820161252, 12911.483540658268, 12956.222626642431, 12982.548784193787, 12178.75642487163, 12275.93862030684, 12950.093493455744, 12955.0, 13124.278164701032, 12204.511819527119, 12456.0, 12574.497020119094, 13368.197908125543, 13395.531156409479, 10377.584896604052, 10548.578056877639, 10596.470176491299, 10777.946110269711, 10789.784204315712, 10156.476528520781, 10716.89283107159, 10834.891651083704, 11107.418998070745, 11131.332120072822], "value": 6940.47325791228, "unit": "score"}], "name": "dromaeo_css", "value": 7012.024080739086, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 112.0, 112.0, 113.0, 113.0, 113.0, 114.0, 114.0, 113.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 112.0, 145.0, 111.0, 110.0, 110.0, 108.0, 140.0, 111.0], "value": 120.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 181.0, 183.0, 185.0, 183.0, 183.0, 185.0, 182.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 90.0, 63.0, 58.0, 59.0, 58.0, 84.0, 58.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 95.0, 70.0, 69.0, 68.0, 89.0, 68.0, 69.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 101.0, 114.0, 84.0, 89.0, 85.0, 92.0, 130.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0, 95.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 80.0, 80.0, 81.0, 83.0, 84.0, 83.0, 81.0], "value": 81.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 53.0, 77.0, 90.0, 90.0, 86.0, 57.0, 57.0, 58.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 75.0, 80.0, 75.0, 72.0, 70.0, 69.0, 70.0, 70.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 84.0, 84.0, 97.0, 100.0, 83.0, 83.0, 87.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 132.0, 146.0, 151.0, 157.0, 131.0, 141.0, 141.0, 188.0, 136.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 165.0, 164.0, 164.0, 164.0, 165.0, 169.0, 165.0, 164.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 66.0, 64.0, 67.0, 67.0, 66.0, 65.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1437.9, "alertThreshold": 2.0}]} 14:46:51 INFO - Return code: 0 14:46:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:46:51 INFO - # TBPL SUCCESS # 14:46:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:46:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:46:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:46:51 INFO - 2016-12-22 14:46:51.228 screenresolution[1653:10025] starting screenresolution argv=screenresolution get 14:46:51 INFO - 2016-12-22 14:46:51.231 screenresolution[1653:10025] Display 0: 1600x1200x32@60 14:46:51 INFO - 2016-12-22 14:46:51.237 screenresolution[1654:10028] starting screenresolution argv=screenresolution list 14:46:51 INFO - Available Modes on Display 0 14:46:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:46:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:46:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:46:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:46:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:46:51 INFO - Intel Iris: 14:46:51 INFO - Chipset Model: Intel Iris 14:46:51 INFO - Type: GPU 14:46:51 INFO - Bus: Built-In 14:46:51 INFO - VRAM (Dynamic, Max): 1536 MB 14:46:51 INFO - Vendor: Intel (0x8086) 14:46:51 INFO - Device ID: 0x0a2e 14:46:51 INFO - Revision ID: 0x0009 14:46:51 INFO - Displays: 14:46:51 INFO - Display: 14:46:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:46:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:46:51 INFO - Display Serial Number: 1600x1200 60 14:46:51 INFO - Main Display: Yes 14:46:51 INFO - Mirror: Off 14:46:51 INFO - Online: Yes 14:46:51 INFO - Rotation: Supported 14:46:51 INFO - Return code: 0 14:46:51 INFO - Running post-action listener: _resource_record_post_action 14:46:51 INFO - [mozharness: 2016-12-22 22:46:51.485084Z] Finished run-tests step (success) 14:46:51 INFO - Running post-run listener: _resource_record_post_run 14:46:51 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38622208; Write bytes: 654111232; Read time: 386; Write time: 1830 14:46:51 INFO - TinderboxPrint: CPU usage
53.2% 14:46:51 INFO - TinderboxPrint: I/O read bytes / time
38,622,208 / 386 14:46:51 INFO - TinderboxPrint: I/O write bytes / time
654,111,232 / 1,830 14:46:51 INFO - TinderboxPrint: CPU idle
2,265.9 (46.8%) 14:46:51 INFO - TinderboxPrint: CPU system
962.9 (19.9%) 14:46:51 INFO - TinderboxPrint: CPU user
1,616.9 (33.4%) 14:46:51 INFO - TinderboxPrint: Swap in / out
387,407,872 / 0 14:46:51 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 338479616; Write bytes: 334374912; Read time: 26503; Write time: 621 14:46:51 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 37667840; Write bytes: 302041600; Read time: 372; Write time: 1156 14:46:51 INFO - Running post-run listener: _upload_blobber_files 14:46:51 INFO - Blob upload gear active. 14:46:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:46:51 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 14:46:51 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'] 14:46:51 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 14:46:52 INFO - (blobuploader) - INFO - Open directory for files ... 14:46:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:46:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:46:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:53 INFO - (blobuploader) - INFO - Done attempting. 14:46:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:46:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:46:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:54 INFO - (blobuploader) - INFO - Done attempting. 14:46:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:46:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:46:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:57 INFO - (blobuploader) - INFO - Done attempting. 14:46:57 INFO - (blobuploader) - INFO - Iteration through files over. 14:46:57 INFO - Return code: 0 14:46:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:46:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:46:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00589252f6426e54c4eefbba4d3901c41c600ec4132d007e3dc63501d62fb1429f46d9ec2a22c1432ef781dcaf7588d8390d23303104d3534b6e890f51be9223", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48158898eb0b491390e7da957f900d6dcd3dd55c453163ddb08babd93bf99ee755ca00111bc135ea4e082254961e5a29c2621ef37c081d9606545bbad3f1b561", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db5b4c0a55e9363d396c534c8eb79a76d6ee887353081a5636491086949a40d09bd4126ac367f8572b980b1e8feb38c8f0cb06610111993e3522b950186d6296"} 14:46:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:46:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:46:57 INFO - Contents: 14:46:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00589252f6426e54c4eefbba4d3901c41c600ec4132d007e3dc63501d62fb1429f46d9ec2a22c1432ef781dcaf7588d8390d23303104d3534b6e890f51be9223", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48158898eb0b491390e7da957f900d6dcd3dd55c453163ddb08babd93bf99ee755ca00111bc135ea4e082254961e5a29c2621ef37c081d9606545bbad3f1b561", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db5b4c0a55e9363d396c534c8eb79a76d6ee887353081a5636491086949a40d09bd4126ac367f8572b980b1e8feb38c8f0cb06610111993e3522b950186d6296"} 14:46:57 INFO - Running post-run listener: copy_logs_to_upload_dir 14:46:57 INFO - Copying logs to upload dir... 14:46:57 INFO - mkdir: /builds/slave/test/build/upload/logs 14:46:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.668432 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-22 14:46:57.751265) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 14:46:57.756057) ========= 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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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/00589252f6426e54c4eefbba4d3901c41c600ec4132d007e3dc63501d62fb1429f46d9ec2a22c1432ef781dcaf7588d8390d23303104d3534b6e890f51be9223", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48158898eb0b491390e7da957f900d6dcd3dd55c453163ddb08babd93bf99ee755ca00111bc135ea4e082254961e5a29c2621ef37c081d9606545bbad3f1b561", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db5b4c0a55e9363d396c534c8eb79a76d6ee887353081a5636491086949a40d09bd4126ac367f8572b980b1e8feb38c8f0cb06610111993e3522b950186d6296"} build_url:https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/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/00589252f6426e54c4eefbba4d3901c41c600ec4132d007e3dc63501d62fb1429f46d9ec2a22c1432ef781dcaf7588d8390d23303104d3534b6e890f51be9223", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48158898eb0b491390e7da957f900d6dcd3dd55c453163ddb08babd93bf99ee755ca00111bc135ea4e082254961e5a29c2621ef37c081d9606545bbad3f1b561", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db5b4c0a55e9363d396c534c8eb79a76d6ee887353081a5636491086949a40d09bd4126ac367f8572b980b1e8feb38c8f0cb06610111993e3522b950186d6296"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 14:46:57.793665) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:46:57.793995) ========= 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.2HSdhoTFhr/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.YLYH7aw9TB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.005182 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:46:57.845690) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 14:46:57.846084) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 14:46:57.846476) ========= ========= Total master_lag: 0.30 =========