builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0251 starttime: 1482349740.94 results: success (0) buildid: 20161221095453 builduid: f241633f955d41888273fb48044cc52b revision: 3686b2e516446faba229534613cee5e2e2c82258 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:00.943955) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:00.944379) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:00.944660) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:00.970821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:00.971214) ========= 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.TshqPUtU8T/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.DQHCoJB6jH/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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-21 11:49:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.8M=0.001s 2016-12-21 11:49:01 (19.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105195 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:01.100333) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:01.100659) ========= 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.TshqPUtU8T/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.DQHCoJB6jH/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.021996 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:01.149227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-21 11:49:01.149592) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3686b2e516446faba229534613cee5e2e2c82258 --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 3686b2e516446faba229534613cee5e2e2c82258 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TshqPUtU8T/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.DQHCoJB6jH/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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-21 11:49:01,238 truncating revision to first 12 chars 2016-12-21 11:49:01,238 Setting DEBUG logging. 2016-12-21 11:49:01,239 attempt 1/10 2016-12-21 11:49:01,239 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3686b2e51644?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 11:49:03,058 unpacking tar archive at: mozilla-aurora-3686b2e51644/testing/mozharness/ program finished with exit code 0 elapsedTime=2.075677 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-21 11:49:03.248415) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:03.248735) ========= script_repo_revision: 3686b2e516446faba229534613cee5e2e2c82258 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:03.249176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:03.249457) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 11:49:03.268977) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-12-21 11:49:03.269267) ========= /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.TshqPUtU8T/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.DQHCoJB6jH/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49:03 INFO - MultiFileLogger online at 20161221 11:49:03 in /builds/slave/test 11:49:03 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 11:49:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:49:03 INFO - {'append_to_log': False, 11:49:03 INFO - 'base_work_dir': '/builds/slave/test', 11:49:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:49:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:49:03 INFO - 'branch': 'Mozilla-Aurora', 11:49:03 INFO - 'buildbot_json_path': 'buildprops.json', 11:49:03 INFO - 'config_files': ('talos/mac_config.py',), 11:49:03 INFO - 'default_actions': ('clobber', 11:49:03 INFO - 'read-buildbot-config', 11:49:03 INFO - 'download-and-extract', 11:49:03 INFO - 'populate-webroot', 11:49:03 INFO - 'create-virtualenv', 11:49:03 INFO - 'install', 11:49:03 INFO - 'run-tests'), 11:49:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:49:03 INFO - 'download_minidump_stackwalk': True, 11:49:03 INFO - 'download_symbols': 'ondemand', 11:49:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:49:03 INFO - 'tooltool.py': '/tools/tooltool.py', 11:49:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:49:03 INFO - '/tools/misc-python/virtualenv.py')}, 11:49:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:49:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:49:03 INFO - 'installer_path': 'installer.exe', 11:49:03 INFO - 'log_level': 'info', 11:49:03 INFO - 'log_name': 'talos', 11:49:03 INFO - 'log_to_console': True, 11:49:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:49:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:49:03 INFO - 'opt_config_files': (), 11:49:03 INFO - 'pip_index': False, 11:49:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:49:03 INFO - 'cmd': ('bash', 11:49:03 INFO - '-c', 11:49:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:49:03 INFO - 'enabled': True, 11:49:03 INFO - 'halt_on_failure': False, 11:49:03 INFO - 'name': 'check_screen_resolution'},), 11:49:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:49:03 INFO - 'cmd': ('bash', 11:49:03 INFO - '-c', 11:49:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:49:03 INFO - 'enabled': True, 11:49:03 INFO - 'halt_on_failure': False, 11:49:03 INFO - 'name': 'check_screen_resolution'},), 11:49:03 INFO - 'run_cmd_checks_enabled': True, 11:49:03 INFO - 'sps_profile': False, 11:49:03 INFO - 'sps_profile_interval': 0, 11:49:03 INFO - 'suite': 'dromaeojs', 11:49:03 INFO - 'system_bits': '32', 11:49:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:49:03 INFO - 'title': 't-yosemite-r7-0251', 11:49:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:49:03 INFO - 'use_talos_json': True, 11:49:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:49:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:49:03 INFO - 'work_dir': 'build'} 11:49:03 INFO - [mozharness: 2016-12-21 19:49:03.471739Z] Running clobber step. 11:49:03 INFO - Running pre-action listener: _resource_record_pre_action 11:49:03 INFO - Running main action method: clobber 11:49:03 INFO - rmtree: /builds/slave/test/build 11:49:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:49:05 INFO - Running post-action listener: _resource_record_post_action 11:49:05 INFO - [mozharness: 2016-12-21 19:49:05.747574Z] Finished clobber step (success) 11:49:05 INFO - [mozharness: 2016-12-21 19:49:05.747704Z] Running read-buildbot-config step. 11:49:05 INFO - Running pre-action listener: _resource_record_pre_action 11:49:05 INFO - Running main action method: read_buildbot_config 11:49:05 INFO - Using buildbot properties: 11:49:05 INFO - { 11:49:05 INFO - "project": "", 11:49:05 INFO - "product": "firefox", 11:49:05 INFO - "script_repo_revision": "production", 11:49:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:49:05 INFO - "repository": "", 11:49:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:49:05 INFO - "buildid": "20161221095453", 11:49:05 INFO - "pgo_build": "False", 11:49:05 INFO - "basedir": "/builds/slave/test", 11:49:05 INFO - "buildnumber": 12, 11:49:05 INFO - "slavename": "t-yosemite-r7-0251", 11:49:05 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:49:05 INFO - "platform": "yosemite_r7", 11:49:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:49:05 INFO - "branch": "mozilla-aurora", 11:49:05 INFO - "repo_path": "releases/mozilla-aurora", 11:49:05 INFO - "revision": "3686b2e516446faba229534613cee5e2e2c82258", 11:49:05 INFO - "stage_platform": "macosx64", 11:49:05 INFO - "builduid": "f241633f955d41888273fb48044cc52b", 11:49:05 INFO - "slavebuilddir": "test" 11:49:05 INFO - } 11:49:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:49:05 INFO - Running post-action listener: _resource_record_post_action 11:49:05 INFO - [mozharness: 2016-12-21 19:49:05.751225Z] Finished read-buildbot-config step (success) 11:49:05 INFO - [mozharness: 2016-12-21 19:49:05.751331Z] Running download-and-extract step. 11:49:05 INFO - Running pre-action listener: _resource_record_pre_action 11:49:05 INFO - Running main action method: download_and_extract 11:49:05 INFO - mkdir: /builds/slave/test/build/tests 11:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:05 INFO - trying https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:49:05 INFO - Downloading https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/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 11:49:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/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 11:49:07 INFO - Downloaded 1199 bytes. 11:49:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:49:07 INFO - Using the following test package requirements: 11:49:07 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:49:07 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:49:07 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:49:07 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:49:07 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:49:07 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:49:07 INFO - u'jsshell-mac.zip'], 11:49:07 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:49:07 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:49:07 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:49:07 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:49:07 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:49:07 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:49:07 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:49:07 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:49:07 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:49:07 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:49:07 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:49:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:49:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:49:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:49:07 INFO - Fetch https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:49:07 INFO - Http code: 200 11:49:07 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 11:49:07 INFO - x-amz-cf-id: tlHrwKe3h4UxjrWm7m4Ieblk0K7zehtXE8l3hhTl3kYuwo1zw4GiPg== 11:49:07 INFO - x-amz-version-id: ZCFx4lJfHzO1j4Qj0wsCjffiU8Mns7sO 11:49:08 INFO - Expected file size: 16269658 11:49:08 INFO - Obtained file size: 16269658 11:49:14 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 11:49:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:49:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:49:14 INFO - Fetch https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:49:15 INFO - Http code: 200 11:49:15 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 11:49:15 INFO - x-amz-cf-id: keM2IwrJcl7BZ755YCSpkvVVv1QEA7R5dHYMq8PHPzMcbSu16tiL2Q== 11:49:15 INFO - x-amz-version-id: ZCFx4lJfHzO1j4Qj0wsCjffiU8Mns7sO 11:49:15 INFO - Expected file size: 16269658 11:49:15 INFO - Obtained file size: 16269658 11:49:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:49:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:49:21 INFO - Fetch https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:49:22 INFO - Http code: 200 11:49:22 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 11:49:22 INFO - x-amz-cf-id: TusY7gs211mqkzOL89sfqXBEX-Ukg5ziR7LO5jS6cbevNE7Md253yg== 11:49:22 INFO - x-amz-version-id: b4GMeMYJDT3FWbO9opIHUTWmsLDfy4IA 11:49:23 INFO - Expected file size: 14409038 11:49:23 INFO - Obtained file size: 14409038 11:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:23 INFO - trying https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:49:23 INFO - Downloading https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/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 11:49:29 INFO - Downloaded 109757527 bytes. 11:49:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:49:29 INFO - mkdir: /builds/slave/test/properties 11:49:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:49:29 INFO - Writing to file /builds/slave/test/properties/build_url 11:49:29 INFO - Contents: 11:49:29 INFO - build_url:https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:49:29 INFO - Running post-action listener: _resource_record_post_action 11:49:29 INFO - Running post-action listener: set_extra_try_arguments 11:49:29 INFO - [mozharness: 2016-12-21 19:49:29.750049Z] Finished download-and-extract step (success) 11:49:29 INFO - [mozharness: 2016-12-21 19:49:29.750173Z] Running populate-webroot step. 11:49:29 INFO - Running pre-action listener: _resource_record_pre_action 11:49:29 INFO - Running main action method: populate_webroot 11:49:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:49:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:49:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:49:29 INFO - '--tppagecycles', 11:49:29 INFO - '7'], 11:49:29 INFO - 'tests': ['tsvgm']}}, 11:49:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:49:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:49:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:49:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:49:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:29 INFO - 'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:49:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:49:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:29 INFO - 'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['damp', 'tps']}, 11:49:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:29 INFO - 'tests': ['damp', 'tps']}, 11:49:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['dromaeo_dom']}, 11:49:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:49:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['basic_compositor_video']}, 11:49:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:49:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['a11yr', 11:49:29 INFO - 'ts_paint', 11:49:29 INFO - 'tpaint', 11:49:29 INFO - 'sessionrestore', 11:49:29 INFO - 'sessionrestore_no_auto_restore', 11:49:29 INFO - 'tabpaint']}, 11:49:29 INFO - 'other-e10s': {'tests': ['a11yr', 11:49:29 INFO - 'ts_paint', 11:49:29 INFO - 'tpaint', 11:49:29 INFO - 'sessionrestore', 11:49:29 INFO - 'sessionrestore_no_auto_restore', 11:49:29 INFO - 'tabpaint']}, 11:49:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:49:29 INFO - 'ts_paint', 11:49:29 INFO - 'tpaint', 11:49:29 INFO - 'sessionrestore', 11:49:29 INFO - 'sessionrestore_no_auto_restore', 11:49:29 INFO - 'tabpaint']}, 11:49:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:49:29 INFO - 'ts_paint', 11:49:29 INFO - 'tpaint', 11:49:29 INFO - 'sessionrestore', 11:49:29 INFO - 'sessionrestore_no_auto_restore', 11:49:29 INFO - 'tabpaint']}, 11:49:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['a11yr', 11:49:29 INFO - 'ts_paint', 11:49:29 INFO - 'tpaint', 11:49:29 INFO - 'sessionrestore', 11:49:29 INFO - 'sessionrestore_no_auto_restore', 11:49:29 INFO - 'tabpaint']}, 11:49:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['a11yr', 11:49:29 INFO - 'ts_paint', 11:49:29 INFO - 'tpaint', 11:49:29 INFO - 'sessionrestore', 11:49:29 INFO - 'sessionrestore_no_auto_restore', 11:49:29 INFO - 'tabpaint']}, 11:49:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['tsvgx', 11:49:29 INFO - 'tsvgr_opacity', 11:49:29 INFO - 'tart', 11:49:29 INFO - 'tscrollx', 11:49:29 INFO - 'cart']}, 11:49:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:49:29 INFO - 'tsvgr_opacity', 11:49:29 INFO - 'tart', 11:49:29 INFO - 'tscrollx', 11:49:29 INFO - 'cart']}, 11:49:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:29 INFO - 'talos_options': ['--disable-e10s'], 11:49:29 INFO - 'tests': ['tp5o']}, 11:49:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:29 INFO - 'tests': ['tp5o']}, 11:49:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:29 INFO - 'talos_options': ['--disable-e10s', 11:49:29 INFO - '--xperf_path', 11:49:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:49:29 INFO - 'tests': ['tp5n']}, 11:49:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:29 INFO - 'talos_options': ['--xperf_path', 11:49:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:49:29 INFO - 'tests': ['tp5n']}}, 11:49:29 INFO - 'talos.zip': {'path': '', 11:49:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:49:29 INFO - Running post-action listener: _resource_record_post_action 11:49:29 INFO - [mozharness: 2016-12-21 19:49:29.768862Z] Finished populate-webroot step (success) 11:49:29 INFO - [mozharness: 2016-12-21 19:49:29.768971Z] Running create-virtualenv step. 11:49:29 INFO - Running pre-action listener: _resource_record_pre_action 11:49:29 INFO - Running main action method: create_virtualenv 11:49:29 INFO - Creating virtualenv /builds/slave/test/build/venv 11:49:29 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 11:49:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:49:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:49:29 INFO - Using real prefix '/tools/python27' 11:49:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:49:30 INFO - Installing distribute.............................................................................................................................................................................................done. 11:49:33 INFO - Installing pip.................done. 11:49:33 INFO - Return code: 0 11:49:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:49:33 INFO - Reading from file tmpfile_stdout 11:49:33 INFO - Output received: 11:49:33 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) 11:49:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:49:33 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')]} 11:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:33 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')]} 11:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:33 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 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49:33 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 11:49:33 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 11:49:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:49:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:33 INFO - 'HOME': '/Users/cltbld', 11:49:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:33 INFO - 'LOGNAME': 'cltbld', 11:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:33 INFO - 'MOZ_NO_REMOTE': '1', 11:49:33 INFO - 'NO_EM_RESTART': '1', 11:49:33 INFO - 'PAGER': '/bin/cat', 11:49:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:33 INFO - 'PWD': '/builds/slave/test', 11:49:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:33 INFO - 'SHELL': '/bin/bash', 11:49:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:49:33 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:49:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:33 INFO - 'USER': 'cltbld', 11:49:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:33 INFO - 'XPC_FLAGS': '0x0', 11:49:33 INFO - 'XPC_SERVICE_NAME': '0', 11:49:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:34 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 11:49:34 INFO - Cleaning up... 11:49:34 INFO - Return code: 0 11:49:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:49:34 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')]} 11:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:34 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')]} 11:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:34 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 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49:34 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 11:49:34 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 11:49:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:49:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:34 INFO - 'HOME': '/Users/cltbld', 11:49:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:34 INFO - 'LOGNAME': 'cltbld', 11:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:34 INFO - 'MOZ_NO_REMOTE': '1', 11:49:34 INFO - 'NO_EM_RESTART': '1', 11:49:34 INFO - 'PAGER': '/bin/cat', 11:49:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:34 INFO - 'PWD': '/builds/slave/test', 11:49:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:34 INFO - 'SHELL': '/bin/bash', 11:49:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:49:34 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:49:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:34 INFO - 'USER': 'cltbld', 11:49:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:34 INFO - 'XPC_FLAGS': '0x0', 11:49:34 INFO - 'XPC_SERVICE_NAME': '0', 11:49:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:34 INFO - Downloading/unpacking psutil>=3.1.1 11:49: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 11:49: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 11:49: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 11:49: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 11:49: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 11:49: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 11:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:49:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:49:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:49:37 INFO - Installing collected packages: psutil 11:49:37 INFO - Running setup.py install for psutil 11:49:37 INFO - building 'psutil._psutil_osx' extension 11:49:37 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 11:49:38 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 11:49:38 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 11:49:38 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 11:49:38 INFO - building 'psutil._psutil_posix' extension 11:49:38 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 11:49:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:49:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:49:38 INFO - ^ 11:49:38 INFO - 1 warning generated. 11:49:38 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 11:49:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:49:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:49:38 INFO - Successfully installed psutil 11:49:38 INFO - Cleaning up... 11:49:38 INFO - Return code: 0 11:49:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:49: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')]} 11:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49: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')]} 11:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:49: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 mozsystemmonitor==0.3 11:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:38 INFO - 'HOME': '/Users/cltbld', 11:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:38 INFO - 'LOGNAME': 'cltbld', 11:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:38 INFO - 'MOZ_NO_REMOTE': '1', 11:49:38 INFO - 'NO_EM_RESTART': '1', 11:49:38 INFO - 'PAGER': '/bin/cat', 11:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:38 INFO - 'PWD': '/builds/slave/test', 11:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:38 INFO - 'SHELL': '/bin/bash', 11:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:49:38 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:38 INFO - 'USER': 'cltbld', 11:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:38 INFO - 'XPC_FLAGS': '0x0', 11:49:38 INFO - 'XPC_SERVICE_NAME': '0', 11:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:49:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:49:41 INFO - Installing collected packages: mozsystemmonitor 11:49:41 INFO - Running setup.py install for mozsystemmonitor 11:49:41 INFO - Successfully installed mozsystemmonitor 11:49:41 INFO - Cleaning up... 11:49:42 INFO - Return code: 0 11:49:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:49:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:49:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:42 INFO - 'HOME': '/Users/cltbld', 11:49:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:42 INFO - 'LOGNAME': 'cltbld', 11:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:42 INFO - 'MOZ_NO_REMOTE': '1', 11:49:42 INFO - 'NO_EM_RESTART': '1', 11:49:42 INFO - 'PAGER': '/bin/cat', 11:49:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:42 INFO - 'PWD': '/builds/slave/test', 11:49:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:42 INFO - 'SHELL': '/bin/bash', 11:49:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:49:42 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:49:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:42 INFO - 'USER': 'cltbld', 11:49:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:42 INFO - 'XPC_FLAGS': '0x0', 11:49:42 INFO - 'XPC_SERVICE_NAME': '0', 11:49:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:42 INFO - Downloading/unpacking jsonschema==2.5.1 11:49:42 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 11:49:42 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 11:49:42 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 11:49:42 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 11:49:42 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 11:49:42 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 11:49:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:49:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:45 INFO - Downloading functools32-3.2.3-2.tar.gz 11:49:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:49:45 INFO - warning: no files found matching '*.txt' 11:49:45 INFO - no previously-included directories found matching 'build' 11:49:45 INFO - no previously-included directories found matching 'dist' 11:49:45 INFO - no previously-included directories found matching '.git*' 11:49:45 INFO - Installing collected packages: jsonschema, functools32 11:49:45 INFO - Running setup.py install for functools32 11:49:45 INFO - warning: no files found matching '*.txt' 11:49:45 INFO - no previously-included directories found matching 'build' 11:49:45 INFO - no previously-included directories found matching 'dist' 11:49:45 INFO - no previously-included directories found matching '.git*' 11:49:45 INFO - Successfully installed jsonschema functools32 11:49:45 INFO - Cleaning up... 11:49:45 INFO - Return code: 0 11:49:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:45 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 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49:45 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 11:49:45 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 11:49:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:49:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:45 INFO - 'HOME': '/Users/cltbld', 11:49:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:45 INFO - 'LOGNAME': 'cltbld', 11:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:45 INFO - 'MOZ_NO_REMOTE': '1', 11:49:45 INFO - 'NO_EM_RESTART': '1', 11:49:45 INFO - 'PAGER': '/bin/cat', 11:49:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:45 INFO - 'PWD': '/builds/slave/test', 11:49:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:45 INFO - 'SHELL': '/bin/bash', 11:49:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:49:45 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:49:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:45 INFO - 'USER': 'cltbld', 11:49:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:45 INFO - 'XPC_FLAGS': '0x0', 11:49:45 INFO - 'XPC_SERVICE_NAME': '0', 11:49:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:49:46 INFO - Cleaning up... 11:49:46 INFO - Return code: 0 11:49:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:49:46 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')]} 11:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:46 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')]} 11:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:46 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 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49:46 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 11:49:46 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 11:49:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:49:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:46 INFO - 'HOME': '/Users/cltbld', 11:49:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:46 INFO - 'LOGNAME': 'cltbld', 11:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:46 INFO - 'MOZ_NO_REMOTE': '1', 11:49:46 INFO - 'NO_EM_RESTART': '1', 11:49:46 INFO - 'PAGER': '/bin/cat', 11:49:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:46 INFO - 'PWD': '/builds/slave/test', 11:49:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:46 INFO - 'SHELL': '/bin/bash', 11:49:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:49:46 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:49:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:46 INFO - 'USER': 'cltbld', 11:49:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:46 INFO - 'XPC_FLAGS': '0x0', 11:49:46 INFO - 'XPC_SERVICE_NAME': '0', 11:49:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:46 INFO - Downloading/unpacking blobuploader==1.2.4 11:49:46 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 11:49:46 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 11:49:46 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 11:49:46 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 11:49:46 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 11:49:46 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 11:49:48 INFO - Downloading blobuploader-1.2.4.tar.gz 11:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:49:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:49:48 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 11:49:48 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 11:49:48 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 11:49:48 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 11:49:48 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 11:49:48 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 11:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:49:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:50 INFO - Downloading docopt-0.6.1.tar.gz 11:49:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:49:50 INFO - Installing collected packages: blobuploader, requests, docopt 11:49:50 INFO - Running setup.py install for blobuploader 11:49:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:49:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:49:50 INFO - Running setup.py install for requests 11:49:50 INFO - Running setup.py install for docopt 11:49:50 INFO - Successfully installed blobuploader requests docopt 11:49:50 INFO - Cleaning up... 11:49:51 INFO - Return code: 0 11:49:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:49:51 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')]} 11:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:51 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')]} 11:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:51 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 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49:51 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 11:49:51 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 11:49:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:49:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:51 INFO - 'HOME': '/Users/cltbld', 11:49:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:51 INFO - 'LOGNAME': 'cltbld', 11:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:51 INFO - 'MOZ_NO_REMOTE': '1', 11:49:51 INFO - 'NO_EM_RESTART': '1', 11:49:51 INFO - 'PAGER': '/bin/cat', 11:49:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:51 INFO - 'PWD': '/builds/slave/test', 11:49:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:51 INFO - 'SHELL': '/bin/bash', 11:49:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:49:51 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:49:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:51 INFO - 'USER': 'cltbld', 11:49:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:51 INFO - 'XPC_FLAGS': '0x0', 11:49:51 INFO - 'XPC_SERVICE_NAME': '0', 11:49:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:49:51 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-jACBCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:49:51 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-D1qwM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:49:51 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-K5J_DW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:49:51 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-TSFH3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:49:51 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-IcgEit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:49:51 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-YkFh1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:49:51 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Lw8RWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-wUJJOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-m94l9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-g0DzKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-IqDl7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-4N1EZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-8oZfPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-WqMSz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-xOOtVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:49:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-XHHbi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:49:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-sTOACu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:49:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:49:53 INFO - Running setup.py install for manifestparser 11:49:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:49:53 INFO - Running setup.py install for mozcrash 11:49:53 INFO - Running setup.py install for mozdebug 11:49:53 INFO - Running setup.py install for mozdevice 11:49:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:49:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:49:53 INFO - Running setup.py install for mozfile 11:49:53 INFO - Running setup.py install for mozhttpd 11:49:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:49:54 INFO - Running setup.py install for mozinfo 11:49:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:49:54 INFO - Running setup.py install for mozInstall 11:49:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:49:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:49:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:49:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:49:54 INFO - Running setup.py install for mozleak 11:49:54 INFO - Running setup.py install for mozlog 11:49:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:49:54 INFO - Running setup.py install for moznetwork 11:49:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:49:54 INFO - Running setup.py install for mozprocess 11:49:54 INFO - Running setup.py install for mozprofile 11:49:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:49:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:49:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:49:55 INFO - Running setup.py install for mozrunner 11:49:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:49:55 INFO - Running setup.py install for mozscreenshot 11:49:55 INFO - Running setup.py install for moztest 11:49:55 INFO - Running setup.py install for mozversion 11:49:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:49:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:49:55 INFO - Cleaning up... 11:49:55 INFO - Return code: 0 11:49:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:49:55 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')]} 11:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:55 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')]} 11:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:55 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 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:49:55 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 11:49:55 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 11:49:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:49:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:55 INFO - 'HOME': '/Users/cltbld', 11:49:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:55 INFO - 'LOGNAME': 'cltbld', 11:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:55 INFO - 'MOZ_NO_REMOTE': '1', 11:49:55 INFO - 'NO_EM_RESTART': '1', 11:49:55 INFO - 'PAGER': '/bin/cat', 11:49:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:55 INFO - 'PWD': '/builds/slave/test', 11:49:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:55 INFO - 'SHELL': '/bin/bash', 11:49:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:49:55 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:49:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:55 INFO - 'USER': 'cltbld', 11:49:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:55 INFO - 'XPC_FLAGS': '0x0', 11:49:55 INFO - 'XPC_SERVICE_NAME': '0', 11:49:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-oXAaUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-00q0di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-a6t66d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-sHfn_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-jFH766-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-WYpkGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-9q9NXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-CVM4gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-bAtRzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:49:56 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)) 11:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:49:56 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-8s1VUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:49:57 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)) 11:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:49:57 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ywFhNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:49:57 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)) 11:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:49:57 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-xBy3I4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:49:57 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)) 11:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:49:57 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-NmS0rI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:49:57 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)) 11:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:49:57 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Lao1pK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:49:57 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)) 11:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:49:57 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-prAhGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:49:57 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)) 11:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:49:57 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-dkbT7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:49:57 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)) 11:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:49:57 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ZNerjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:49:57 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)) 11:49:57 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)) 11:49:57 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)) 11:49:57 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)) 11:49:57 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)) 11:49:57 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)) 11:49:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:49:57 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 11:49:57 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 11:49:57 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 11:49:57 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 11:49:57 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 11:49:57 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 11:50:01 INFO - Downloading blessings-1.6.tar.gz 11:50:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:50:01 INFO - Installing collected packages: blessings 11:50:01 INFO - Running setup.py install for blessings 11:50:02 INFO - Successfully installed blessings 11:50:02 INFO - Cleaning up... 11:50:02 INFO - Return code: 0 11:50:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:50:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:50:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:50:02 INFO - Reading from file tmpfile_stdout 11:50:02 INFO - Current package versions: 11:50:02 INFO - blessings == 1.6 11:50:02 INFO - blobuploader == 1.2.4 11:50:02 INFO - docopt == 0.6.1 11:50:02 INFO - functools32 == 3.2.3-2 11:50:02 INFO - jsonschema == 2.5.1 11:50:02 INFO - manifestparser == 1.1 11:50:02 INFO - mozInstall == 1.12 11:50:02 INFO - mozcrash == 1.0 11:50:02 INFO - mozdebug == 0.1 11:50:02 INFO - mozdevice == 0.48 11:50:02 INFO - mozfile == 1.2 11:50:02 INFO - mozhttpd == 0.7 11:50:02 INFO - mozinfo == 0.9 11:50:02 INFO - mozleak == 0.1 11:50:02 INFO - mozlog == 3.4 11:50:02 INFO - moznetwork == 0.27 11:50:02 INFO - mozprocess == 0.23 11:50:02 INFO - mozprofile == 0.28 11:50:02 INFO - mozrunner == 6.13 11:50:02 INFO - mozscreenshot == 0.1 11:50:02 INFO - mozsystemmonitor == 0.3 11:50:02 INFO - moztest == 0.7 11:50:02 INFO - mozversion == 1.4 11:50:02 INFO - psutil == 3.1.1 11:50:02 INFO - requests == 1.2.3 11:50:02 INFO - wsgiref == 0.1.2 11:50:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:50: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')]} 11:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50: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')]} 11:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:02 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 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:50:02 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 11:50:02 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 11:50:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:50:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:02 INFO - 'HOME': '/Users/cltbld', 11:50:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:02 INFO - 'LOGNAME': 'cltbld', 11:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:02 INFO - 'MOZ_NO_REMOTE': '1', 11:50:02 INFO - 'NO_EM_RESTART': '1', 11:50:02 INFO - 'PAGER': '/bin/cat', 11:50:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:02 INFO - 'PWD': '/builds/slave/test', 11:50:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:02 INFO - 'SHELL': '/bin/bash', 11:50:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:50:02 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:50:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:02 INFO - 'USER': 'cltbld', 11:50:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:02 INFO - 'XPC_FLAGS': '0x0', 11:50:02 INFO - 'XPC_SERVICE_NAME': '0', 11:50:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 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)) 11:50:03 INFO - Cleaning up... 11:50:03 INFO - Return code: 0 11:50:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:50:03 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')]} 11:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:03 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')]} 11:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:03 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 0x10fe8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e84e0bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, '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.TshqPUtU8T/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.DQHCoJB6jH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 11:50:03 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 11:50:03 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 11:50:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:50:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:03 INFO - 'HOME': '/Users/cltbld', 11:50:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:03 INFO - 'LOGNAME': 'cltbld', 11:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:03 INFO - 'MOZ_NO_REMOTE': '1', 11:50:03 INFO - 'NO_EM_RESTART': '1', 11:50:03 INFO - 'PAGER': '/bin/cat', 11:50:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:03 INFO - 'PWD': '/builds/slave/test', 11:50:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:03 INFO - 'SHELL': '/bin/bash', 11:50:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:50:03 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:50:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:03 INFO - 'USER': 'cltbld', 11:50:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:03 INFO - 'XPC_FLAGS': '0x0', 11:50:03 INFO - 'XPC_SERVICE_NAME': '0', 11:50:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:50:03 INFO - Cleaning up... 11:50:03 INFO - Return code: 0 11:50:03 INFO - Running post-action listener: _resource_record_post_action 11:50:03 INFO - Running post-action listener: _start_resource_monitoring 11:50:03 INFO - Starting resource monitoring. 11:50:03 INFO - [mozharness: 2016-12-21 19:50:03.417350Z] Finished create-virtualenv step (success) 11:50:03 INFO - [mozharness: 2016-12-21 19:50:03.418323Z] Running install step. 11:50:03 INFO - Running pre-action listener: _resource_record_pre_action 11:50:03 INFO - Running main action method: install 11:50:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:50:03 INFO - Reading from file tmpfile_stdout 11:50:03 INFO - Detecting whether we're running mozinstall >=1.0... 11:50:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:50:03 INFO - Reading from file tmpfile_stdout 11:50:03 INFO - Output received: 11:50:03 INFO - Usage: mozinstall [options] installer 11:50:03 INFO - Options: 11:50:03 INFO - -h, --help show this help message and exit 11:50:03 INFO - -d DEST, --destination=DEST 11:50:03 INFO - Directory to install application into. [default: 11:50:03 INFO - "/builds/slave/test"] 11:50:03 INFO - --app=APP Application being installed. [default: firefox] 11:50:03 INFO - mkdir: /builds/slave/test/build/application 11:50:03 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'] 11:50:03 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 11:50:34 INFO - Reading from file tmpfile_stdout 11:50:34 INFO - Output received: 11:50:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:50:34 INFO - Running post-action listener: _resource_record_post_action 11:50:34 INFO - [mozharness: 2016-12-21 19:50:34.934687Z] Finished install step (success) 11:50:34 INFO - [mozharness: 2016-12-21 19:50:34.934856Z] Running run-tests step. 11:50:34 INFO - Running pre-action listener: _resource_record_pre_action 11:50:34 INFO - Running main action method: run_tests 11:50:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:50:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:50:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:50:34 INFO - 2016-12-21 11:50:34.949 screenresolution[1627:7600] starting screenresolution argv=screenresolution get 11:50:34 INFO - 2016-12-21 11:50:34.952 screenresolution[1627:7600] Display 0: 1600x1200x32@60 11:50:34 INFO - 2016-12-21 11:50:34.958 screenresolution[1628:7602] starting screenresolution argv=screenresolution list 11:50:34 INFO - Available Modes on Display 0 11:50:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:50:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:50:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:50:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:50:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:50:35 INFO - Intel Iris: 11:50:35 INFO - Chipset Model: Intel Iris 11:50:35 INFO - Type: GPU 11:50:35 INFO - Bus: Built-In 11:50:35 INFO - VRAM (Dynamic, Max): 1536 MB 11:50:35 INFO - Vendor: Intel (0x8086) 11:50:35 INFO - Device ID: 0x0a2e 11:50:35 INFO - Revision ID: 0x0009 11:50:35 INFO - Displays: 11:50:35 INFO - Display: 11:50:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:50:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:50:35 INFO - Display Serial Number: 1600x1200 60 11:50:35 INFO - Main Display: Yes 11:50:35 INFO - Mirror: Off 11:50:35 INFO - Online: Yes 11:50:35 INFO - Rotation: Supported 11:50:35 INFO - Return code: 0 11:50:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:50:35 INFO - Python 2.7.3 11:50:35 INFO - Return code: 0 11:50:35 INFO - grabbing minidump binary from tooltool 11:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:35 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 0x10fca0d30>, '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 0x10fb06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:50:35 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 11:50:35 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 11:50:35 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 11:50:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:50:35 INFO - Return code: 0 11:50:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:50:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:50:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:50:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:50:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:50:35 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/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--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 11:50:35 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/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0251 --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 11:50:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TshqPUtU8T/Render', 11:50:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:35 INFO - 'HOME': '/Users/cltbld', 11:50:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:35 INFO - 'LOGNAME': 'cltbld', 11:50:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:50:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:35 INFO - 'MOZ_NO_REMOTE': '1', 11:50:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:50:35 INFO - 'NO_EM_RESTART': '1', 11:50:35 INFO - 'PAGER': '/bin/cat', 11:50:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:35 INFO - 'PWD': '/builds/slave/test', 11:50:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:50:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:35 INFO - 'SHELL': '/bin/bash', 11:50:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQHCoJB6jH/Listeners', 11:50:35 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 11:50:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:35 INFO - 'USER': 'cltbld', 11:50:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:35 INFO - 'XPC_FLAGS': '0x0', 11:50:35 INFO - 'XPC_SERVICE_NAME': '0', 11:50:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:35 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/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--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 11:50:35 INFO - mozversion application_buildid: 20161221095453 11:50:35 INFO - mozversion application_changeset: 3686b2e516446faba229534613cee5e2e2c82258 11:50:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:50:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:50:35 INFO - mozversion application_name: Firefox 11:50:35 INFO - mozversion application_remotingname: firefox-dev 11:50:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:50:35 INFO - mozversion application_vendor: Mozilla 11:50:35 INFO - mozversion application_version: 52.0a2 11:50:35 INFO - mozversion platform_buildid: 20161221095453 11:50:35 INFO - mozversion platform_changeset: 3686b2e516446faba229534613cee5e2e2c82258 11:50:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:50:35 INFO - mozversion platform_version: 52.0a2 11:50:35 INFO - using testdate: 1482349835 11:50:35 INFO - actual date: 1482349835 11:50:35 INFO - starting webserver on 'localhost:49249' 11:50:35 INFO - SUITE-START | Running 2 tests 11:50:35 INFO - TEST-START | dromaeo_css 11:50:35 INFO - operating with platform_type : mac_ 11:50:35 INFO - Initialising browser for dromaeo_css test... 11:50:35 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmp38AqoY/profile http://localhost:49249/getInfo.html) 11:50:38 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 11:50:38 INFO - PROCESS | 1638 | colorDepth:24 11:50:38 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 11:50:38 INFO - PROCESS | 1638 | __metrics 11:50:38 INFO - PROCESS | 1638 | 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" 11:50:38 INFO - PROCESS | 1638 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:50:39 INFO - TEST-INFO | 1638: exit 0 11:50:39 INFO - Browser initialized. 11:50:39 INFO - Running cycle 1/1 for dromaeo_css test... 11:50:39 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmp38AqoY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:51 INFO - PROCESS | 1640 | RSS: Main: 222560256 11:50:51 INFO - PROCESS | 1640 | 11:51:42 INFO - PROCESS | 1640 | 1482349902656 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] 11:53:57 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 11:53:59 INFO - PROCESS | 1640 | RSS: Main: 300277760 11:53:59 INFO - PROCESS | 1640 | 11:57:02 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 11:57:02 INFO - PROCESS | 1640 | RSS: Main: 354791424 11:57:02 INFO - PROCESS | 1640 | 12:00:05 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 12:00:05 INFO - PROCESS | 1640 | RSS: Main: 317349888 12:00:05 INFO - PROCESS | 1640 | 12:03:08 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 12:03:08 INFO - PROCESS | 1640 | RSS: Main: 320499712 12:03:08 INFO - PROCESS | 1640 | 12:06:12 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 12:06:13 INFO - PROCESS | 1640 | RSS: Main: 310710272 12:06:13 INFO - PROCESS | 1640 | 12:09:15 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 12:09:16 INFO - PROCESS | 1640 | RSS: Main: 363462656 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - PROCESS | 1640 | __start_tp_report 12:09:16 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 12:09:16 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 12:09:16 INFO - PROCESS | 1640 | |i|pagename|runs| 12:09:16 INFO - PROCESS | 1640 | |0;dojo.html;3292.670732926708;3299.307145499445;3334.816585087821;3335.7498187635933;3382.2051817822808;4579.335996280537;5462.53568446682;5527.39198688144;5557.888842223153;5561.749721262543;5542.8337149885465;5546.972265138679;5547.583931205166;5563.860903477415;5570.944290557093;5075.720835354054;5087.56755675768;5096.210087436435;5181.689098654074;5190.2474141249495;5058.974705126469;5068.087744206042;5077.974610126944;5104.157813960692;5181.0933086709865;5142.7428628568605;5160.509751573594;5167.4057483389515;5167.509086636783;5174.249733788598;5141.640085194038;5149.742512874342;5151.4075881273575;5155.381354237478;5161.019406312807;4991.32617096691;5102.744862756847;5106;5111.897762044739;5114.258432527263;5603.075492543725;5606.383297837235;5608.214849921014;5613.354464236624;5613.550915926757;5898.233229680157;5941.524678025791;5944.791932282393;5946.910796338058;5963.403659634002;6061.696915154224;6067.211262535842;6071.544634152457;6071.787487437962;6087.634741915499;5571.609987300891;5577.52591029763;5591.3569939457075;5596.972015139898;5612.07400778871;6138.969305153535;6297.968510157511;6309.305976342599;6318.083877837687;6318.241810982711;5795.304563452329;5795.478406943395;5866.73599688015;5867.911981320283;5887.941120588825;6049.001914684028;6076.939230607726;6080.57435979486;6127.632342059491;6156.538259630545;6018.337982821886;6019.578629495981;6024.728887199999;6028.3971602839365;6066.332703402622;4406.669499787465;5829.242198514166;5852.356240813507;5867.354590994987;5991.730372133265;4785.473597904228;4799.088173247142;4818.469968303484;4819.9759001205475;4838.250071238964;4296.2911119664905;4301.010767523487;4304.375865499485;4310.202612516695;4341.0666706657585;4817.975910120497;5591.245181900392;5622.465865742748;5665.150227465913;5700.857478562986;6071.848203794941;6114.114890844268;6143.692815359214;6153.8461538461;6166.907496387557;5740.966626007359;5754.942450575524;5758.856028599234;5770.798022069207;5795.9420405796245;5143.6399452037995;5276.947230527646;5277.920831187535;5280.471952804689;5300.337457817773;5242.449542797951;6215.098810672337;6244.1570387997035;6282.2147231596055;6290.811275661737;6133.619935514652;6134.528431832502;6137.294211165773;6146.170267013896;6164.106204600218;6276.497880169667;6283.905741413791;6307.779227727007;6309.779157729457;6410.871782564416;5687.914681279867;5798.82603521895;5855.94144058554;5876.059830427112;5925.674087925206;6018.4884284834525;6031.547633927561;6109.205803245727;6118.174046503665;6139.785107521216;5853.648781072979;5863.442972917566;5870.442307980735;5872.295324560909;5884.32330282023;5731;5787.334456537552;5794.072948328416;5794.2177805997335;5882.323532793613;5561.5272701820795;5738.483536481736;5751.482366587027;5774.595778295479;5805.680687562056;5735.627184233012;5864.912026319694;5875.764969401176;5887.8233652990475;5897.734601943009;5597;5625.409332020243;5626.1560765883805;5682.460166284196;5699.116636921284 12:09:16 INFO - PROCESS | 1640 | |1;ext.html;519.7167543688689;533.6077982682727;537.4464301769174;539.827255278311;565.4684596479309;29774.851125744368;39473.60526394735;40054;40204.19591608166;41667.79166104165;40809.18381632365;41157;41272.17455650885;41273.793631031804;41385.172296554054;9267.351285410025;9408;9425.151736343727;9434.292428067922;9443.338966272364;9312.255019598417;9425.104615061593;9434.386764860297;9441.76395590109;9445.763855903622;9361.625534978593;9410.52947352633;9414.293927955396;9415.717528474157;9418.764530886749;9353.859692104625;9425.717228483156;9435.90564094357;9441.575129119206;9451.952740236324;9351.953240233823;9407.670731524433;9423;9424.575894084783;9439.339246252764;38678.22643547113;39077.80461097676;39193;39234.80382598098;39414.40878386827;3156.589643346373;3288.4245257080156;3346.5482159908343;3656.213914008501;3816.103215744298;22576.77423225763;27954;29271.121866344045;29923.70076299253;30188.698113018807;3130.1861516005774;3179.3164469639137;3182.347618738153;3273.9836300818583;3285.671432856719;456018;458043;458538;458977;460327;30685.693143068285;30886.91895783637;31059.84470077635;58501.70749146312;58914.41085589175;35388.82305588455;35464.46803297952;35543;35629.82185089058;35663.82168089143;14040.71918561623;14193.148411095368;14196.64508387278;14222.431102755774;14227.35976881043;11776.293422394683;11881.762364752829;11882.346470944181;11901.702457438634;11904.821427678591;7751.147373788879;7787.92212077872;7790.454668173174;7850.018747656543;7877.172896845863;3192.8403579820915;3213.9839300803815;3217.3726123406177;3225.741940644743;3267.836608169582;803.3171803966582;864.489950928955;963.9396663670047;1029.9691009269734;1105.4417519152937;25583.61624575633;26915.596266056025;27842.582361264598;28977;29583.408331833674;880.9780654440818;1021.4637315409469;1029.5226350187356;1057.0856209378833;1148.5405837664835;14584.416623334948;15059.849401506064;15091.471798487;15304.234788260543;15483;648570;652534;654560;656684;657486;45833;56071.71964140235;56429;56665.150022749694;56692.71653641788;1220.9694757630953;1263.4503990764047;1337.4516448256168;1353.188087147723;1372.945082196721;27079.1876243713;27677.723222767516;29935.4012919736;30177;30520.084397468112;48368.5163148364;48987.75506122447;49007.26489102595;49055.26417103818;49229;489457;557794;562720;564957;565017;23586.292411227692;24123.879380602986;25806.483870322114;26575.202743917715;26605.86697066502;44929.0055786691;44946.3258051123;46659.76670116763;46814.76592617015;47034.529654703016;35843.64156358403;37733.24533509369;37753.43369849509;39483.802580988064;39519.407208891316;37335.43996840104;37680.623193767715;37905.62094379021;38954.80522597369;39121.60878391293;37004.81497592495;37179.814100929325;37190.8140459296;37672.43491347577;37727;31445.213869652525;33781.49327760036;33884.49173262453;34022.82988585041;34098.829505853304;24304.270871873872;25131;25249.36876578027;25335.493290134462;25732.61401079023 12:09:16 INFO - PROCESS | 1640 | |2;jquery.html;292.8140630699506;352.46777366177065;367.86389036056664;376.37709590627514;402.21768659956376;14436.349502177663;14632.34154463049;16015.439459618901;20201.59596808063;20297.49256268594;18887.716684249714;19018.14418351174;20408.081636326362;20644.277450289243;20649.967501624935;5361.437049109846;5490.121580547113;5500.257465242201;5501.862453438656;5624.3531993820825;3920.4315374270714;4529;5527.005139074965;5531.032069387861;5590.720463976805;4157.376393540978;4161.812718427678;4166.437530933331;5823.970880145594;6144.2319710036245;6160.353162917896;6161.476274516672;6166.044263139221;6241.407066328691;6709.899351509683;5965.940340596582;5975.073863551156;5978.461938425563;5994.1608174855555;6011.458968692838;13238.801417978739;19810.61325707201;23596.528069438515;24411.023559057616;24639.87680061606;2295.7130358705163;2344.9296521104393;2353.411647088228;2358.9174378896655;2473.431110844516;9965.850512242323;9980.55087521056;10003.59985600575;10453.11148552374;10476.371417714885;1772.158224843202;1860.3860725960535;1864.2263460663808;1906.637738829618;1926.6050459655737;120525;127419;128272;129011.35494322563;131178.03232951608;9623.326367154232;10774.569017239379;11056.944715276371;11252.212345135762;11749.29504229749;14817.70364592702;14820.629484262765;14852.777208341884;14858.777118343234;14907.70184596302;8194.713185038496;8440.873386899202;8484.448510846774;8577.399582029198;8601.61292741828;8339.166083391612;8351.270759691157;8377.916220837835;8581.570921454027;8620.78448038804;4453.888652783707;4522.118186953512;4556.635469162526;4591.563801438858;4593.058423023273;1795.937142200043;1881.6707076261873;1921.0778826163519;1945.805419458043;1976.5453945592308;674.5076094451078;682.9931700683029;746.0562388578453;862.6420035685246;950.9049095090436;18664.066796660114;18736.969466679464;19259.61480770377;20749.962501874845;21012.894935525226;929.697848199338;936.1387523478417;945.8770359853175;954.7708549948102;964.8842138943231;10503.789924201626;10857.891421085687;10871.891281087088;10891.891081089247;10945.83581246282;595511;609467;616918;621851;622611;128974.06538901923;133858;133967;137746;138112;965.3725078698761;971.6404930175739;1003.4781913405135;1003.9096481316717;1004.4726518577805;13243.086520965373;16566.917165414096;17537.473875783748;17704.2033108513;17779.73330400071;54146.72926635342;54623;55196;55309.44690553043;55733;76692.23307766851;78073;78157.82763258442;79616.80574791499;80573.59713201397;7440.107187137578;7647.923520764721;8025.07711613172;8082.838343232985;8516.105808890225;10743.194260430342;11770.646880593597;11844.526218951145;12392;13091.738165236451;10380.740481488023;10584;10877.564897404016;11229.21695326891;11412.258203216765;10794.406307653155;10882.94558527234;10987.560497580327;11284.32294062359;11344.546218151512;11182.776344473228;11241.988221060144;11337.319760814378;11544;11547.422628868691;7747.535147891144;7863.960680196562;7920.287174154354;8306.62620182081;8319.916800831914;8102.663060595171;8751.781205469913;10958.890411095786;11234;11419 12:09:16 INFO - PROCESS | 1640 | |3;mootools.html;1295.9740805183897;1436.3823555870974;1556.8988015778973;1767.354915455859;1787.4726955548103;8079.111297757242;8487.981442226941;8501.44740591861;8506.91493085069;8509.78725531862;7432.777016689494;7719.614019299041;8013.67945282188;8266.256036956687;8309.127541608135;2319.246244970383;2344.519373528431;2345.319857241398;2348.988255058731;2356.1871154451774;2325.802306803924;2360.811135109187;2383.07060246504;2384.0821283805685;2386.53462574798;2020.9898950505226;2089.1747759634936;2249.808766254862;2355.1286024170995;2355.1286024170995;2319.895604697784;2356.15178535727;2356.705411823522;2356.7289761677284;2358.0685629176464;1981.5739615982634;2281.771822817705;2316.2819525946843;2317.119494592061;2319.5592837360846;8309.210624990616;8362.45644033136;8362.790930226733;8373.330133589297;8392.748217553422;5082.364704411949;5243.632945693803;5245.186996015624;5248.3701955765555;5260.263563101169;3297.4559197732583;3335.34960682667;3412.5563676721868;3498.807565583892;3500.3349363621087;1023.43199524264;1029.5006921642996;1035.8498017787379;1082.096449464698;1098.1160166066313;9206.447613143233;10514.526846291936;10722.14222862177;10769.299995500267;10824.512896919658;4535.455745310584;4602.240630296031;4714;4741.5021422750115;4743.66794324394;9523.666671666459;9527.142557169887;9542.236621070298;9555.041216550591;9556.139947404767;5259.474052594786;5264.842054738364;5265.183896496049;5268.078086334876;5270.604704647167;4608.239640459286;4745.264484004984;4811.446683631357;4813.470518242991;4835.960268542281;3690.1697118148204;3723.1809002019513;3747.6252374762853;3772.1135533149686;3821.1402434452025;1086.7719476991108;1154.4112502623723;1219.9451024703733;1225.2709637765515;1237.1958227152422;629.7323637454064;745.4117088043766;851.7274472168971;906.4878343735768;926.2636204217663;5270.49930256625;5713.371529131792;5727.2268243787385;5873.148393482921;5981.491573216238;893.3433926064338;905.090384163911;905.714699869539;921.5208091792231;933.8785957825439;3747.5690295616355;3762.510873586471;3775.5846856845724;3843.788591627487;3975.264576053439;8336.70821521256;9161.267098632226;10281.794364112826;10304.484775761182;10314.484275786332;9878.901210987942;10087.747806304755;10250.38497690141;10253.84619230712;10257.076863082359;7729.188435214334;7890.250426209501;7913.881291780628;7914.762557123296;7921;5480.616356855141;5489.286392768914;5518.475744804237;5640.012997725464;5655.971720141373;7939.682412703427;9371.34400591952;9533.189678877374;9551;9555;8998.460092394478;9424.764380890532;9424.905750942402;9440.33917625787;9465.100815422524;6214.9067763984485;7116.822079448055;7264.564126152236;7265.854682906206;7280.890786638099;7761.417893658111;7783.260590243918;7812.335951444191;7856.803579910548;7867.409944254089;7006.404455621126;7128.786136415916;7202.819929501804;7212.71149154028;7220.530665506725;7005.439564834699;7023;7036.718531258897;7063.223045464892;7113.359797618239;6646.567973081735;6652.73389064432;6657.966710166612;6694.765683201064;6749.493787965824;4844.757762111951;4860.392450943632;4879.438864530624;4925.064237794808;4949.7030178189425;4244.3633454982;4652.790624421841;4784.688995215439;4816.277558366273;5084.694918304914 12:09:16 INFO - PROCESS | 1640 | |4;prototype.html;300.37372079214833;304.0998644013721;310.1950438611802;316.27099535570517;317.4777491027262;4387.75663082616;4800.4719480857075;5020.623453241013;5285.392179899312;5317.085849094327;4044.4539987101734;4250.832075641422;4993.478293461806;5010.323606313146;5156.278121063054;3925.1757131002523;3955.367141257392;3972.36442169253;3979.621935916083;4002.2195671844;3118.5789918360956;3451.929901730459;3982.4424580558743;4003.778847451526;4008.97995510022;3591.892243232707;3626.87305944292;3958.366661334187;3975.264576053425;4007.7194596378267;3002;3922.5292964844116;3951.688262366519;3953.881383558469;3955.248502784462;2890.4363649088436;2926.648802143735;3041.6958304169625;3172.4924012157944;3490.5636795400574;4551.499335073139;4778.044391121754;4879.536444037846;5101.872453188663;5124.666896651743;4442.644588432918;4507.2788353863225;4548.340490628873;4558.247889098295;4560.563422521895;4330.1718583779175;4889.2910527973245;5390.838274851761;5413.188021796723;5422.267993820863;3277.6886195811358;3681.263747250534;3840.827162777654;3846.9230615387814;3878.3212937736066;4437.334399840018;5019.949800501985;5061.418676475024;5230.8692282692855;5554.944450555524;3869.968266660026;3873.903152421212;3886.7279290449396;3888.7277890547957;3893.3186692328727;3344;3673.779573225615;3828.105087981467;3958.7426817256787;3974.291054846386;3599.586047604507;4315.5900189481945;4494.483134439516;4541.931871021938;4547.04477074861;5127.359080114986;5179.756858354043;5402.621816472809;5462.672239665632;5506.752196151103;1097.3744965369663;1232.7795482472288;1247.95008199671;1253.147859455579;1279.9040071994639;786.9179877668206;807.9717209897625;820.3519219816278;864.9610767515477;866.1944391715639;4246.554111818276;4749.311349854252;4901.803927842918;4914.582260507826;4929.309896614477;4762.166620841339;4792.424909010941;5284.146560857171;5288.65623734456;5337.546308563816;4386.7367957922625;4674.4657682048355;4790.520947905182;4796.352492413549;4855.1260773060485;2235.636261880252;2238.854474459155;2625.908093216728;2661.8953134449375;2724.7057647617225;5510.531604813636;5527.613067085346;5580.274564306615;5593.20339830085;5613.270274864241;4449.532799056117;5553.416891226444;5818.330891947396;5818.854528636818;5833.7374818133285;5214.400343960418;5290.640443318521;5835.649861008353;6027.939720602738;6050.003498845371;4550.361869726962;4988.700677959334;5270.235815806764;5741.770329186786;5755.079187330007;4674.392328997209;5117.43708192106;5475.370332411823;5525.364583072998;5595.384507704068;5843.357230704534;5949.851253718692;5970.31341395745;5993.61041532299;5996.400359963969;3305.8181600144753;3717.572479164931;3799.6200379961783;3840.7887566184127;3850.441685955465;5628.521575666115;5640.435956404327;5641.830745077655;5642.802501912413;5662.85842853932;4676.836310729108;5496.520335926208;5517;5543.223948647112;5552.916706249491;4835.782389792397;5358.276632654698;5455.918161227664;5512.6968016759465;5520.503154716095;3869.9613003870727;4732.976335118303;5230.29391032221;5252.107141785829;5401.864953376197;4627.537246275346;5287.788488460418;5381.650192737459;5529.861753456195;5556.194351819045;5349.545288650508;5402.648827826179;5405.972970135124;5412.8646783829145;5416.918746218888 12:09:16 INFO - PROCESS | 1640 | |5;yui.html;705.9364657180853;736.69058995222;757.4357103957552;779.2986312318912;788.5426452657457;13721.725565488698;13843.446262149515;14095.859041409583;14148.221847798366;14269.928650356747;14814.333354999024;14865.108093514396;14886.404543818235;14905.776413353782;14911.403543858232;651.1404945471979;802.6307898366744;824.5423789796661;824.8061705499202;825.352098602597;663.6582160187507;791.0823444804029;810.1533897077568;816.6692489541715;822.3298012120133;675.8445557521773;858.4548811504708;859.0035558751849;864.0279685353977;864.8486514859906;854.1117238072397;855.0807881527321;856.755824589992;858.6136238692613;860.2988564320059;500.469502327534;509.53377659441566;605.6638565596093;615.673692942741;629.0250112325922;14041.368138433794;14269.78595321071;14300.284985750775;14343.354549045318;14344.354504047238;12985.22088674673;13190.670233244246;13300.268485233308;13323.26722030288;13351.799723004162;11984.221025633393;12103.636890893287;12116.575919842764;12169.574064907774;12305.753884922251;4315.82736690532;4356.427749178991;4362.214801335758;4373.737575745466;4536.977315113436;79286.60356698238;83398.58300708518;85387.5730621349;85760.57119714424;86719;10224.437655928996;10256.897431025594;10419.94790026045;10535.789284214272;10599;19640.116194771268;19781.20875165006;19887.502812429804;19926.50183745418;20025.499362515762;7440.702371905063;7521.172671006185;7559.1306999695735;7669.884951725729;7695.538267703843;4062.1469491406547;4081.6122468365456;4121.196366708523;4142.192272506893;4422.29243321067;2831.546952487592;2910.5488649258973;2952.276692210422;2953.350262942149;2958.73371396571;662.29134825736;662.6355504472521;664.0935123556408;669.4844969373555;678.6674529480518;629.789020678078;649.8375406148463;668.3784080804801;669.517947078103;676.090658064902;8881.911180888108;9112.817743645222;9280.728558161893;9287.071292870658;9309.674161404319;582.6125626458443;619.8543342314553;659.722916375114;686.7630667419733;687.3470203306879;3990.900227494336;4460.219461594208;4461.13007963444;4466.6873318867365;4532.773361331921;68296.31703682999;70367;85992.14007859967;106551;107063.46468267609;63437;70750.64624676947;70937;90992.5450372744;95207;72451.63774181095;72637;73789;74465;76235.85646215422;7733.419993500397;8590.742277731679;8598.312135029057;8746.431481953652;8973.551322433721;45209.54790452185;51307;53042;54030.45969540254;54653;76209;78222.2177778215;79644.20355796367;81005;81079.3784124326;11346.546138154712;11531.250468719507;11926.403679815801;11942.223755456214;11985.101117416054;9989.400635961865;11996.340201289013;12128.636140915787;12356.814647780471;12387.380630968235;11082.113430925669;11548.364839933884;11803.468843901874;12122.212056216318;12125.81811272814;10016.248781341574;11724.941375293069;11813.11401644863;11831.230969987268;11838.467268972745;8784.91215087841;9817.410955342702;11545.191836571696;11671.35807530594;11833.585824496102;9257.629694812133;10016.64941727036;10026.24803139782;10258.538365773707;10277.640282590373;9764.365316254421;10127.7468063299;10142.543585538817;10143.64497242593;10168.389896606228 12:09:16 INFO - PROCESS | 1640 | __end_tp_report 12:09:16 INFO - PROCESS | 1640 | __start_cc_report 12:09:16 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,2210 12:09:16 INFO - PROCESS | 1640 | __end_cc_report 12:09:16 INFO - PROCESS | 1640 | __startTimestamp1482350956162__endTimestamp 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - PROCESS | 1640 | ------- Summary: start ------- 12:09:16 INFO - PROCESS | 1640 | Number of tests: 6 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5574.71 Median:5740.97 stddev:621.59 (10.8%) stddev-sans-first:597.16 12:09:16 INFO - PROCESS | 1640 | Values: 3292.7 3299.3 3334.8 3335.7 3382.2 4579.3 5462.5 5527.4 5557.9 5561.7 5542.8 5547.0 5547.6 5563.9 5570.9 5075.7 5087.6 5096.2 5181.7 5190.2 5059.0 5068.1 5078.0 5104.2 5181.1 5142.7 5160.5 5167.4 5167.5 5174.2 5141.6 5149.7 5151.4 5155.4 5161.0 4991.3 5102.7 5106.0 5111.9 5114.3 5603.1 5606.4 5608.2 5613.4 5613.6 5898.2 5941.5 5944.8 5946.9 5963.4 6061.7 6067.2 6071.5 6071.8 6087.6 5571.6 5577.5 5591.4 5597.0 5612.1 6139.0 6298.0 6309.3 6318.1 6318.2 5795.3 5795.5 5866.7 5867.9 5887.9 6049.0 6076.9 6080.6 6127.6 6156.5 6018.3 6019.6 6024.7 6028.4 6066.3 4406.7 5829.2 5852.4 5867.4 5991.7 4785.5 4799.1 4818.5 4820.0 4838.3 4296.3 4301.0 4304.4 4310.2 4341.1 4818.0 5591.2 5622.5 5665.2 5700.9 6071.8 6114.1 6143.7 6153.8 6166.9 5741.0 5754.9 5758.9 5770.8 5795.9 5143.6 5276.9 5277.9 5280.5 5300.3 5242.4 6215.1 6244.2 6282.2 6290.8 6133.6 6134.5 6137.3 6146.2 6164.1 6276.5 6283.9 6307.8 6309.8 6410.9 5687.9 5798.8 5855.9 5876.1 5925.7 6018.5 6031.5 6109.2 6118.2 6139.8 5853.6 5863.4 5870.4 5872.3 5884.3 5731.0 5787.3 5794.1 5794.2 5882.3 5561.5 5738.5 5751.5 5774.6 5805.7 5735.6 5864.9 5875.8 5887.8 5897.7 5597.0 5625.4 5626.2 5682.5 5699.1 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:66607.85 Median:21247.16 stddev:149941.25 (705.7%) stddev-sans-first:150279.78 12:09:16 INFO - PROCESS | 1640 | Values: 519.7 533.6 537.4 539.8 565.5 29774.9 39473.6 40054.0 40204.2 41667.8 40809.2 41157.0 41272.2 41273.8 41385.2 9267.4 9408.0 9425.2 9434.3 9443.3 9312.3 9425.1 9434.4 9441.8 9445.8 9361.6 9410.5 9414.3 9415.7 9418.8 9353.9 9425.7 9435.9 9441.6 9452.0 9352.0 9407.7 9423.0 9424.6 9439.3 38678.2 39077.8 39193.0 39234.8 39414.4 3156.6 3288.4 3346.5 3656.2 3816.1 22576.8 27954.0 29271.1 29923.7 30188.7 3130.2 3179.3 3182.3 3274.0 3285.7 456018.0 458043.0 458538.0 458977.0 460327.0 30685.7 30886.9 31059.8 58501.7 58914.4 35388.8 35464.5 35543.0 35629.8 35663.8 14040.7 14193.1 14196.6 14222.4 14227.4 11776.3 11881.8 11882.3 11901.7 11904.8 7751.1 7787.9 7790.5 7850.0 7877.2 3192.8 3214.0 3217.4 3225.7 3267.8 803.3 864.5 963.9 1030.0 1105.4 25583.6 26915.6 27842.6 28977.0 29583.4 881.0 1021.5 1029.5 1057.1 1148.5 14584.4 15059.8 15091.5 15304.2 15483.0 648570.0 652534.0 654560.0 656684.0 657486.0 45833.0 56071.7 56429.0 56665.2 56692.7 1221.0 1263.5 1337.5 1353.2 1372.9 27079.2 27677.7 29935.4 30177.0 30520.1 48368.5 48987.8 49007.3 49055.3 49229.0 489457.0 557794.0 562720.0 564957.0 565017.0 23586.3 24123.9 25806.5 26575.2 26605.9 44929.0 44946.3 46659.8 46814.8 47034.5 35843.6 37733.2 37753.4 39483.8 39519.4 37335.4 37680.6 37905.6 38954.8 39121.6 37004.8 37179.8 37190.8 37672.4 37727.0 31445.2 33781.5 33884.5 34022.8 34098.8 24304.3 25131.0 25249.4 25335.5 25732.6 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:35543.93 Median:24525.45 stddev:102717.74 (418.8%) stddev-sans-first:102971.79 12:09:16 INFO - PROCESS | 1640 | Values: 292.8 352.5 367.9 376.4 402.2 14436.3 14632.3 16015.4 20201.6 20297.5 18887.7 19018.1 20408.1 20644.3 20650.0 5361.4 5490.1 5500.3 5501.9 5624.4 3920.4 4529.0 5527.0 5531.0 5590.7 4157.4 4161.8 4166.4 5824.0 6144.2 6160.4 6161.5 6166.0 6241.4 6709.9 5965.9 5975.1 5978.5 5994.2 6011.5 13238.8 19810.6 23596.5 24411.0 24639.9 2295.7 2344.9 2353.4 2358.9 2473.4 9965.9 9980.6 10003.6 10453.1 10476.4 1772.2 1860.4 1864.2 1906.6 1926.6 120525.0 127419.0 128272.0 129011.4 131178.0 9623.3 10774.6 11056.9 11252.2 11749.3 14817.7 14820.6 14852.8 14858.8 14907.7 8194.7 8440.9 8484.4 8577.4 8601.6 8339.2 8351.3 8377.9 8581.6 8620.8 4453.9 4522.1 4556.6 4591.6 4593.1 1795.9 1881.7 1921.1 1945.8 1976.5 674.5 683.0 746.1 862.6 950.9 18664.1 18737.0 19259.6 20750.0 21012.9 929.7 936.1 945.9 954.8 964.9 10503.8 10857.9 10871.9 10891.9 10945.8 595511.0 609467.0 616918.0 621851.0 622611.0 128974.1 133858.0 133967.0 137746.0 138112.0 965.4 971.6 1003.5 1003.9 1004.5 13243.1 16566.9 17537.5 17704.2 17779.7 54146.7 54623.0 55196.0 55309.4 55733.0 76692.2 78073.0 78157.8 79616.8 80573.6 7440.1 7647.9 8025.1 8082.8 8516.1 10743.2 11770.6 11844.5 12392.0 13091.7 10380.7 10584.0 10877.6 11229.2 11412.3 10794.4 10882.9 10987.6 11284.3 11344.5 11182.8 11242.0 11337.3 11544.0 11547.4 7747.5 7864.0 7920.3 8306.6 8319.9 8102.7 8751.8 10958.9 11234.0 11419.0 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5429.48 Median:5259.87 stddev:2956.71 (56.2%) stddev-sans-first:2948.68 12:09:16 INFO - PROCESS | 1640 | Values: 1296.0 1436.4 1556.9 1767.4 1787.5 8079.1 8488.0 8501.4 8506.9 8509.8 7432.8 7719.6 8013.7 8266.3 8309.1 2319.2 2344.5 2345.3 2349.0 2356.2 2325.8 2360.8 2383.1 2384.1 2386.5 2021.0 2089.2 2249.8 2355.1 2355.1 2319.9 2356.2 2356.7 2356.7 2358.1 1981.6 2281.8 2316.3 2317.1 2319.6 8309.2 8362.5 8362.8 8373.3 8392.7 5082.4 5243.6 5245.2 5248.4 5260.3 3297.5 3335.3 3412.6 3498.8 3500.3 1023.4 1029.5 1035.8 1082.1 1098.1 9206.4 10514.5 10722.1 10769.3 10824.5 4535.5 4602.2 4714.0 4741.5 4743.7 9523.7 9527.1 9542.2 9555.0 9556.1 5259.5 5264.8 5265.2 5268.1 5270.6 4608.2 4745.3 4811.4 4813.5 4836.0 3690.2 3723.2 3747.6 3772.1 3821.1 1086.8 1154.4 1219.9 1225.3 1237.2 629.7 745.4 851.7 906.5 926.3 5270.5 5713.4 5727.2 5873.1 5981.5 893.3 905.1 905.7 921.5 933.9 3747.6 3762.5 3775.6 3843.8 3975.3 8336.7 9161.3 10281.8 10304.5 10314.5 9878.9 10087.7 10250.4 10253.8 10257.1 7729.2 7890.3 7913.9 7914.8 7921.0 5480.6 5489.3 5518.5 5640.0 5656.0 7939.7 9371.3 9533.2 9551.0 9555.0 8998.5 9424.8 9424.9 9440.3 9465.1 6214.9 7116.8 7264.6 7265.9 7280.9 7761.4 7783.3 7812.3 7856.8 7867.4 7006.4 7128.8 7202.8 7212.7 7220.5 7005.4 7023.0 7036.7 7063.2 7113.4 6646.6 6652.7 6658.0 6694.8 6749.5 4844.8 4860.4 4879.4 4925.1 4949.7 4244.4 4652.8 4784.7 4816.3 5084.7 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4355.88 Median:4794.39 stddev:1371.96 (28.6%) stddev-sans-first:1341.62 12:09:16 INFO - PROCESS | 1640 | Values: 300.4 304.1 310.2 316.3 317.5 4387.8 4800.5 5020.6 5285.4 5317.1 4044.5 4250.8 4993.5 5010.3 5156.3 3925.2 3955.4 3972.4 3979.6 4002.2 3118.6 3451.9 3982.4 4003.8 4009.0 3591.9 3626.9 3958.4 3975.3 4007.7 3002.0 3922.5 3951.7 3953.9 3955.2 2890.4 2926.6 3041.7 3172.5 3490.6 4551.5 4778.0 4879.5 5101.9 5124.7 4442.6 4507.3 4548.3 4558.2 4560.6 4330.2 4889.3 5390.8 5413.2 5422.3 3277.7 3681.3 3840.8 3846.9 3878.3 4437.3 5019.9 5061.4 5230.9 5554.9 3870.0 3873.9 3886.7 3888.7 3893.3 3344.0 3673.8 3828.1 3958.7 3974.3 3599.6 4315.6 4494.5 4541.9 4547.0 5127.4 5179.8 5402.6 5462.7 5506.8 1097.4 1232.8 1248.0 1253.1 1279.9 786.9 808.0 820.4 865.0 866.2 4246.6 4749.3 4901.8 4914.6 4929.3 4762.2 4792.4 5284.1 5288.7 5337.5 4386.7 4674.5 4790.5 4796.4 4855.1 2235.6 2238.9 2625.9 2661.9 2724.7 5510.5 5527.6 5580.3 5593.2 5613.3 4449.5 5553.4 5818.3 5818.9 5833.7 5214.4 5290.6 5835.6 6027.9 6050.0 4550.4 4988.7 5270.2 5741.8 5755.1 4674.4 5117.4 5475.4 5525.4 5595.4 5843.4 5949.9 5970.3 5993.6 5996.4 3305.8 3717.6 3799.6 3840.8 3850.4 5628.5 5640.4 5641.8 5642.8 5662.9 4676.8 5496.5 5517.0 5543.2 5552.9 4835.8 5358.3 5455.9 5512.7 5520.5 3870.0 4733.0 5230.3 5252.1 5401.9 4627.5 5287.8 5381.7 5529.9 5556.2 5349.5 5402.6 5406.0 5412.9 5416.9 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:18854.31 Median:52174.50 stddev:26715.11 (51.2%) stddev-sans-first:26755.30 12:09:16 INFO - PROCESS | 1640 | Values: 705.9 736.7 757.4 779.3 788.5 13721.7 13843.4 14095.9 14148.2 14269.9 14814.3 14865.1 14886.4 14905.8 14911.4 651.1 802.6 824.5 824.8 825.4 663.7 791.1 810.2 816.7 822.3 675.8 858.5 859.0 864.0 864.8 854.1 855.1 856.8 858.6 860.3 500.5 509.5 605.7 615.7 629.0 14041.4 14269.8 14300.3 14343.4 14344.4 12985.2 13190.7 13300.3 13323.3 13351.8 11984.2 12103.6 12116.6 12169.6 12305.8 4315.8 4356.4 4362.2 4373.7 4537.0 79286.6 83398.6 85387.6 85760.6 86719.0 10224.4 10256.9 10419.9 10535.8 10599.0 19640.1 19781.2 19887.5 19926.5 20025.5 7440.7 7521.2 7559.1 7669.9 7695.5 4062.1 4081.6 4121.2 4142.2 4422.3 2831.5 2910.5 2952.3 2953.4 2958.7 662.3 662.6 664.1 669.5 678.7 629.8 649.8 668.4 669.5 676.1 8881.9 9112.8 9280.7 9287.1 9309.7 582.6 619.9 659.7 686.8 687.3 3990.9 4460.2 4461.1 4466.7 4532.8 68296.3 70367.0 85992.1 106551.0 107063.5 63437.0 70750.6 70937.0 90992.5 95207.0 72451.6 72637.0 73789.0 74465.0 76235.9 7733.4 8590.7 8598.3 8746.4 8973.6 45209.5 51307.0 53042.0 54030.5 54653.0 76209.0 78222.2 79644.2 81005.0 81079.4 11346.5 11531.3 11926.4 11942.2 11985.1 9989.4 11996.3 12128.6 12356.8 12387.4 11082.1 11548.4 11803.5 12122.2 12125.8 10016.2 11724.9 11813.1 11831.2 11838.5 8784.9 9817.4 11545.2 11671.4 11833.6 9257.6 10016.6 10026.2 10258.5 10277.6 9764.4 10127.7 10142.5 10143.6 10168.4 12:09:16 INFO - PROCESS | 1640 | -------- Summary: end -------- 12:09:16 INFO - PROCESS | 1640 | 12:09:16 INFO - TEST-INFO | 1640: exit 0 12:09:16 INFO - TEST-OK | dromaeo_css | took 1121300ms 12:09:16 INFO - TEST-START | kraken 12:09:16 INFO - operating with platform_type : mac_ 12:09:16 INFO - Initialising browser for kraken test... 12:09:16 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpBWlHYS/profile http://localhost:49249/getInfo.html) 12:09:19 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 12:09:19 INFO - PROCESS | 1650 | colorDepth:24 12:09:19 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 12:09:19 INFO - PROCESS | 1650 | __metrics 12:09:19 INFO - PROCESS | 1650 | 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" 12:09:19 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:09:20 INFO - TEST-INFO | 1650: exit 0 12:09:20 INFO - Browser initialized. 12:09:20 INFO - Running cycle 1/1 for kraken test... 12:09:20 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpBWlHYS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:32 INFO - PROCESS | 1651 | RSS: Main: 222658560 12:09:32 INFO - PROCESS | 1651 | 12:09:35 INFO - PROCESS | 1651 | 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) 12:09:36 INFO - PROCESS | 1651 | RSS: Main: 273223680 12:09:36 INFO - PROCESS | 1651 | 12:09:38 INFO - PROCESS | 1651 | 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) 12:09:39 INFO - PROCESS | 1651 | RSS: Main: 284925952 12:09:39 INFO - PROCESS | 1651 | 12:09:42 INFO - PROCESS | 1651 | 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) 12:09:43 INFO - PROCESS | 1651 | RSS: Main: 380325888 12:09:43 INFO - PROCESS | 1651 | 12:09:45 INFO - PROCESS | 1651 | 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) 12:09:45 INFO - PROCESS | 1651 | RSS: Main: 283918336 12:09:45 INFO - PROCESS | 1651 | 12:09:47 INFO - PROCESS | 1651 | 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) 12:09:48 INFO - PROCESS | 1651 | RSS: Main: 285573120 12:09:48 INFO - PROCESS | 1651 | 12:09:51 INFO - PROCESS | 1651 | 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) 12:09:51 INFO - PROCESS | 1651 | RSS: Main: 325627904 12:09:51 INFO - PROCESS | 1651 | 12:09:54 INFO - PROCESS | 1651 | 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) 12:09:55 INFO - PROCESS | 1651 | RSS: Main: 326107136 12:09:55 INFO - PROCESS | 1651 | 12:09:58 INFO - PROCESS | 1651 | 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) 12:09:58 INFO - PROCESS | 1651 | RSS: Main: 322957312 12:09:58 INFO - PROCESS | 1651 | 12:10:00 INFO - PROCESS | 1651 | 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) 12:10:00 INFO - PROCESS | 1651 | RSS: Main: 299458560 12:10:00 INFO - PROCESS | 1651 | 12:10:02 INFO - PROCESS | 1651 | 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) 12:10:03 INFO - PROCESS | 1651 | RSS: Main: 302080000 12:10:03 INFO - PROCESS | 1651 | 12:10:05 INFO - PROCESS | 1651 | 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) 12:10:06 INFO - PROCESS | 1651 | RSS: Main: 304410624 12:10:06 INFO - PROCESS | 1651 | 12:10:09 INFO - PROCESS | 1651 | 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) 12:10:09 INFO - PROCESS | 1651 | RSS: Main: 314707968 12:10:09 INFO - PROCESS | 1651 | 12:10:12 INFO - PROCESS | 1651 | 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) 12:10:13 INFO - PROCESS | 1651 | RSS: Main: 314986496 12:10:13 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | 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) 12:10:15 INFO - PROCESS | 1651 | RSS: Main: 358313984 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | __start_tp_report 12:10:15 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 12:10:15 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 12:10:15 INFO - PROCESS | 1651 | |i|pagename|runs| 12:10:15 INFO - PROCESS | 1651 | |0;ai-astar;116;116;115;114;112;115;114;113;104;107 12:10:15 INFO - PROCESS | 1651 | |1;audio-beat-detection;147;109;110;142;109;109;135;138;141;109 12:10:15 INFO - PROCESS | 1651 | |2;audio-dft;183;182;182;185;188;182;185;183;183;179 12:10:15 INFO - PROCESS | 1651 | |3;audio-fft;79;59;59;90;62;58;59;59;83;56 12:10:15 INFO - PROCESS | 1651 | |4;audio-oscillator;88;73;93;69;88;98;124;54;90;118 12:10:15 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;87;89;86;88;86;89;87;90;86 12:10:15 INFO - PROCESS | 1651 | |6;imaging-darkroom;95;96;95;94;95;94;94;94;95;95 12:10:15 INFO - PROCESS | 1651 | |7;imaging-desaturate;83;84;81;80;81;81;82;85;83;81 12:10:15 INFO - PROCESS | 1651 | |8;json-parse-financial;56;54;55;54;57;58;58;59;57;58 12:10:15 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;74;80;65;73;70;69;70;69;71;72 12:10:15 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;83;84;85;83;86;81;81;84;84;80 12:10:15 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;140;139;139;140;138;143;142;146;209;171 12:10:15 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;166;168;166;166;164;166;165;165;163;165 12:10:15 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;68;66;68;66;68;80;84;84;84 12:10:15 INFO - PROCESS | 1651 | __end_tp_report 12:10:15 INFO - PROCESS | 1651 | __start_cc_report 12:10:15 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1940 12:10:15 INFO - PROCESS | 1651 | __end_cc_report 12:10:15 INFO - PROCESS | 1651 | __startTimestamp1482351015334__endTimestamp 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | ------- Summary: start ------- 12:10:15 INFO - PROCESS | 1651 | Number of tests: 14 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.50 stddev:4.01 (3.5%) stddev-sans-first:4.06 12:10:15 INFO - PROCESS | 1651 | Values: 116.0 116.0 115.0 114.0 112.0 115.0 114.0 113.0 104.0 107.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:136.50 stddev:16.82 (12.3%) stddev-sans-first:15.83 12:10:15 INFO - PROCESS | 1651 | Values: 147.0 109.0 110.0 142.0 109.0 109.0 135.0 138.0 141.0 109.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.00 stddev:2.39 (1.3%) stddev-sans-first:2.54 12:10:15 INFO - PROCESS | 1651 | Values: 183.0 182.0 182.0 185.0 188.0 182.0 185.0 183.0 183.0 179.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:12.51 (20.7%) stddev-sans-first:12.41 12:10:15 INFO - PROCESS | 1651 | Values: 79.0 59.0 59.0 90.0 62.0 58.0 59.0 59.0 83.0 56.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:88.00 stddev:21.20 (24.1%) stddev-sans-first:22.48 12:10:15 INFO - PROCESS | 1651 | Values: 88.0 73.0 93.0 69.0 88.0 98.0 124.0 54.0 90.0 118.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:1.43 (1.6%) stddev-sans-first:1.51 12:10:15 INFO - PROCESS | 1651 | Values: 88.0 87.0 89.0 86.0 88.0 86.0 89.0 87.0 90.0 86.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 12:10:15 INFO - PROCESS | 1651 | Values: 95.0 96.0 95.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:82.10 Median:82.50 stddev:1.60 (1.9%) stddev-sans-first:1.66 12:10:15 INFO - PROCESS | 1651 | Values: 83.0 84.0 81.0 80.0 81.0 81.0 82.0 85.0 83.0 81.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.50 stddev:1.78 (3.1%) stddev-sans-first:1.87 12:10:15 INFO - PROCESS | 1651 | Values: 56.0 54.0 55.0 54.0 57.0 58.0 58.0 59.0 57.0 58.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:3.95 (5.5%) stddev-sans-first:4.06 12:10:15 INFO - PROCESS | 1651 | Values: 74.0 80.0 65.0 73.0 70.0 69.0 70.0 69.0 71.0 72.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:84.00 stddev:1.91 (2.3%) stddev-sans-first:2.03 12:10:15 INFO - PROCESS | 1651 | Values: 83.0 84.0 85.0 83.0 86.0 81.0 81.0 84.0 84.0 80.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:142.50 stddev:22.69 (15.9%) stddev-sans-first:23.73 12:10:15 INFO - PROCESS | 1651 | Values: 140.0 139.0 139.0 140.0 138.0 143.0 142.0 146.0 209.0 171.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:166.00 stddev:1.35 (0.8%) stddev-sans-first:1.41 12:10:15 INFO - PROCESS | 1651 | Values: 166.0 168.0 166.0 166.0 164.0 166.0 165.0 165.0 163.0 165.0 12:10:15 INFO - PROCESS | 1651 | 12:10:15 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:74.00 stddev:8.21 (11.1%) stddev-sans-first:8.45 12:10:15 INFO - PROCESS | 1651 | Values: 68.0 68.0 66.0 68.0 66.0 68.0 80.0 84.0 84.0 84.0 12:10:15 INFO - PROCESS | 1651 | -------- Summary: end -------- 12:10:15 INFO - PROCESS | 1651 | 12:10:16 INFO - TEST-INFO | 1651: exit 0 12:10:16 INFO - TEST-OK | kraken | took 59515ms 12:10:16 INFO - SUITE-END | took 1180s 12:10:16 INFO - Completed test suite (00:19:40) 12:10:16 INFO - javascript benchmark 12:10:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.670732926708, 3299.307145499445, 3334.816585087821, 3335.7498187635933, 3382.2051817822808, 4579.335996280537, 5462.53568446682, 5527.39198688144, 5557.888842223153, 5561.749721262543, 5542.8337149885465, 5546.972265138679, 5547.583931205166, 5563.860903477415, 5570.944290557093, 5075.720835354054, 5087.56755675768, 5096.210087436435, 5181.689098654074, 5190.2474141249495, 5058.974705126469, 5068.087744206042, 5077.974610126944, 5104.157813960692, 5181.0933086709865, 5142.7428628568605, 5160.509751573594, 5167.4057483389515, 5167.509086636783, 5174.249733788598, 5141.640085194038, 5149.742512874342, 5151.4075881273575, 5155.381354237478, 5161.019406312807, 4991.32617096691, 5102.744862756847, 5106.0, 5111.897762044739, 5114.258432527263, 5603.075492543725, 5606.383297837235, 5608.214849921014, 5613.354464236624, 5613.550915926757, 5898.233229680157, 5941.524678025791, 5944.791932282393, 5946.910796338058, 5963.403659634002, 6061.696915154224, 6067.211262535842, 6071.544634152457, 6071.787487437962, 6087.634741915499, 5571.609987300891, 5577.52591029763, 5591.3569939457075, 5596.972015139898, 5612.07400778871, 6138.969305153535, 6297.968510157511, 6309.305976342599, 6318.083877837687, 6318.241810982711, 5795.304563452329, 5795.478406943395, 5866.73599688015, 5867.911981320283, 5887.941120588825, 6049.001914684028, 6076.939230607726, 6080.57435979486, 6127.632342059491, 6156.538259630545, 6018.337982821886, 6019.578629495981, 6024.728887199999, 6028.3971602839365, 6066.332703402622, 4406.669499787465, 5829.242198514166, 5852.356240813507, 5867.354590994987, 5991.730372133265, 4785.473597904228, 4799.088173247142, 4818.469968303484, 4819.9759001205475, 4838.250071238964, 4296.2911119664905, 4301.010767523487, 4304.375865499485, 4310.202612516695, 4341.0666706657585, 4817.975910120497, 5591.245181900392, 5622.465865742748, 5665.150227465913, 5700.857478562986, 6071.848203794941, 6114.114890844268, 6143.692815359214, 6153.8461538461, 6166.907496387557, 5740.966626007359, 5754.942450575524, 5758.856028599234, 5770.798022069207, 5795.9420405796245, 5143.6399452037995, 5276.947230527646, 5277.920831187535, 5280.471952804689, 5300.337457817773, 5242.449542797951, 6215.098810672337, 6244.1570387997035, 6282.2147231596055, 6290.811275661737, 6133.619935514652, 6134.528431832502, 6137.294211165773, 6146.170267013896, 6164.106204600218, 6276.497880169667, 6283.905741413791, 6307.779227727007, 6309.779157729457, 6410.871782564416, 5687.914681279867, 5798.82603521895, 5855.94144058554, 5876.059830427112, 5925.674087925206, 6018.4884284834525, 6031.547633927561, 6109.205803245727, 6118.174046503665, 6139.785107521216, 5853.648781072979, 5863.442972917566, 5870.442307980735, 5872.295324560909, 5884.32330282023, 5731.0, 5787.334456537552, 5794.072948328416, 5794.2177805997335, 5882.323532793613, 5561.5272701820795, 5738.483536481736, 5751.482366587027, 5774.595778295479, 5805.680687562056, 5735.627184233012, 5864.912026319694, 5875.764969401176, 5887.8233652990475, 5897.734601943009, 5597.0, 5625.409332020243, 5626.1560765883805, 5682.460166284196, 5699.116636921284], "value": 5536.733314735901, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.7167543688689, 533.6077982682727, 537.4464301769174, 539.827255278311, 565.4684596479309, 29774.851125744368, 39473.60526394735, 40054.0, 40204.19591608166, 41667.79166104165, 40809.18381632365, 41157.0, 41272.17455650885, 41273.793631031804, 41385.172296554054, 9267.351285410025, 9408.0, 9425.151736343727, 9434.292428067922, 9443.338966272364, 9312.255019598417, 9425.104615061593, 9434.386764860297, 9441.76395590109, 9445.763855903622, 9361.625534978593, 9410.52947352633, 9414.293927955396, 9415.717528474157, 9418.764530886749, 9353.859692104625, 9425.717228483156, 9435.90564094357, 9441.575129119206, 9451.952740236324, 9351.953240233823, 9407.670731524433, 9423.0, 9424.575894084783, 9439.339246252764, 38678.22643547113, 39077.80461097676, 39193.0, 39234.80382598098, 39414.40878386827, 3156.589643346373, 3288.4245257080156, 3346.5482159908343, 3656.213914008501, 3816.103215744298, 22576.77423225763, 27954.0, 29271.121866344045, 29923.70076299253, 30188.698113018807, 3130.1861516005774, 3179.3164469639137, 3182.347618738153, 3273.9836300818583, 3285.671432856719, 456018.0, 458043.0, 458538.0, 458977.0, 460327.0, 30685.693143068285, 30886.91895783637, 31059.84470077635, 58501.70749146312, 58914.41085589175, 35388.82305588455, 35464.46803297952, 35543.0, 35629.82185089058, 35663.82168089143, 14040.71918561623, 14193.148411095368, 14196.64508387278, 14222.431102755774, 14227.35976881043, 11776.293422394683, 11881.762364752829, 11882.346470944181, 11901.702457438634, 11904.821427678591, 7751.147373788879, 7787.92212077872, 7790.454668173174, 7850.018747656543, 7877.172896845863, 3192.8403579820915, 3213.9839300803815, 3217.3726123406177, 3225.741940644743, 3267.836608169582, 803.3171803966582, 864.489950928955, 963.9396663670047, 1029.9691009269734, 1105.4417519152937, 25583.61624575633, 26915.596266056025, 27842.582361264598, 28977.0, 29583.408331833674, 880.9780654440818, 1021.4637315409469, 1029.5226350187356, 1057.0856209378833, 1148.5405837664835, 14584.416623334948, 15059.849401506064, 15091.471798487, 15304.234788260543, 15483.0, 648570.0, 652534.0, 654560.0, 656684.0, 657486.0, 45833.0, 56071.71964140235, 56429.0, 56665.150022749694, 56692.71653641788, 1220.9694757630953, 1263.4503990764047, 1337.4516448256168, 1353.188087147723, 1372.945082196721, 27079.1876243713, 27677.723222767516, 29935.4012919736, 30177.0, 30520.084397468112, 48368.5163148364, 48987.75506122447, 49007.26489102595, 49055.26417103818, 49229.0, 489457.0, 557794.0, 562720.0, 564957.0, 565017.0, 23586.292411227692, 24123.879380602986, 25806.483870322114, 26575.202743917715, 26605.86697066502, 44929.0055786691, 44946.3258051123, 46659.76670116763, 46814.76592617015, 47034.529654703016, 35843.64156358403, 37733.24533509369, 37753.43369849509, 39483.802580988064, 39519.407208891316, 37335.43996840104, 37680.623193767715, 37905.62094379021, 38954.80522597369, 39121.60878391293, 37004.81497592495, 37179.814100929325, 37190.8140459296, 37672.43491347577, 37727.0, 31445.213869652525, 33781.49327760036, 33884.49173262453, 34022.82988585041, 34098.829505853304, 24304.270871873872, 25131.0, 25249.36876578027, 25335.493290134462, 25732.61401079023], "value": 18064.502205855522, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.8140630699506, 352.46777366177065, 367.86389036056664, 376.37709590627514, 402.21768659956376, 14436.349502177663, 14632.34154463049, 16015.439459618901, 20201.59596808063, 20297.49256268594, 18887.716684249714, 19018.14418351174, 20408.081636326362, 20644.277450289243, 20649.967501624935, 5361.437049109846, 5490.121580547113, 5500.257465242201, 5501.862453438656, 5624.3531993820825, 3920.4315374270714, 4529.0, 5527.005139074965, 5531.032069387861, 5590.720463976805, 4157.376393540978, 4161.812718427678, 4166.437530933331, 5823.970880145594, 6144.2319710036245, 6160.353162917896, 6161.476274516672, 6166.044263139221, 6241.407066328691, 6709.899351509683, 5965.940340596582, 5975.073863551156, 5978.461938425563, 5994.1608174855555, 6011.458968692838, 13238.801417978739, 19810.61325707201, 23596.528069438515, 24411.023559057616, 24639.87680061606, 2295.7130358705163, 2344.9296521104393, 2353.411647088228, 2358.9174378896655, 2473.431110844516, 9965.850512242323, 9980.55087521056, 10003.59985600575, 10453.11148552374, 10476.371417714885, 1772.158224843202, 1860.3860725960535, 1864.2263460663808, 1906.637738829618, 1926.6050459655737, 120525.0, 127419.0, 128272.0, 129011.35494322563, 131178.03232951608, 9623.326367154232, 10774.569017239379, 11056.944715276371, 11252.212345135762, 11749.29504229749, 14817.70364592702, 14820.629484262765, 14852.777208341884, 14858.777118343234, 14907.70184596302, 8194.713185038496, 8440.873386899202, 8484.448510846774, 8577.399582029198, 8601.61292741828, 8339.166083391612, 8351.270759691157, 8377.916220837835, 8581.570921454027, 8620.78448038804, 4453.888652783707, 4522.118186953512, 4556.635469162526, 4591.563801438858, 4593.058423023273, 1795.937142200043, 1881.6707076261873, 1921.0778826163519, 1945.805419458043, 1976.5453945592308, 674.5076094451078, 682.9931700683029, 746.0562388578453, 862.6420035685246, 950.9049095090436, 18664.066796660114, 18736.969466679464, 19259.61480770377, 20749.962501874845, 21012.894935525226, 929.697848199338, 936.1387523478417, 945.8770359853175, 954.7708549948102, 964.8842138943231, 10503.789924201626, 10857.891421085687, 10871.891281087088, 10891.891081089247, 10945.83581246282, 595511.0, 609467.0, 616918.0, 621851.0, 622611.0, 128974.06538901923, 133858.0, 133967.0, 137746.0, 138112.0, 965.3725078698761, 971.6404930175739, 1003.4781913405135, 1003.9096481316717, 1004.4726518577805, 13243.086520965373, 16566.917165414096, 17537.473875783748, 17704.2033108513, 17779.73330400071, 54146.72926635342, 54623.0, 55196.0, 55309.44690553043, 55733.0, 76692.23307766851, 78073.0, 78157.82763258442, 79616.80574791499, 80573.59713201397, 7440.107187137578, 7647.923520764721, 8025.07711613172, 8082.838343232985, 8516.105808890225, 10743.194260430342, 11770.646880593597, 11844.526218951145, 12392.0, 13091.738165236451, 10380.740481488023, 10584.0, 10877.564897404016, 11229.21695326891, 11412.258203216765, 10794.406307653155, 10882.94558527234, 10987.560497580327, 11284.32294062359, 11344.546218151512, 11182.776344473228, 11241.988221060144, 11337.319760814378, 11544.0, 11547.422628868691, 7747.535147891144, 7863.960680196562, 7920.287174154354, 8306.62620182081, 8319.916800831914, 8102.663060595171, 8751.781205469913, 10958.890411095786, 11234.0, 11419.0], "value": 9300.96782460224, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.9740805183897, 1436.3823555870974, 1556.8988015778973, 1767.354915455859, 1787.4726955548103, 8079.111297757242, 8487.981442226941, 8501.44740591861, 8506.91493085069, 8509.78725531862, 7432.777016689494, 7719.614019299041, 8013.67945282188, 8266.256036956687, 8309.127541608135, 2319.246244970383, 2344.519373528431, 2345.319857241398, 2348.988255058731, 2356.1871154451774, 2325.802306803924, 2360.811135109187, 2383.07060246504, 2384.0821283805685, 2386.53462574798, 2020.9898950505226, 2089.1747759634936, 2249.808766254862, 2355.1286024170995, 2355.1286024170995, 2319.895604697784, 2356.15178535727, 2356.705411823522, 2356.7289761677284, 2358.0685629176464, 1981.5739615982634, 2281.771822817705, 2316.2819525946843, 2317.119494592061, 2319.5592837360846, 8309.210624990616, 8362.45644033136, 8362.790930226733, 8373.330133589297, 8392.748217553422, 5082.364704411949, 5243.632945693803, 5245.186996015624, 5248.3701955765555, 5260.263563101169, 3297.4559197732583, 3335.34960682667, 3412.5563676721868, 3498.807565583892, 3500.3349363621087, 1023.43199524264, 1029.5006921642996, 1035.8498017787379, 1082.096449464698, 1098.1160166066313, 9206.447613143233, 10514.526846291936, 10722.14222862177, 10769.299995500267, 10824.512896919658, 4535.455745310584, 4602.240630296031, 4714.0, 4741.5021422750115, 4743.66794324394, 9523.666671666459, 9527.142557169887, 9542.236621070298, 9555.041216550591, 9556.139947404767, 5259.474052594786, 5264.842054738364, 5265.183896496049, 5268.078086334876, 5270.604704647167, 4608.239640459286, 4745.264484004984, 4811.446683631357, 4813.470518242991, 4835.960268542281, 3690.1697118148204, 3723.1809002019513, 3747.6252374762853, 3772.1135533149686, 3821.1402434452025, 1086.7719476991108, 1154.4112502623723, 1219.9451024703733, 1225.2709637765515, 1237.1958227152422, 629.7323637454064, 745.4117088043766, 851.7274472168971, 906.4878343735768, 926.2636204217663, 5270.49930256625, 5713.371529131792, 5727.2268243787385, 5873.148393482921, 5981.491573216238, 893.3433926064338, 905.090384163911, 905.714699869539, 921.5208091792231, 933.8785957825439, 3747.5690295616355, 3762.510873586471, 3775.5846856845724, 3843.788591627487, 3975.264576053439, 8336.70821521256, 9161.267098632226, 10281.794364112826, 10304.484775761182, 10314.484275786332, 9878.901210987942, 10087.747806304755, 10250.38497690141, 10253.84619230712, 10257.076863082359, 7729.188435214334, 7890.250426209501, 7913.881291780628, 7914.762557123296, 7921.0, 5480.616356855141, 5489.286392768914, 5518.475744804237, 5640.012997725464, 5655.971720141373, 7939.682412703427, 9371.34400591952, 9533.189678877374, 9551.0, 9555.0, 8998.460092394478, 9424.764380890532, 9424.905750942402, 9440.33917625787, 9465.100815422524, 6214.9067763984485, 7116.822079448055, 7264.564126152236, 7265.854682906206, 7280.890786638099, 7761.417893658111, 7783.260590243918, 7812.335951444191, 7856.803579910548, 7867.409944254089, 7006.404455621126, 7128.786136415916, 7202.819929501804, 7212.71149154028, 7220.530665506725, 7005.439564834699, 7023.0, 7036.718531258897, 7063.223045464892, 7113.359797618239, 6646.567973081735, 6652.73389064432, 6657.966710166612, 6694.765683201064, 6749.493787965824, 4844.757762111951, 4860.392450943632, 4879.438864530624, 4925.064237794808, 4949.7030178189425, 4244.3633454982, 4652.790624421841, 4784.688995215439, 4816.277558366273, 5084.694918304914], "value": 4395.3321068883615, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.37372079214833, 304.0998644013721, 310.1950438611802, 316.27099535570517, 317.4777491027262, 4387.75663082616, 4800.4719480857075, 5020.623453241013, 5285.392179899312, 5317.085849094327, 4044.4539987101734, 4250.832075641422, 4993.478293461806, 5010.323606313146, 5156.278121063054, 3925.1757131002523, 3955.367141257392, 3972.36442169253, 3979.621935916083, 4002.2195671844, 3118.5789918360956, 3451.929901730459, 3982.4424580558743, 4003.778847451526, 4008.97995510022, 3591.892243232707, 3626.87305944292, 3958.366661334187, 3975.264576053425, 4007.7194596378267, 3002.0, 3922.5292964844116, 3951.688262366519, 3953.881383558469, 3955.248502784462, 2890.4363649088436, 2926.648802143735, 3041.6958304169625, 3172.4924012157944, 3490.5636795400574, 4551.499335073139, 4778.044391121754, 4879.536444037846, 5101.872453188663, 5124.666896651743, 4442.644588432918, 4507.2788353863225, 4548.340490628873, 4558.247889098295, 4560.563422521895, 4330.1718583779175, 4889.2910527973245, 5390.838274851761, 5413.188021796723, 5422.267993820863, 3277.6886195811358, 3681.263747250534, 3840.827162777654, 3846.9230615387814, 3878.3212937736066, 4437.334399840018, 5019.949800501985, 5061.418676475024, 5230.8692282692855, 5554.944450555524, 3869.968266660026, 3873.903152421212, 3886.7279290449396, 3888.7277890547957, 3893.3186692328727, 3344.0, 3673.779573225615, 3828.105087981467, 3958.7426817256787, 3974.291054846386, 3599.586047604507, 4315.5900189481945, 4494.483134439516, 4541.931871021938, 4547.04477074861, 5127.359080114986, 5179.756858354043, 5402.621816472809, 5462.672239665632, 5506.752196151103, 1097.3744965369663, 1232.7795482472288, 1247.95008199671, 1253.147859455579, 1279.9040071994639, 786.9179877668206, 807.9717209897625, 820.3519219816278, 864.9610767515477, 866.1944391715639, 4246.554111818276, 4749.311349854252, 4901.803927842918, 4914.582260507826, 4929.309896614477, 4762.166620841339, 4792.424909010941, 5284.146560857171, 5288.65623734456, 5337.546308563816, 4386.7367957922625, 4674.4657682048355, 4790.520947905182, 4796.352492413549, 4855.1260773060485, 2235.636261880252, 2238.854474459155, 2625.908093216728, 2661.8953134449375, 2724.7057647617225, 5510.531604813636, 5527.613067085346, 5580.274564306615, 5593.20339830085, 5613.270274864241, 4449.532799056117, 5553.416891226444, 5818.330891947396, 5818.854528636818, 5833.7374818133285, 5214.400343960418, 5290.640443318521, 5835.649861008353, 6027.939720602738, 6050.003498845371, 4550.361869726962, 4988.700677959334, 5270.235815806764, 5741.770329186786, 5755.079187330007, 4674.392328997209, 5117.43708192106, 5475.370332411823, 5525.364583072998, 5595.384507704068, 5843.357230704534, 5949.851253718692, 5970.31341395745, 5993.61041532299, 5996.400359963969, 3305.8181600144753, 3717.572479164931, 3799.6200379961783, 3840.7887566184127, 3850.441685955465, 5628.521575666115, 5640.435956404327, 5641.830745077655, 5642.802501912413, 5662.85842853932, 4676.836310729108, 5496.520335926208, 5517.0, 5543.223948647112, 5552.916706249491, 4835.782389792397, 5358.276632654698, 5455.918161227664, 5512.6968016759465, 5520.503154716095, 3869.9613003870727, 4732.976335118303, 5230.29391032221, 5252.107141785829, 5401.864953376197, 4627.537246275346, 5287.788488460418, 5381.650192737459, 5529.861753456195, 5556.194351819045, 5349.545288650508, 5402.648827826179, 5405.972970135124, 5412.8646783829145, 5416.918746218888], "value": 3921.5268963739445, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.9364657180853, 736.69058995222, 757.4357103957552, 779.2986312318912, 788.5426452657457, 13721.725565488698, 13843.446262149515, 14095.859041409583, 14148.221847798366, 14269.928650356747, 14814.333354999024, 14865.108093514396, 14886.404543818235, 14905.776413353782, 14911.403543858232, 651.1404945471979, 802.6307898366744, 824.5423789796661, 824.8061705499202, 825.352098602597, 663.6582160187507, 791.0823444804029, 810.1533897077568, 816.6692489541715, 822.3298012120133, 675.8445557521773, 858.4548811504708, 859.0035558751849, 864.0279685353977, 864.8486514859906, 854.1117238072397, 855.0807881527321, 856.755824589992, 858.6136238692613, 860.2988564320059, 500.469502327534, 509.53377659441566, 605.6638565596093, 615.673692942741, 629.0250112325922, 14041.368138433794, 14269.78595321071, 14300.284985750775, 14343.354549045318, 14344.354504047238, 12985.22088674673, 13190.670233244246, 13300.268485233308, 13323.26722030288, 13351.799723004162, 11984.221025633393, 12103.636890893287, 12116.575919842764, 12169.574064907774, 12305.753884922251, 4315.82736690532, 4356.427749178991, 4362.214801335758, 4373.737575745466, 4536.977315113436, 79286.60356698238, 83398.58300708518, 85387.5730621349, 85760.57119714424, 86719.0, 10224.437655928996, 10256.897431025594, 10419.94790026045, 10535.789284214272, 10599.0, 19640.116194771268, 19781.20875165006, 19887.502812429804, 19926.50183745418, 20025.499362515762, 7440.702371905063, 7521.172671006185, 7559.1306999695735, 7669.884951725729, 7695.538267703843, 4062.1469491406547, 4081.6122468365456, 4121.196366708523, 4142.192272506893, 4422.29243321067, 2831.546952487592, 2910.5488649258973, 2952.276692210422, 2953.350262942149, 2958.73371396571, 662.29134825736, 662.6355504472521, 664.0935123556408, 669.4844969373555, 678.6674529480518, 629.789020678078, 649.8375406148463, 668.3784080804801, 669.517947078103, 676.090658064902, 8881.911180888108, 9112.817743645222, 9280.728558161893, 9287.071292870658, 9309.674161404319, 582.6125626458443, 619.8543342314553, 659.722916375114, 686.7630667419733, 687.3470203306879, 3990.900227494336, 4460.219461594208, 4461.13007963444, 4466.6873318867365, 4532.773361331921, 68296.31703682999, 70367.0, 85992.14007859967, 106551.0, 107063.46468267609, 63437.0, 70750.64624676947, 70937.0, 90992.5450372744, 95207.0, 72451.63774181095, 72637.0, 73789.0, 74465.0, 76235.85646215422, 7733.419993500397, 8590.742277731679, 8598.312135029057, 8746.431481953652, 8973.551322433721, 45209.54790452185, 51307.0, 53042.0, 54030.45969540254, 54653.0, 76209.0, 78222.2177778215, 79644.20355796367, 81005.0, 81079.3784124326, 11346.546138154712, 11531.250468719507, 11926.403679815801, 11942.223755456214, 11985.101117416054, 9989.400635961865, 11996.340201289013, 12128.636140915787, 12356.814647780471, 12387.380630968235, 11082.113430925669, 11548.364839933884, 11803.468843901874, 12122.212056216318, 12125.81811272814, 10016.248781341574, 11724.941375293069, 11813.11401644863, 11831.230969987268, 11838.467268972745, 8784.91215087841, 9817.410955342702, 11545.191836571696, 11671.35807530594, 11833.585824496102, 9257.629694812133, 10016.64941727036, 10026.24803139782, 10258.538365773707, 10277.640282590373, 9764.365316254421, 10127.7468063299, 10142.543585538817, 10143.64497242593, 10168.389896606228], "value": 6993.653517207133, "unit": "score"}], "name": "dromaeo_css", "value": 6944.389237093689, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 115.0, 114.0, 112.0, 115.0, 114.0, 113.0, 104.0, 107.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 110.0, 142.0, 109.0, 109.0, 135.0, 138.0, 141.0, 109.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 182.0, 185.0, 188.0, 182.0, 185.0, 183.0, 183.0, 179.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 90.0, 62.0, 58.0, 59.0, 59.0, 83.0, 56.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 73.0, 93.0, 69.0, 88.0, 98.0, 124.0, 54.0, 90.0, 118.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 86.0, 88.0, 86.0, 89.0, 87.0, 90.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 81.0, 80.0, 81.0, 81.0, 82.0, 85.0, 83.0, 81.0], "value": 82.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 54.0, 57.0, 58.0, 58.0, 59.0, 57.0, 58.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 65.0, 73.0, 70.0, 69.0, 70.0, 69.0, 71.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 83.0, 86.0, 81.0, 81.0, 84.0, 84.0, 80.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 139.0, 140.0, 138.0, 143.0, 142.0, 146.0, 209.0, 171.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 166.0, 166.0, 164.0, 166.0, 165.0, 165.0, 163.0, 165.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 66.0, 68.0, 66.0, 68.0, 80.0, 84.0, 84.0, 84.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1441.7, "alertThreshold": 2.0}]} 12:10:16 INFO - Return code: 0 12:10:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:10:16 INFO - # TBPL SUCCESS # 12:10:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:16 INFO - 2016-12-21 12:10:16.601 screenresolution[1655:10200] starting screenresolution argv=screenresolution get 12:10:16 INFO - 2016-12-21 12:10:16.605 screenresolution[1655:10200] Display 0: 1600x1200x32@60 12:10:16 INFO - 2016-12-21 12:10:16.612 screenresolution[1656:10202] starting screenresolution argv=screenresolution list 12:10:16 INFO - Available Modes on Display 0 12:10:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:16 INFO - Intel Iris: 12:10:16 INFO - Chipset Model: Intel Iris 12:10:16 INFO - Type: GPU 12:10:16 INFO - Bus: Built-In 12:10:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:16 INFO - Vendor: Intel (0x8086) 12:10:16 INFO - Device ID: 0x0a2e 12:10:16 INFO - Revision ID: 0x0009 12:10:16 INFO - Displays: 12:10:16 INFO - Display: 12:10:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:16 INFO - Display Serial Number: 1600x1200 60 12:10:16 INFO - Main Display: Yes 12:10:16 INFO - Mirror: Off 12:10:16 INFO - Online: Yes 12:10:16 INFO - Rotation: Supported 12:10:16 INFO - Return code: 0 12:10:16 INFO - Running post-action listener: _resource_record_post_action 12:10:16 INFO - [mozharness: 2016-12-21 20:10:16.856441Z] Finished run-tests step (success) 12:10:16 INFO - Running post-run listener: _resource_record_post_run 12:10:16 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36664320; Write bytes: 653568512; Read time: 236; Write time: 1875 12:10:16 INFO - TinderboxPrint: CPU usage
53.5% 12:10:16 INFO - TinderboxPrint: I/O read bytes / time
36,664,320 / 236 12:10:16 INFO - TinderboxPrint: I/O write bytes / time
653,568,512 / 1,875 12:10:16 INFO - TinderboxPrint: CPU idle
2,253.2 (46.5%) 12:10:16 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 12:10:16 INFO - TinderboxPrint: CPU user
1,626.8 (33.5%) 12:10:16 INFO - TinderboxPrint: Swap in / out
385,200,128 / 0 12:10:16 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 344408576; Write bytes: 345344000; Read time: 26880; Write time: 702 12:10:16 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 34649088; Write bytes: 301347328; Read time: 217; Write time: 1145 12:10:17 INFO - Running post-run listener: _upload_blobber_files 12:10:17 INFO - Blob upload gear active. 12:10:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:10:17 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 12:10:17 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'] 12:10:17 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 12:10:17 INFO - (blobuploader) - INFO - Open directory for files ... 12:10:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:10:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:10:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:10:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:10:18 INFO - (blobuploader) - INFO - Done attempting. 12:10:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:10:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:10:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:10:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:10:19 INFO - (blobuploader) - INFO - Done attempting. 12:10:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:10:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:10:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:10:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:10:22 INFO - (blobuploader) - INFO - Done attempting. 12:10:22 INFO - (blobuploader) - INFO - Iteration through files over. 12:10:22 INFO - Return code: 0 12:10:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:10:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:10:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59848905cebf2fba7ebeb36cf242b8fb5fcaebaa118d0ac22e23664f34e8a24743460f07acd4627a05fb9c2cacda4ea05932a020fca42186af328991138ac8ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f594d04249d613381bac314ffe8fcb9e9009cb8533c998b53639257ec525e598afb7dbe09fd600120181275b726aab43d5dbf17c3364e2fa790d61892c6d5f23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00c7d03a9aca02c5e64dfee28a5a5d5f8153000272ec4d525a8e13b34b29349244f8225d18c1148a6fea2bd06f80e5c678d2fb01ffedaf3d8abf9b97d57e5e96"} 12:10:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:10:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:10:22 INFO - Contents: 12:10:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59848905cebf2fba7ebeb36cf242b8fb5fcaebaa118d0ac22e23664f34e8a24743460f07acd4627a05fb9c2cacda4ea05932a020fca42186af328991138ac8ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f594d04249d613381bac314ffe8fcb9e9009cb8533c998b53639257ec525e598afb7dbe09fd600120181275b726aab43d5dbf17c3364e2fa790d61892c6d5f23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00c7d03a9aca02c5e64dfee28a5a5d5f8153000272ec4d525a8e13b34b29349244f8225d18c1148a6fea2bd06f80e5c678d2fb01ffedaf3d8abf9b97d57e5e96"} 12:10:22 INFO - Running post-run listener: copy_logs_to_upload_dir 12:10:22 INFO - Copying logs to upload dir... 12:10:22 INFO - mkdir: /builds/slave/test/build/upload/logs 12:10:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.998856 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-12-21 12:10:22.306966) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 12:10:22.311197) ========= 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.TshqPUtU8T/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.DQHCoJB6jH/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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/59848905cebf2fba7ebeb36cf242b8fb5fcaebaa118d0ac22e23664f34e8a24743460f07acd4627a05fb9c2cacda4ea05932a020fca42186af328991138ac8ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f594d04249d613381bac314ffe8fcb9e9009cb8533c998b53639257ec525e598afb7dbe09fd600120181275b726aab43d5dbf17c3364e2fa790d61892c6d5f23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00c7d03a9aca02c5e64dfee28a5a5d5f8153000272ec4d525a8e13b34b29349244f8225d18c1148a6fea2bd06f80e5c678d2fb01ffedaf3d8abf9b97d57e5e96"} build_url:https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011104 build_url: 'https://queue.taskcluster.net/v1/task/QqrOm1YAQcuksvwNmueUpw/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/59848905cebf2fba7ebeb36cf242b8fb5fcaebaa118d0ac22e23664f34e8a24743460f07acd4627a05fb9c2cacda4ea05932a020fca42186af328991138ac8ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f594d04249d613381bac314ffe8fcb9e9009cb8533c998b53639257ec525e598afb7dbe09fd600120181275b726aab43d5dbf17c3364e2fa790d61892c6d5f23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00c7d03a9aca02c5e64dfee28a5a5d5f8153000272ec4d525a8e13b34b29349244f8225d18c1148a6fea2bd06f80e5c678d2fb01ffedaf3d8abf9b97d57e5e96"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 12:10:22.345982) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 12:10:22.346318) ========= 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.TshqPUtU8T/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.DQHCoJB6jH/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.005167 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 12:10:22.394982) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 12:10:22.395319) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 12:10:22.395669) ========= ========= Total master_lag: 0.18 =========