builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0337 starttime: 1491602519.2 results: success (0) buildid: 20170407133121 builduid: 2a0ada5df388430eb82c97934e90962e revision: 65bd52f60c9f0c5dec2901e5936c1eb4698d6709 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 15:01:59.195586) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 15:01:59.196047) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 15:01:59.196343) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 15:01:59.221748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:01:59.222151) ========= 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.o7nODwaoKi/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.reE1QMxNY1/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-07 15:01:59-- 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% 7.98M=0.001s 2017-04-07 15:01:59 (7.98 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101849 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:01:59.347698) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:01:59.348016) ========= 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.o7nODwaoKi/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.reE1QMxNY1/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.021112 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:01:59.396444) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 15:01:59.396813) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 65bd52f60c9f0c5dec2901e5936c1eb4698d6709 --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-beta --rev 65bd52f60c9f0c5dec2901e5936c1eb4698d6709 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o7nODwaoKi/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.reE1QMxNY1/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-07 15:01:59,539 truncating revision to first 12 chars 2017-04-07 15:01:59,539 Setting DEBUG logging. 2017-04-07 15:01:59,539 attempt 1/10 2017-04-07 15:01:59,539 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/65bd52f60c9f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 15:02:00,839 unpacking tar archive at: mozilla-beta-65bd52f60c9f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.568027 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 15:02:00.991236) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 15:02:00.991787) ========= script_repo_revision: 65bd52f60c9f0c5dec2901e5936c1eb4698d6709 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 15:02:00.992446) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 15:02:00.992904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 15:02:01.013517) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-04-07 15:02:01.013892) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o7nODwaoKi/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.reE1QMxNY1/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:01 INFO - MultiFileLogger online at 20170407 15:02:01 in /builds/slave/test 15:02:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:02:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:02:01 INFO - {'append_to_log': False, 15:02:01 INFO - 'base_work_dir': '/builds/slave/test', 15:02:01 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:02:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:02:01 INFO - 'branch': 'Mozilla-Beta', 15:02:01 INFO - 'buildbot_json_path': 'buildprops.json', 15:02:01 INFO - 'config_files': ('talos/mac_config.py',), 15:02:01 INFO - 'default_actions': ('clobber', 15:02:01 INFO - 'read-buildbot-config', 15:02:01 INFO - 'download-and-extract', 15:02:01 INFO - 'populate-webroot', 15:02:01 INFO - 'create-virtualenv', 15:02:01 INFO - 'install', 15:02:01 INFO - 'run-tests'), 15:02:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:02:01 INFO - 'download_minidump_stackwalk': True, 15:02:01 INFO - 'download_symbols': 'ondemand', 15:02:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:02:01 INFO - 'tooltool.py': '/tools/tooltool.py', 15:02:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:02:01 INFO - '/tools/misc-python/virtualenv.py')}, 15:02:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:02:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:02:01 INFO - 'installer_path': 'installer.exe', 15:02:01 INFO - 'log_level': 'info', 15:02:01 INFO - 'log_name': 'talos', 15:02:01 INFO - 'log_to_console': True, 15:02:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:02:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:02:01 INFO - 'opt_config_files': (), 15:02:01 INFO - 'pip_index': False, 15:02:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:02:01 INFO - 'cmd': ('bash', 15:02:01 INFO - '-c', 15:02:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:02:01 INFO - 'enabled': True, 15:02:01 INFO - 'halt_on_failure': False, 15:02:01 INFO - 'name': 'check_screen_resolution'},), 15:02:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:02:01 INFO - 'cmd': ('bash', 15:02:01 INFO - '-c', 15:02:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:02:01 INFO - 'enabled': True, 15:02:01 INFO - 'halt_on_failure': False, 15:02:01 INFO - 'name': 'check_screen_resolution'},), 15:02:01 INFO - 'run_cmd_checks_enabled': True, 15:02:01 INFO - 'sps_profile': False, 15:02:01 INFO - 'sps_profile_interval': 0, 15:02:01 INFO - 'suite': 'dromaeojs-e10s', 15:02:01 INFO - 'system_bits': '32', 15:02:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:02:01 INFO - 'title': 't-yosemite-r7-0337', 15:02:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:02:01 INFO - 'use_talos_json': True, 15:02:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:02:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:02:01 INFO - 'work_dir': 'build'} 15:02:01 INFO - [mozharness: 2017-04-07 22:02:01.275757Z] Running clobber step. 15:02:01 INFO - Running pre-action listener: _resource_record_pre_action 15:02:01 INFO - Running main action method: clobber 15:02:01 INFO - rmtree: /builds/slave/test/build 15:02:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:02:03 INFO - Running post-action listener: _resource_record_post_action 15:02:03 INFO - [mozharness: 2017-04-07 22:02:03.102858Z] Finished clobber step (success) 15:02:03 INFO - [mozharness: 2017-04-07 22:02:03.102983Z] Running read-buildbot-config step. 15:02:03 INFO - Running pre-action listener: _resource_record_pre_action 15:02:03 INFO - Running main action method: read_buildbot_config 15:02:03 INFO - Using buildbot properties: 15:02:03 INFO - { 15:02:03 INFO - "project": "", 15:02:03 INFO - "product": "firefox", 15:02:03 INFO - "script_repo_revision": "production", 15:02:03 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:02:03 INFO - "repository": "", 15:02:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:02:03 INFO - "buildid": "20170407133121", 15:02:03 INFO - "pgo_build": "False", 15:02:03 INFO - "basedir": "/builds/slave/test", 15:02:03 INFO - "buildnumber": 16, 15:02:03 INFO - "slavename": "t-yosemite-r7-0337", 15:02:03 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:02:03 INFO - "platform": "yosemite_r7", 15:02:03 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:02:03 INFO - "branch": "mozilla-beta", 15:02:03 INFO - "repo_path": "releases/mozilla-beta", 15:02:03 INFO - "revision": "65bd52f60c9f0c5dec2901e5936c1eb4698d6709", 15:02:03 INFO - "stage_platform": "macosx64", 15:02:03 INFO - "builduid": "2a0ada5df388430eb82c97934e90962e", 15:02:03 INFO - "slavebuilddir": "test" 15:02:03 INFO - } 15:02:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 15:02:03 INFO - Running post-action listener: _resource_record_post_action 15:02:03 INFO - [mozharness: 2017-04-07 22:02:03.106161Z] Finished read-buildbot-config step (success) 15:02:03 INFO - [mozharness: 2017-04-07 22:02:03.106302Z] Running download-and-extract step. 15:02:03 INFO - Running pre-action listener: _resource_record_pre_action 15:02:03 INFO - Running main action method: download_and_extract 15:02:03 INFO - mkdir: /builds/slave/test/build/tests 15:02: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')]} 15:02:03 INFO - trying https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 15:02:03 INFO - Downloading https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 15:02:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 15:02:04 INFO - Downloaded 1165 bytes. 15:02:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 15:02:04 INFO - Using the following test package requirements: 15:02:04 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 15:02:04 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:02:04 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 15:02:04 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:02:04 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 15:02:04 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:02:04 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:02:04 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 15:02:04 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 15:02:04 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:02:04 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 15:02:04 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:02:04 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 15:02:04 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:02:04 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 15:02:04 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:02:04 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 15:02:04 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 15:02:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 15:02:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 15:02:04 INFO - Fetch https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 15:02:05 INFO - Expected file size: 16116458 15:02:05 INFO - Obtained file size: 16116458 15:02:11 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:02:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 15:02:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 15:02:11 INFO - Fetch https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 15:02:12 INFO - Expected file size: 16116458 15:02:12 INFO - Obtained file size: 16116458 15:02:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 15:02:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 15:02:19 INFO - Fetch https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 15:02:21 INFO - Expected file size: 14403650 15:02:21 INFO - Obtained file size: 14403650 15:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:21 INFO - trying https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:02:21 INFO - Downloading https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 15:02:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 15:02:23 INFO - Downloaded 58739271 bytes. 15:02:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:02:23 INFO - mkdir: /builds/slave/test/properties 15:02:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:02:23 INFO - Writing to file /builds/slave/test/properties/build_url 15:02:23 INFO - Contents: 15:02:23 INFO - build_url:https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:02:24 INFO - Running post-action listener: _resource_record_post_action 15:02:24 INFO - Running post-action listener: set_extra_try_arguments 15:02:24 INFO - [mozharness: 2017-04-07 22:02:24.344888Z] Finished download-and-extract step (success) 15:02:24 INFO - [mozharness: 2017-04-07 22:02:24.345026Z] Running populate-webroot step. 15:02:24 INFO - Running pre-action listener: _resource_record_pre_action 15:02:24 INFO - Running main action method: populate_webroot 15:02:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:02:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:02:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:02:24 INFO - '--tppagecycles', 15:02:24 INFO - '7'], 15:02:24 INFO - 'tests': ['tsvgm']}}, 15:02:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:02:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:02:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:02:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:02:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:02:24 INFO - 'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:02:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:02:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:02:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:02:24 INFO - 'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['damp', 'tps']}, 15:02:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:02:24 INFO - 'tests': ['damp', 'tps']}, 15:02:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['dromaeo_dom']}, 15:02:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:02:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['basic_compositor_video']}, 15:02:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:02:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['a11yr', 15:02:24 INFO - 'ts_paint', 15:02:24 INFO - 'tpaint', 15:02:24 INFO - 'sessionrestore', 15:02:24 INFO - 'sessionrestore_no_auto_restore', 15:02:24 INFO - 'tabpaint']}, 15:02:24 INFO - 'other-e10s': {'tests': ['a11yr', 15:02:24 INFO - 'ts_paint', 15:02:24 INFO - 'tpaint', 15:02:24 INFO - 'sessionrestore', 15:02:24 INFO - 'sessionrestore_no_auto_restore', 15:02:24 INFO - 'tabpaint']}, 15:02:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:02:24 INFO - 'ts_paint', 15:02:24 INFO - 'tpaint', 15:02:24 INFO - 'sessionrestore', 15:02:24 INFO - 'sessionrestore_no_auto_restore', 15:02:24 INFO - 'tabpaint']}, 15:02:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:02:24 INFO - 'ts_paint', 15:02:24 INFO - 'tpaint', 15:02:24 INFO - 'sessionrestore', 15:02:24 INFO - 'sessionrestore_no_auto_restore', 15:02:24 INFO - 'tabpaint']}, 15:02:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['a11yr', 15:02:24 INFO - 'ts_paint', 15:02:24 INFO - 'tpaint', 15:02:24 INFO - 'sessionrestore', 15:02:24 INFO - 'sessionrestore_no_auto_restore', 15:02:24 INFO - 'tabpaint']}, 15:02:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['a11yr', 15:02:24 INFO - 'ts_paint', 15:02:24 INFO - 'tpaint', 15:02:24 INFO - 'sessionrestore', 15:02:24 INFO - 'sessionrestore_no_auto_restore', 15:02:24 INFO - 'tabpaint']}, 15:02:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['tsvgx', 15:02:24 INFO - 'tsvgr_opacity', 15:02:24 INFO - 'tart', 15:02:24 INFO - 'tscrollx', 15:02:24 INFO - 'cart', 15:02:24 INFO - 'tsvg_static']}, 15:02:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:02:24 INFO - 'tsvgr_opacity', 15:02:24 INFO - 'tart', 15:02:24 INFO - 'tscrollx', 15:02:24 INFO - 'cart', 15:02:24 INFO - 'tsvg_static']}, 15:02:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:02:24 INFO - 'talos_options': ['--disable-e10s'], 15:02:24 INFO - 'tests': ['tp5o']}, 15:02:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:02:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:02:24 INFO - 'talos_options': ['--disable-e10s', 15:02:24 INFO - '--xperf_path', 15:02:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:02:24 INFO - 'tests': ['tp5n']}, 15:02:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:02:24 INFO - 'talos_options': ['--xperf_path', 15:02:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:02:24 INFO - 'tests': ['tp5n']}}, 15:02:24 INFO - 'talos.zip': {'path': '', 15:02:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:02:24 INFO - Running post-action listener: _resource_record_post_action 15:02:24 INFO - [mozharness: 2017-04-07 22:02:24.363423Z] Finished populate-webroot step (success) 15:02:24 INFO - [mozharness: 2017-04-07 22:02:24.363554Z] Running create-virtualenv step. 15:02:24 INFO - Running pre-action listener: _resource_record_pre_action 15:02:24 INFO - Running main action method: create_virtualenv 15:02:24 INFO - Creating virtualenv /builds/slave/test/build/venv 15:02:24 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 15:02:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:02:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:02:24 INFO - Using real prefix '/tools/python27' 15:02:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:02:25 INFO - Installing distribute.............................................................................................................................................................................................done. 15:02:28 INFO - Installing pip.................done. 15:02:28 INFO - Return code: 0 15:02:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:02:28 INFO - Reading from file tmpfile_stdout 15:02:28 INFO - Output received: 15:02:28 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) 15:02:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:02:28 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')]} 15:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:28 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')]} 15:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:28 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:28 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 15:02:28 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 15:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:28 INFO - 'HOME': '/Users/cltbld', 15:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:28 INFO - 'LOGNAME': 'cltbld', 15:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:28 INFO - 'MOZ_NO_REMOTE': '1', 15:02:28 INFO - 'NO_EM_RESTART': '1', 15:02:28 INFO - 'PAGER': '/bin/cat', 15:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:28 INFO - 'PWD': '/builds/slave/test', 15:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:28 INFO - 'SHELL': '/bin/bash', 15:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:28 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:28 INFO - 'USER': 'cltbld', 15:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:28 INFO - 'XPC_FLAGS': '0x0', 15:02:28 INFO - 'XPC_SERVICE_NAME': '0', 15:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:28 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 15:02:28 INFO - Cleaning up... 15:02:28 INFO - Return code: 0 15:02:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:02:28 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')]} 15:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:28 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')]} 15:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:28 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:28 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 15:02:28 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 15:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:28 INFO - 'HOME': '/Users/cltbld', 15:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:28 INFO - 'LOGNAME': 'cltbld', 15:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:28 INFO - 'MOZ_NO_REMOTE': '1', 15:02:28 INFO - 'NO_EM_RESTART': '1', 15:02:28 INFO - 'PAGER': '/bin/cat', 15:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:28 INFO - 'PWD': '/builds/slave/test', 15:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:28 INFO - 'SHELL': '/bin/bash', 15:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:28 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:28 INFO - 'USER': 'cltbld', 15:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:28 INFO - 'XPC_FLAGS': '0x0', 15:02:28 INFO - 'XPC_SERVICE_NAME': '0', 15:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:29 INFO - Downloading/unpacking psutil>=3.1.1 15:02:29 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 15:02:29 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 15:02:29 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 15:02:29 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 15:02:29 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 15:02:29 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 15:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:02:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:02:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:02:32 INFO - Installing collected packages: psutil 15:02:32 INFO - Running setup.py install for psutil 15:02:32 INFO - building 'psutil._psutil_osx' extension 15:02:32 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 15:02:33 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 15:02:33 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 15:02:33 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 15:02:33 INFO - building 'psutil._psutil_posix' extension 15:02:33 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 15:02:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:02:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:02:33 INFO - ^ 15:02:33 INFO - 1 warning generated. 15:02:33 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 15:02:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:02:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:02:33 INFO - Successfully installed psutil 15:02:33 INFO - Cleaning up... 15:02:33 INFO - Return code: 0 15:02:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:02: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')]} 15:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02: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')]} 15:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:02: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 mozsystemmonitor==0.3 15:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:33 INFO - 'HOME': '/Users/cltbld', 15:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:33 INFO - 'LOGNAME': 'cltbld', 15:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:33 INFO - 'MOZ_NO_REMOTE': '1', 15:02:33 INFO - 'NO_EM_RESTART': '1', 15:02:33 INFO - 'PAGER': '/bin/cat', 15:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:33 INFO - 'PWD': '/builds/slave/test', 15:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:33 INFO - 'SHELL': '/bin/bash', 15:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:33 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:33 INFO - 'USER': 'cltbld', 15:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:33 INFO - 'XPC_FLAGS': '0x0', 15:02:33 INFO - 'XPC_SERVICE_NAME': '0', 15:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:02:33 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 15:02:33 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 15:02:33 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 15:02:33 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 15:02:33 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 15:02:33 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 15:02:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:02:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:02:36 INFO - Installing collected packages: mozsystemmonitor 15:02:36 INFO - Running setup.py install for mozsystemmonitor 15:02:36 INFO - Successfully installed mozsystemmonitor 15:02:36 INFO - Cleaning up... 15:02:36 INFO - Return code: 0 15:02:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:02:36 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')]} 15:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:36 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')]} 15:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:36 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:36 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 15:02:36 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 15:02:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:36 INFO - 'HOME': '/Users/cltbld', 15:02:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:36 INFO - 'LOGNAME': 'cltbld', 15:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:36 INFO - 'MOZ_NO_REMOTE': '1', 15:02:36 INFO - 'NO_EM_RESTART': '1', 15:02:36 INFO - 'PAGER': '/bin/cat', 15:02:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:36 INFO - 'PWD': '/builds/slave/test', 15:02:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:36 INFO - 'SHELL': '/bin/bash', 15:02:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:36 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:36 INFO - 'USER': 'cltbld', 15:02:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:36 INFO - 'XPC_FLAGS': '0x0', 15:02:36 INFO - 'XPC_SERVICE_NAME': '0', 15:02:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:37 INFO - Downloading/unpacking jsonschema==2.5.1 15:02:37 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 15:02:37 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 15:02:37 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 15:02:37 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 15:02:37 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 15:02:37 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 15:02:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:02:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02:40 INFO - Downloading functools32-3.2.3-2.tar.gz 15:02:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:02:40 INFO - warning: no files found matching '*.txt' 15:02:40 INFO - no previously-included directories found matching 'build' 15:02:40 INFO - no previously-included directories found matching 'dist' 15:02:40 INFO - no previously-included directories found matching '.git*' 15:02:40 INFO - Installing collected packages: jsonschema, functools32 15:02:40 INFO - Running setup.py install for functools32 15:02:40 INFO - warning: no files found matching '*.txt' 15:02:40 INFO - no previously-included directories found matching 'build' 15:02:40 INFO - no previously-included directories found matching 'dist' 15:02:40 INFO - no previously-included directories found matching '.git*' 15:02:40 INFO - Successfully installed jsonschema functools32 15:02:40 INFO - Cleaning up... 15:02:40 INFO - Return code: 0 15:02:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:02:40 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')]} 15:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:40 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')]} 15:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:40 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:40 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 15:02:40 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 15:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:40 INFO - 'HOME': '/Users/cltbld', 15:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:40 INFO - 'LOGNAME': 'cltbld', 15:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:40 INFO - 'MOZ_NO_REMOTE': '1', 15:02:40 INFO - 'NO_EM_RESTART': '1', 15:02:40 INFO - 'PAGER': '/bin/cat', 15:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:40 INFO - 'PWD': '/builds/slave/test', 15:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:40 INFO - 'SHELL': '/bin/bash', 15:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:40 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:40 INFO - 'USER': 'cltbld', 15:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:40 INFO - 'XPC_FLAGS': '0x0', 15:02:40 INFO - 'XPC_SERVICE_NAME': '0', 15:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:02:40 INFO - Cleaning up... 15:02:40 INFO - Return code: 0 15:02:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:02:40 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')]} 15:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:40 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')]} 15:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:40 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:40 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 15:02:40 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 15:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:40 INFO - 'HOME': '/Users/cltbld', 15:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:40 INFO - 'LOGNAME': 'cltbld', 15:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:40 INFO - 'MOZ_NO_REMOTE': '1', 15:02:40 INFO - 'NO_EM_RESTART': '1', 15:02:40 INFO - 'PAGER': '/bin/cat', 15:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:40 INFO - 'PWD': '/builds/slave/test', 15:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:40 INFO - 'SHELL': '/bin/bash', 15:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:40 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:40 INFO - 'USER': 'cltbld', 15:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:40 INFO - 'XPC_FLAGS': '0x0', 15:02:40 INFO - 'XPC_SERVICE_NAME': '0', 15:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:41 INFO - Downloading/unpacking blobuploader==1.2.4 15:02:41 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 15:02:41 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 15:02:41 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 15:02:41 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 15:02:41 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 15:02:41 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 15:02:43 INFO - Downloading blobuploader-1.2.4.tar.gz 15:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:02:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:02:43 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 15:02:43 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 15:02:43 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 15:02:43 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 15:02:43 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 15:02:43 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 15:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:02:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02:44 INFO - Downloading docopt-0.6.1.tar.gz 15:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:02:45 INFO - Installing collected packages: blobuploader, requests, docopt 15:02:45 INFO - Running setup.py install for blobuploader 15:02:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:02:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:02:45 INFO - Running setup.py install for requests 15:02:45 INFO - Running setup.py install for docopt 15:02:45 INFO - Successfully installed blobuploader requests docopt 15:02:45 INFO - Cleaning up... 15:02:45 INFO - Return code: 0 15:02:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02: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')]} 15:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02: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')]} 15:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:45 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:45 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 15:02:45 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 15:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:45 INFO - 'HOME': '/Users/cltbld', 15:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:45 INFO - 'LOGNAME': 'cltbld', 15:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:45 INFO - 'MOZ_NO_REMOTE': '1', 15:02:45 INFO - 'NO_EM_RESTART': '1', 15:02:45 INFO - 'PAGER': '/bin/cat', 15:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:45 INFO - 'PWD': '/builds/slave/test', 15:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:45 INFO - 'SHELL': '/bin/bash', 15:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:45 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:45 INFO - 'USER': 'cltbld', 15:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:45 INFO - 'XPC_FLAGS': '0x0', 15:02:45 INFO - 'XPC_SERVICE_NAME': '0', 15:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:46 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-C5382o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:46 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-2HHh2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:46 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-HrPTtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:46 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-xVP9lN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:46 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-l9YmYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:46 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-u_36Sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:46 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-55fosS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:46 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-xEIcqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-ESshHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-mjL3VZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-UU84m3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-yIqnvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-CKSsFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-RxQoD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-6TtFdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-eypMy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:47 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-3dowbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:02:48 INFO - Running setup.py install for manifestparser 15:02:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:02:48 INFO - Running setup.py install for mozcrash 15:02:48 INFO - Running setup.py install for mozdebug 15:02:48 INFO - Running setup.py install for mozdevice 15:02:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:02:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:02:48 INFO - Running setup.py install for mozfile 15:02:48 INFO - Running setup.py install for mozhttpd 15:02:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:02:48 INFO - Running setup.py install for mozinfo 15:02:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Running setup.py install for mozInstall 15:02:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Running setup.py install for mozleak 15:02:49 INFO - Running setup.py install for mozlog 15:02:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Running setup.py install for moznetwork 15:02:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Running setup.py install for mozprocess 15:02:49 INFO - Running setup.py install for mozprofile 15:02:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:02:49 INFO - Running setup.py install for mozrunner 15:02:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:02:50 INFO - Running setup.py install for mozscreenshot 15:02:50 INFO - Running setup.py install for moztest 15:02:50 INFO - Running setup.py install for mozversion 15:02:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:02:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:02:50 INFO - Cleaning up... 15:02:50 INFO - Return code: 0 15:02:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:50 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')]} 15:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:50 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')]} 15:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:50 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:50 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 15:02:50 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 15:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:50 INFO - 'HOME': '/Users/cltbld', 15:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:50 INFO - 'LOGNAME': 'cltbld', 15:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:50 INFO - 'MOZ_NO_REMOTE': '1', 15:02:50 INFO - 'NO_EM_RESTART': '1', 15:02:50 INFO - 'PAGER': '/bin/cat', 15:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:50 INFO - 'PWD': '/builds/slave/test', 15:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:50 INFO - 'SHELL': '/bin/bash', 15:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:50 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:50 INFO - 'USER': 'cltbld', 15:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:50 INFO - 'XPC_FLAGS': '0x0', 15:02:50 INFO - 'XPC_SERVICE_NAME': '0', 15:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:50 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-dwf7XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:50 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)) 15:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:50 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-lxXGqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:51 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-JDW0Xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:51 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-53r67i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-VFDfzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:51 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-cSopLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:51 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-ZH1soU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:51 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-j0KaWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:51 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-O0zS1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:51 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-78ZMMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:51 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)) 15:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:51 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-nNSfBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:52 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)) 15:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:52 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-DBJAE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 15:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:52 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-3zPRkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:52 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)) 15:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:52 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-XuGwkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:52 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)) 15:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:52 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-PtH9WM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:52 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)) 15:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:52 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-XfSHpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 15:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:52 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-xstU7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:52 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)) 15:02:52 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)) 15:02:52 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)) 15:02:52 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)) 15:02:52 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:02:52 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:02:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:02:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:55 INFO - Downloading blessings-1.6.tar.gz 15:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:02:55 INFO - Installing collected packages: blessings 15:02:55 INFO - Running setup.py install for blessings 15:02:55 INFO - Successfully installed blessings 15:02:55 INFO - Cleaning up... 15:02:55 INFO - Return code: 0 15:02:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:02:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:56 INFO - Reading from file tmpfile_stdout 15:02:56 INFO - Current package versions: 15:02:56 INFO - blessings == 1.6 15:02:56 INFO - blobuploader == 1.2.4 15:02:56 INFO - docopt == 0.6.1 15:02:56 INFO - functools32 == 3.2.3-2 15:02:56 INFO - jsonschema == 2.5.1 15:02:56 INFO - manifestparser == 1.1 15:02:56 INFO - mozInstall == 1.12 15:02:56 INFO - mozcrash == 1.0 15:02:56 INFO - mozdebug == 0.1 15:02:56 INFO - mozdevice == 0.49 15:02:56 INFO - mozfile == 1.2 15:02:56 INFO - mozhttpd == 0.7 15:02:56 INFO - mozinfo == 0.9 15:02:56 INFO - mozleak == 0.1 15:02:56 INFO - mozlog == 3.4 15:02:56 INFO - moznetwork == 0.27 15:02:56 INFO - mozprocess == 0.24 15:02:56 INFO - mozprofile == 0.28 15:02:56 INFO - mozrunner == 6.13 15:02:56 INFO - mozscreenshot == 0.1 15:02:56 INFO - mozsystemmonitor == 0.3 15:02:56 INFO - moztest == 0.8 15:02:56 INFO - mozversion == 1.4 15:02:56 INFO - psutil == 3.1.1 15:02:56 INFO - requests == 1.2.3 15:02:56 INFO - wsgiref == 0.1.2 15:02:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:56 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:56 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 15:02:56 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 15:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:56 INFO - 'HOME': '/Users/cltbld', 15:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:56 INFO - 'LOGNAME': 'cltbld', 15:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:56 INFO - 'MOZ_NO_REMOTE': '1', 15:02:56 INFO - 'NO_EM_RESTART': '1', 15:02:56 INFO - 'PAGER': '/bin/cat', 15:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:56 INFO - 'PWD': '/builds/slave/test', 15:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:56 INFO - 'SHELL': '/bin/bash', 15:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:56 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:56 INFO - 'USER': 'cltbld', 15:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:56 INFO - 'XPC_FLAGS': '0x0', 15:02:56 INFO - 'XPC_SERVICE_NAME': '0', 15:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 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)) 15:02:57 INFO - Cleaning up... 15:02:57 INFO - Return code: 0 15:02:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:02:57 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')]} 15:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:57 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')]} 15:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:57 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 0x10320cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc96b5bf3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, '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.o7nODwaoKi/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.reE1QMxNY1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 15:02:57 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 15:02:57 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 15:02:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:02:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:57 INFO - 'HOME': '/Users/cltbld', 15:02:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:57 INFO - 'LOGNAME': 'cltbld', 15:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:57 INFO - 'MOZ_NO_REMOTE': '1', 15:02:57 INFO - 'NO_EM_RESTART': '1', 15:02:57 INFO - 'PAGER': '/bin/cat', 15:02:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:57 INFO - 'PWD': '/builds/slave/test', 15:02:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:57 INFO - 'SHELL': '/bin/bash', 15:02:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:02:57 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:02:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:57 INFO - 'USER': 'cltbld', 15:02:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:57 INFO - 'XPC_FLAGS': '0x0', 15:02:57 INFO - 'XPC_SERVICE_NAME': '0', 15:02:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:02:57 INFO - Cleaning up... 15:02:57 INFO - Return code: 0 15:02:57 INFO - Running post-action listener: _resource_record_post_action 15:02:57 INFO - Running post-action listener: _start_resource_monitoring 15:02:57 INFO - Starting resource monitoring. 15:02:57 INFO - [mozharness: 2017-04-07 22:02:57.765720Z] Finished create-virtualenv step (success) 15:02:57 INFO - [mozharness: 2017-04-07 22:02:57.766726Z] Running install step. 15:02:57 INFO - Running pre-action listener: _resource_record_pre_action 15:02:57 INFO - Running main action method: install 15:02:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:58 INFO - Reading from file tmpfile_stdout 15:02:58 INFO - Detecting whether we're running mozinstall >=1.0... 15:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:02:58 INFO - Reading from file tmpfile_stdout 15:02:58 INFO - Output received: 15:02:58 INFO - Usage: mozinstall [options] installer 15:02:58 INFO - Options: 15:02:58 INFO - -h, --help show this help message and exit 15:02:58 INFO - -d DEST, --destination=DEST 15:02:58 INFO - Directory to install application into. [default: 15:02:58 INFO - "/builds/slave/test"] 15:02:58 INFO - --app=APP Application being installed. [default: firefox] 15:02:58 INFO - mkdir: /builds/slave/test/build/application 15:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:03:13 INFO - Reading from file tmpfile_stdout 15:03:13 INFO - Output received: 15:03:13 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:03:13 INFO - Running post-action listener: _resource_record_post_action 15:03:13 INFO - [mozharness: 2017-04-07 22:03:13.097096Z] Finished install step (success) 15:03:13 INFO - [mozharness: 2017-04-07 22:03:13.097280Z] Running run-tests step. 15:03:13 INFO - Running pre-action listener: _resource_record_pre_action 15:03:13 INFO - Running main action method: run_tests 15:03:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:03:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:03:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:03:13 INFO - 2017-04-07 15:03:13.112 screenresolution[1901:13069] starting screenresolution argv=screenresolution get 15:03:13 INFO - 2017-04-07 15:03:13.114 screenresolution[1901:13069] Display 0: 1600x1200x32@60 15:03:13 INFO - 2017-04-07 15:03:13.120 screenresolution[1902:13071] starting screenresolution argv=screenresolution list 15:03:13 INFO - Available Modes on Display 0 15:03:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:03:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:03:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:03:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:03:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:03:13 INFO - Intel Iris: 15:03:13 INFO - Chipset Model: Intel Iris 15:03:13 INFO - Type: GPU 15:03:13 INFO - Bus: Built-In 15:03:13 INFO - VRAM (Dynamic, Max): 1536 MB 15:03:13 INFO - Vendor: Intel (0x8086) 15:03:13 INFO - Device ID: 0x0a2e 15:03:13 INFO - Revision ID: 0x0009 15:03:13 INFO - Displays: 15:03:13 INFO - Display: 15:03:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:03:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:03:13 INFO - Display Serial Number: 1600x1200 60 15:03:13 INFO - Main Display: Yes 15:03:13 INFO - Mirror: Off 15:03:13 INFO - Online: Yes 15:03:13 INFO - Rotation: Supported 15:03:13 INFO - Return code: 0 15:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:03:13 INFO - Python 2.7.3 15:03:13 INFO - Return code: 0 15:03:13 INFO - grabbing minidump binary from tooltool 15:03:13 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')]} 15:03:13 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 0x102fead30>, '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 0x102f11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fdfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:03:13 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 15:03:13 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 15:03:13 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 15:03:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:03:13 INFO - Return code: 0 15:03:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:03:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:03:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:03:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:03:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0337 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:03:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o7nODwaoKi/Render', 15:03:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:13 INFO - 'HOME': '/Users/cltbld', 15:03:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:13 INFO - 'LOGNAME': 'cltbld', 15:03:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:03:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:13 INFO - 'MOZ_NO_REMOTE': '1', 15:03:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:03:13 INFO - 'NO_EM_RESTART': '1', 15:03:13 INFO - 'PAGER': '/bin/cat', 15:03:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:13 INFO - 'PWD': '/builds/slave/test', 15:03:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:03:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:13 INFO - 'SHELL': '/bin/bash', 15:03:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.reE1QMxNY1/Listeners', 15:03:13 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 15:03:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:13 INFO - 'USER': 'cltbld', 15:03:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:13 INFO - 'XPC_FLAGS': '0x0', 15:03:13 INFO - 'XPC_SERVICE_NAME': '0', 15:03:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:03:13 INFO - mozversion application_buildid: 20170407133121 15:03:13 INFO - mozversion application_changeset: 65bd52f60c9f0c5dec2901e5936c1eb4698d6709 15:03:13 INFO - mozversion application_display_name: Firefox 15:03:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:03:13 INFO - mozversion application_name: Firefox 15:03:13 INFO - mozversion application_remotingname: firefox 15:03:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:03:13 INFO - mozversion application_vendor: Mozilla 15:03:13 INFO - mozversion application_version: 53.0 15:03:13 INFO - mozversion platform_buildid: 20170407133121 15:03:13 INFO - mozversion platform_changeset: 65bd52f60c9f0c5dec2901e5936c1eb4698d6709 15:03:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:03:13 INFO - mozversion platform_version: 53.0 15:03:13 INFO - using testdate: 1491602593 15:03:13 INFO - actual date: 1491602593 15:03:13 INFO - starting webserver on 'localhost:51528' 15:03:13 INFO - SUITE-START | Running 2 tests 15:03:13 INFO - TEST-START | dromaeo_css 15:03:13 INFO - operating with platform_type : mac_ 15:03:13 INFO - Initialising browser for dromaeo_css test... 15:03:13 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmp4rM5fa/profile http://localhost:51528/getInfo.html) 15:03:16 INFO - PROCESS | 1912 | 2017-04-07 15:03:16.438 plugin-container[1915:13227] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 15:03:16 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:03:16 INFO - PROCESS | 1912 | 2017-04-07 15:03:16.439 plugin-container[1915:13227] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4f, name = 'com.apple.CFPasteboardClient' 15:03:16 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:03:16 INFO - PROCESS | 1912 | 2017-04-07 15:03:16.439 plugin-container[1915:13227] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:03:16 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 15:03:16 INFO - PROCESS | 1912 | colorDepth:24 15:03:16 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 15:03:16 INFO - PROCESS | 1912 | __metrics 15:03:16 ERROR - PROCESS | 1912 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 15:03:17 INFO - TEST-INFO | 1912: exit 0 15:03:17 INFO - Browser initialized. 15:03:17 INFO - Running cycle 1/1 for dromaeo_css test... 15:03:17 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmp4rM5fa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:03:19 INFO - PROCESS | 1916 | 2017-04-07 15:03:19.590 plugin-container[1917:13352] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.tsm.portname' 15:03:19 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:03:19 INFO - PROCESS | 1916 | 2017-04-07 15:03:19.590 plugin-container[1917:13352] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x861f, name = 'com.apple.CFPasteboardClient' 15:03:19 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:03:19 INFO - PROCESS | 1916 | 2017-04-07 15:03:19.590 plugin-container[1917:13352] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:03:29 INFO - PROCESS | 1916 | RSS: Main: 173031424 15:03:29 INFO - PROCESS | 1916 | 15:06:30 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51528/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51528/tests/dromaeo/cssquery-ext.html) 15:06:32 INFO - PROCESS | 1916 | RSS: Main: 192745472 15:06:32 INFO - PROCESS | 1916 | 15:09:34 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51528/tests/dromaeo/cssquery-ext.html (next: http://localhost:51528/tests/dromaeo/cssquery-jquery.html) 15:09:35 INFO - PROCESS | 1916 | RSS: Main: 204173312 15:09:35 INFO - PROCESS | 1916 | 15:12:37 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51528/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51528/tests/dromaeo/cssquery-mootools.html) 15:12:38 INFO - PROCESS | 1916 | RSS: Main: 205361152 15:12:38 INFO - PROCESS | 1916 | 15:15:39 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51528/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51528/tests/dromaeo/cssquery-prototype.html) 15:15:40 INFO - PROCESS | 1916 | RSS: Main: 209145856 15:15:40 INFO - PROCESS | 1916 | 15:18:43 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51528/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51528/tests/dromaeo/cssquery-yui.html) 15:18:43 INFO - PROCESS | 1916 | RSS: Main: 210837504 15:18:43 INFO - PROCESS | 1916 | 15:21:46 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51528/tests/dromaeo/cssquery-yui.html (next: http://localhost:51528/tests/dromaeo/cssquery-dojo.html) 15:21:47 INFO - PROCESS | 1916 | RSS: Main: 210997248 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - PROCESS | 1916 | __start_tp_report 15:21:47 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 15:21:47 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 15:21:47 INFO - PROCESS | 1916 | |i|pagename|runs| 15:21:47 INFO - PROCESS | 1916 | |0;dojo.html;3491.3890069237877;3507.649235076492;3550.769200001998;3553.1472446612806;3557.1462848916267;5889.764409423623;5903.907777061241;5966.492848107905;5983.700814959257;6019.939800601993;5914.88170236595;5953.970230148854;6000.819975400745;6004.969975150118;6018.729157187926;5271.525562699366;5329.680219186842;5344.305240318753;5373.919391209135;5412.107002344608;5383.273258110164;5399.946000540003;5403.7027963462;5408.91886621701;5419.864503387427;5257.080010998079;5259.921101183466;5409.729513524328;5419.105847535172;5455.481729235736;5266.683998960056;5315.574754019669;5327.094393953037;5361.490658387447;5405.729713514309;5218.60860435465;5307.681539107666;5319.840404787863;5335.813246536352;5341.4925582069645;5719.799807006735;5846.7076646167525;5879.224387746517;6045;6061.818145455643;6199.132121502993;6207.844803879894;6219.2847822500535;6275.560710750249;6373.26707428647;6422.935770642281;6425.8393540161405;6430.1319321891415;6431.196100487439;6446.677666116722;6162.722677479479;6176.258848938066;6182.907256391113;6197.1324014637985;6200.472959798468;6326.715297811609;6343.682815859189;6398.392152745481;6408.166938297992;6440.645764482999;6188.040853667689;6237.5321850860455;6247.312795592481;6248.937510624926;6255.6872156392;6392.073149393312;6392.648404337806;6397.10440538325;6397.136386587844;6398.744050238031;6172.845678858064;6179.814605561751;6181.258249010148;6197.442230199304;6221.96889015552;6234.251889773256;6256.562040657111;6278.277998030194;6279.246490421087;6293.370662933761;4631.791569379318;5155.716435596003;5165.509276618715;5172.405173405032;5179.974100129475;4428.867133985986;4516.751578663205;4558.612517936012;4616.584507394351;4618.214903466352;6102.084687296852;6165.013597824416;6168.814935551941;6172.444479996821;6194.938050619436;6464.547481676237;6466.353364663496;6470.870582588322;6486;6490.415862572391;4790.592799612072;5977.820665380045;6051.425114614105;6066.302375226817;6088.726007329592;5523.613347065747;5643.633163844337;5648.971755141198;5649.548036157097;5693.800716974886;5617.466340697709;6533.18335208099;6594.175728033996;6600.141981542369;6601.636909969998;6236.220472440945;6240.625562466266;6244.094606282155;6315.5263355247625;6377.904331435125;6630.1049358336;6632.270450250565;6636.933630663631;6646.867062658623;6690.19717633887;6304.873902521832;6332.683365831598;6334.081558173947;6336.271328797247;6344.080108384166;5285.550728188147;6117.480014198843;6302.810915672537;6338.207724034496;6385.904211436925;6206.224221972254;6224.595401298901;6256.249250089836;6275.466585340298;6280.685965701788;6283.465905398092;6294.15028971083;6298.149749783904;6302.873942521215;6311.211098612674;5312.840614781563;6104;6140.749659041634;6308.558400911892;6310.936890631035;6218.875622487615;6224.253089629274;6224.751009959551;6245.7189426476825;6331.27190373113;6270.592411493239;6279.497640188865;6290.2766181889665;6295.685215739286;6326.588771729823 15:21:47 INFO - PROCESS | 1916 | |1;ext.html;524.355043296745;529.4335061484213;535.9629117657333;537.2237117365407;538.1362912525394;32050.519242211387;43768.781156094214;43868.90327741804;43920.78039609805;44002.779986100104;44911.550884491146;45573.77213113938;45590.772046139806;45628;45762.771186144106;9592.808143837101;9858;9863.30956833022;9865.704028879145;11551.075913926908;11531.250468719507;11795.823062654066;11805.940970295136;11853.288802671825;11887.940560297186;11751.353675547842;11818.704532386673;11851.822222666668;11865.70335741609;11897;11670;11693.064554835593;11702.882971170307;11741.589044383449;11807.822882656767;11603.883961160365;11611.187216894821;11621.6513504595;11640.825387619192;11649.70875728105;37975;38071.619283807086;38189.42715859264;38594.80702596497;38980.02549936246;4044.4944381952255;4072.5112986441522;4089.488813898263;4149.937750933738;4324.978375108136;29143.271418214503;29638;29680.257993550335;30064.54903176454;31197;3321.485169798685;3330.550375699274;3360.092774950774;3374.9325013499592;3398.8640454381793;393074;407925.96037019626;471155.2884471146;471314.6434267841;479119;48799.26801097986;50915.49084509107;58009;60327;60587;63149.05276420857;63486;63856.36143638504;63983.36016639867;64131;16412.09733464647;16558.503244902673;16559.586010349598;16844.24200910962;16939.983600983982;11058.336499809877;11132.165087618461;12865.2924089176;13115.081944264;13188.802167967488;19163.808361916483;19172.041397930327;19173.137208825636;19174.712379314322;19237.807621923883;7913.683452661829;8059.556724380215;8068.959655201804;8120.700687889909;8139.430239883151;870.5560164316245;873.5413907698382;882.2589025218847;911.9316051296179;912.543728135932;31325.866240925894;31926.042218733473;31966.68033319637;32088.87688930876;32171.839140804146;1058.9947050264852;1108.3017698849674;1126.2510430563748;1130.7173206698326;1164.446887728339;15874.285657145456;16507.926984745955;16533.50399488017;16535.338586456408;16596.6680666386;635731;649383;650196;650663;652128;63586.0462093069;64169.679151604876;64204;64221;64364.034539481945;1421.6588018875602;1442.604796487928;1466.875315598186;1492.5298530962712;1496.3864815425623;29995.850020749756;31149.532757008208;31205.90779322713;31392.679854413756;32210.35579288448;55120.72439637776;55347;55506.72246638741;55529.44470555243;55849.162262566904;556884;558202;562974;563212;563546.3645363494;24862.875685621457;25518.97924083016;27471.17586472409;27731.168064958085;28334.71665283321;49679.25481117714;49981.50018499768;50107.24839127489;50108.997820044126;50355;28883.133505994854;31222.375552489277;33101.33797324088;33725;33902.491462628575;33678.98963031113;33834.323313534085;34526;34757.30485390227;34970.65029349674;31434.84282578664;36859;39510;39904.60095399009;40110.39834402428;31519.84240078785;31804;32479.512807307437;32556.02331930046;33287.83356083204;25598.8720056406;25645.871770641028;25649.871750641127;25871.870640646677;26205.73794262033 15:21:47 INFO - PROCESS | 1916 | |2;jquery.html;371.1852483798063;396.5083296712076;397.3086828917684;404.3934098851722;426.6075210806058;14300.427982880672;20263.797362026377;20952.16191352348;21490.89254553727;21693.34919952402;21254;21438.678419823715;21564.89217553914;21605.67591486129;21697.783022169773;5866.354700982888;6163.753449862;6174.10475481055;6176.8455788605415;6190.876182476348;5826.15520749491;5963.4334738199805;6026.035834266519;6091.756329746805;6108.664023478706;5862.912056319137;6018.909716354258;6022.337542870281;6023.819285421422;6042.697865106749;5034.848954531388;5786.797462088828;5937.109433584954;5957.076653118774;6068.787592434243;4530.207213737583;5299.814506492293;5311.415744268127;5314.043472175007;5349.331333583302;26055.218343449727;26178.73821261782;26254.47491050168;26287.868560657265;26413.867930660224;2334.8132149428006;2499.4253620219147;2516.874156292178;2653.7080921098664;2704.959425608598;11947.940260298643;14012;15490.148041857694;16022.51932442017;16143.596410089724;1831.377331707226;1875.2967637136073;1911.827935485813;1918.9232430702755;1946.143696773415;107359;130648.34675826655;135873.96189057172;136509;138007;9739.415635061778;11437.37094459796;11705.531778728926;11908.98773604236;11965.700857478458;13054.412551435018;13674.521391751241;14697.55907322782;14730;14737.557873263819;7416.592087435243;8668.046514883357;8691.043985161627;9040.728778136536;9199.218066464426;9011.864822027675;9050.909490905138;9069.818603628022;9208.26333893287;9211.723648290563;6015.488683461867;7166.8208294792;7220.0613920190035;7228.807246804324;7246;1975.7233987241798;1991.960160796805;1993.1031036033553;1996.9500762480764;2002.589469158819;930.7905721212809;1002.8395456726888;1066.424130969268;1099.9285046472114;1115.9175599668308;21795.564088718453;22534.774652253596;22808.087676493087;23040.53918921612;23162.1893233736;1098.9120870330355;1110.4392281897751;1117.458032854065;1142.257532603814;1143.4397145398696;10358.896411035945;11424.314541127558;11805.822912656316;12061.035117190604;12095;788011;788643;794118;796810;799231;121638;137325;138622.30688846693;139066.30466847602;141257;1059.6662051453768;1089.2429761315968;1100.5267734873921;1101.5208384352673;1107.8449017137607;16185.75721364204;17903.910480447514;19459;19642.116104775607;19717.60564788725;52292;56194.15708764453;57723.26025452572;58851.70574147102;60080;65499.672501637186;70158.29841701721;88245.55877220572;89451;90879;7155.570665760071;8343.374246931626;8382.580870956306;8435.83128337442;8688.174623410765;12289.385530723606;12776.297303648016;13119.409626567018;13148.737025259632;13216.339183041002;8797;9544.379615324982;10470.842937356098;11020.338779673246;11246.66260012201;10583.894161058292;10646.8935310649;10668.1998850085;10910.836337455103;10917.890821092005;10134.391936483835;10377.481125943823;11481.770364592494;11630.76738465209;11644.41777911118;7291.41668666516;7466.850662986819;7664.501807382502;7867.567283799446;8707.564621768759;9861.161801246973;11106.666799996014;11337.943310283395;11598;11600.88399115998 15:21:47 INFO - PROCESS | 1916 | |3;mootools.html;1407.3385508810861;1468.1484738851468;1495.3420494982206;1505.6687528743673;1510.1920472547183;7746.883796743054;8009.1990800919875;8255.38084643652;8701.17338852808;8709.21617054465;8162.632681529345;8483.745487635366;8499.192576705218;8628.180322869333;8639.740807775761;2321.442853715113;2360.681308023412;2362.0669835415;2369.230000249917;2373.3947843299934;2229.843910926236;2231.9286742363674;2434.7200071991683;2445.3519817248443;2445.7798798108165;2345.4253707841604;2383.5709572276983;2387.069043073203;2412.49337639096;2415.9879200603973;2335.252719129879;2340.20433052762;2346.7887890089974;2365.3613524348416;2384.082128380577;2326.255598208574;2333.1600623775425;2355.234548771656;2355.316958082154;2357.622780355135;8443.324534037261;8520.74437766868;8610.698625548075;8629.827403451958;8633.266172375355;5203.271541984125;5294.629375943686;5321.840344789663;5339.252504649352;5356.571474282087;3517.66582174693;3574.767640103385;3587.246678197582;3600.1539638185004;3628.981855090734;1212.993935030328;1251.7997120460682;1289.38109707341;1312.127435255554;1325.072449285504;11029.669109926715;11029.889701102966;11036.227464077518;11065.834012489819;11085.72285692856;4688.9531104689195;4774.618030557547;4805.207140821725;4833.429135530966;4878.365812444359;7750.534967901944;8287.62705678246;8858.822823543493;8872.24585910192;8958.507282099547;5285.392179899284;5304.734763261821;5305.071612467803;5314.176302673091;5314.256004159421;4403.691741578123;4507.233770259064;4919.2129259318335;4962.454130045692;4997.050560393586;3743.7753734776;3818.809059547067;3861.768293902375;3872.341701910682;3887.2225554889137;1186.2763714134371;1198.1672737447564;1200.4717924113363;1202.6632542888005;1203.464458316041;923.8752768376318;927.6125608838597;933.7012156110115;939.8026414452904;971.4754032822339;5495.313085864267;5634.1830434586755;5716.388738306393;5856.677882716492;5881.5882888198275;522.7386306846577;564.4383838081136;565.406323360478;576.5013263527019;588.1677426441537;3781.9810900945317;3861.9034524137123;3903.843846246118;3912.1441347816253;3931.5478719947;9638.277129215337;10097.646582369582;10162.83127440339;10182;10221;8205.466644668077;9730.12428881404;10009.449480278508;10146.695599132037;10188.745281367877;7942;8083.393745469113;8090.312323452455;8096.271335579709;8116;4781;5297.9139276449005;5496.835094947158;5632.690202038927;5820.76716931318;9239.445633262027;9282.489463079595;9317.953410233176;9354.298427618092;9369.718908432758;9091.818163636557;9382.906170938204;9442.622295108118;9588.041195880356;9622.422654640744;6772.762953296611;6967.616781077089;7342.8898566520475;7343.5961022142155;7475.4019678426685;8102.675892964216;8115.7159499419595;8165.714200003209;8178.427510074238;8181.672733090848;6973.965130174317;7080.716771329296;7145.64271786419;7166.462515311477;7231.2768723127265;6934.653267336512;6997.335253150943;7012.018317435665;7090.184628767758;7124.857502850017;5835.095560188178;5867.471927526543;5875.6474611523445;5912.320083190488;5956.791512297049;5019.322391477104;5055.949440505695;5081.949180508148;5121.513456221653;5125.538701516882;5068.695878247317;5077.136886729191;5094.083065048327;5136.280920671034;5286.048511267855 15:21:47 INFO - PROCESS | 1916 | |4;prototype.html;320.93420848726015;358.42472831106073;368.12202896092816;374.3673192305005;378.95073640426756;4143.523494798099;5385.35375754909;5536.224928510007;5538.335399752035;5551.694656793879;4178.728382655126;4822.7687449408895;5311.840644780653;5348.866278343044;5499.102809530706;3397.9490307645283;3990.840366385341;4228.344606585984;4243.681723870706;4285.657147428214;3058.9541156882665;3565.983694647026;4000.5597984725623;4259.041715613977;4285.635720963722;3876.8175706409675;3962.187751510933;4064.959350406488;4072.2669919414493;4098.8565400211;3534.5228394166843;4067.5118985721606;4067.9389809152885;4088.9795551022203;4107.8973025674295;2869.4404591104535;3291.7780498163643;3554.7156227501996;3601.477785721082;3613.8012409317475;4986.925196122061;5250.6324557281005;5311.893762124774;5344.706041167734;5359.4189714034455;3734.607866174067;4036.636702696742;4123.463949686521;4257.127288905767;4708.670393072452;5116.05826649678;5234.272690012325;5796.420357964212;5820.941790582082;5831.300243970751;3427.9828600856836;3495.6017592963017;3793.785988483659;3801.6388443097862;3939.231849789292;5471.23402723619;5998.97000514999;6008.279006519202;6030.577859549833;6047.274327080734;3808.200277941608;4002.8598999034893;4019.738716983387;4032.3951407288555;4043.1711499142607;3923.7841918694744;4050.3924411338535;4074.9388759168637;4092.5498195198506;4110.938335924963;4053.756774593534;4328.7402755834755;4655.95344046562;4701.717896926195;4731.219348807408;5041.395032596112;5088.872778180576;5765.221695071195;5789.899919015374;5796.594238403271;1048.727330893973;1281.1032277405666;1298.8311519502404;1307.1438207973792;1310.0043966585463;742.3726950726627;803.369355056278;853.5817449449725;881.969131080409;886.9068747781519;4808.422989241313;4851.830185943475;5155.664881782747;5184.507471790174;5217.582483473331;4757.072370887644;5376.8118115865755;5470.288862447856;5728.283964504437;5769.538436925036;4911.803527858846;5142.665726727751;5155.74221288934;5183.0563538673;5208.8437346879655;2535.4802265535523;2640.881160347789;2665.60015997598;2934.9559756603667;3072;5037.596992240612;5326.547243484347;5912.368995796829;5960.344362120163;5990.490808281258;5008.524190201925;5364.7049412282695;5817.854553636108;5841.591088623841;5844.473997340175;5214.634975551675;5310.415854255952;5738.073582472969;5893.966581529366;5904.586678932434;4934.432540257916;5445.727713614224;5559.63862348946;5715.657060576379;5737.684427356536;5546.500814926676;5565.944340556543;5618.073017951992;5633.352164501135;5640.717964101696;5018.44797072329;5023.764424830915;5526.955026640008;5835.7373918174635;5895.14520394549;3369.275605744777;3521;3829.7170447900266;3843.1184503650957;3854.3640299350295;4672.602828759457;5128.461511541234;5297.788088476418;5955.434233747787;5988.640681559121;4593.402857628487;4922.384701912261;4993.500649934977;5244.973775131252;5801.3038435388025;4643.535646435329;5230.63385563007;5833.883322333445;5850;5864.179014937998;4719.787609557647;5355.892882142258;5701.629394089371;5742.741576628978;5781.219535362672;5057.570106540985;5302.125149350468;5665.291838520185;5863.442972917566;5908.5568582357355;5258.710770907637;5731.19763233156;5737.196792449144;5744.856378590569;5756.827295181151 15:21:47 INFO - PROCESS | 1916 | |5;yui.html;734.79425760787;749.7713197474772;787.5747096567853;877.4647465046321;883.1212943121593;15012.77480837787;16089.839101608995;16096.356145754156;16102.677946441094;16109.275082621281;14668.559943201695;14827.777583336232;14899.851001489982;15049.849501504981;15069.849301506982;695.5409429776348;787.8700014497601;832.7585000349922;863.3999370437548;875.8554838451648;721.293132729925;854.9016863060765;872.8603423452249;882.1355072029415;885.7608445719652;904.9276057915351;938.5307346326837;939.1829108675462;941.948192849393;942.5334459442586;898.3980732908967;931.8881734191906;935.550935550936;937.1003836317144;938.0057139432156;673.474689742002;676.0298970976615;691.5401258163315;700.5061431690697;702.0066605752853;13660.590182294547;14929.328180231807;15151.469698560497;15567.065976041475;16256;10610.257281990187;13039.478420863154;13152.21086734799;13227.801582976263;13527.32363381827;11738.75677474798;11865.406729663482;11951.641450756491;11985.161038727292;11988.280703157838;3832.291026160169;3879.12719638082;3905.2384784966944;4028.9597104028876;4066.369712694537;91902.54048729714;104361;105433.94566054318;108019.45990269998;109922.45038774835;11422.200445968703;11611.245269057485;11618.88381116195;11843.526258949545;12001.1599188056;24076.965398284054;24585.631215531783;24867.767190265513;24877.62683559748;24883.5023299533;8189.959050204711;8210.055843577946;8226.383021273428;8268.8346233075;8367.790805229795;4406.294992801137;4427.800748966304;4516.23224051912;4601.447826260804;4621.376114224604;3751.362268414376;3813.2945405100145;3825.8087095645105;3843.9039024024064;3852.8073596320633;555.927729395176;675.9729610815513;690.520088538471;700.9369156775915;716.9068021157216;616.9413905678954;667.4126768443739;697.2852825853439;699.6328659218437;735.7939776862487;10509.737256568647;10616.734581635521;10778.78442431147;11021.504032318566;11115.277506962022;680.7474247384737;691.0601581848682;694.5138403117737;739.4749727693398;748.2704363245792;5112.401919897706;5115.616328775357;5179.663321884141;5184.170532714747;5199.246109314128;80953.59523202464;82829.51511454667;90578;100730;104650;90704.5464772672;91953.08046919579;93718.53140734253;96072;96532.51733741286;77171.61414193005;77234;78597.82103268341;79968.60015700001;80671;9971;9992.800143996934;10018.05684414236;10120.190384769054;10155;50571;50983.745081274355;51035.74482127714;51741;52834.943301133;62387.37612623997;62642;62734;62766;65173.02240466492;12054.337011464453;12179.269243845398;12249.265044097026;12775.488980440678;12926.806097908351;13217.537386191438;13224.735505289647;13263.668408289484;13291.142394418837;13590.728185436039;12257.100149476846;12395.876041239471;12454.688632784253;12580.30808305552;12972.286524241257;12788.680282993;12825.551105711254;12861.356932153169;13868.544368135113;14423.567292981228;13598.320083995959;13677.31613419345;14279.357428915931;14495.637609059859;14637.853621464075;9467.147956683932;9903.50482475859;10114.51525870865;10230.209713300292;10421.739456513647;11073.390963497084;11167.441627918734;11451.713707157387;11480.909313615193;11502.309861408343 15:21:47 INFO - PROCESS | 1916 | __end_tp_report 15:21:47 INFO - PROCESS | 1916 | __start_cc_report 15:21:47 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,380 15:21:47 INFO - PROCESS | 1916 | __end_cc_report 15:21:47 INFO - PROCESS | 1916 | __startTimestamp1491603707221__endTimestamp 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - PROCESS | 1916 | ------- Summary: start ------- 15:21:47 INFO - PROCESS | 1916 | Number of tests: 6 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:5909.06 Median:6194.94 stddev:653.31 (10.5%) stddev-sans-first:627.18 15:21:47 INFO - PROCESS | 1916 | Values: 3491.4 3507.6 3550.8 3553.1 3557.1 5889.8 5903.9 5966.5 5983.7 6019.9 5914.9 5954.0 6000.8 6005.0 6018.7 5271.5 5329.7 5344.3 5373.9 5412.1 5383.3 5399.9 5403.7 5408.9 5419.9 5257.1 5259.9 5409.7 5419.1 5455.5 5266.7 5315.6 5327.1 5361.5 5405.7 5218.6 5307.7 5319.8 5335.8 5341.5 5719.8 5846.7 5879.2 6045.0 6061.8 6199.1 6207.8 6219.3 6275.6 6373.3 6422.9 6425.8 6430.1 6431.2 6446.7 6162.7 6176.3 6182.9 6197.1 6200.5 6326.7 6343.7 6398.4 6408.2 6440.6 6188.0 6237.5 6247.3 6248.9 6255.7 6392.1 6392.6 6397.1 6397.1 6398.7 6172.8 6179.8 6181.3 6197.4 6222.0 6234.3 6256.6 6278.3 6279.2 6293.4 4631.8 5155.7 5165.5 5172.4 5180.0 4428.9 4516.8 4558.6 4616.6 4618.2 6102.1 6165.0 6168.8 6172.4 6194.9 6464.5 6466.4 6470.9 6486.0 6490.4 4790.6 5977.8 6051.4 6066.3 6088.7 5523.6 5643.6 5649.0 5649.5 5693.8 5617.5 6533.2 6594.2 6600.1 6601.6 6236.2 6240.6 6244.1 6315.5 6377.9 6630.1 6632.3 6636.9 6646.9 6690.2 6304.9 6332.7 6334.1 6336.3 6344.1 5285.6 6117.5 6302.8 6338.2 6385.9 6206.2 6224.6 6256.2 6275.5 6280.7 6283.5 6294.2 6298.1 6302.9 6311.2 5312.8 6104.0 6140.7 6308.6 6310.9 6218.9 6224.3 6224.8 6245.7 6331.3 6270.6 6279.5 6290.3 6295.7 6326.6 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:69188.09 Median:18549.97 stddev:148894.51 (802.7%) stddev-sans-first:149222.95 15:21:47 INFO - PROCESS | 1916 | Values: 524.4 529.4 536.0 537.2 538.1 32050.5 43768.8 43868.9 43920.8 44002.8 44911.6 45573.8 45590.8 45628.0 45762.8 9592.8 9858.0 9863.3 9865.7 11551.1 11531.3 11795.8 11805.9 11853.3 11887.9 11751.4 11818.7 11851.8 11865.7 11897.0 11670.0 11693.1 11702.9 11741.6 11807.8 11603.9 11611.2 11621.7 11640.8 11649.7 37975.0 38071.6 38189.4 38594.8 38980.0 4044.5 4072.5 4089.5 4149.9 4325.0 29143.3 29638.0 29680.3 30064.5 31197.0 3321.5 3330.6 3360.1 3374.9 3398.9 393074.0 407926.0 471155.3 471314.6 479119.0 48799.3 50915.5 58009.0 60327.0 60587.0 63149.1 63486.0 63856.4 63983.4 64131.0 16412.1 16558.5 16559.6 16844.2 16940.0 11058.3 11132.2 12865.3 13115.1 13188.8 19163.8 19172.0 19173.1 19174.7 19237.8 7913.7 8059.6 8069.0 8120.7 8139.4 870.6 873.5 882.3 911.9 912.5 31325.9 31926.0 31966.7 32088.9 32171.8 1059.0 1108.3 1126.3 1130.7 1164.4 15874.3 16507.9 16533.5 16535.3 16596.7 635731.0 649383.0 650196.0 650663.0 652128.0 63586.0 64169.7 64204.0 64221.0 64364.0 1421.7 1442.6 1466.9 1492.5 1496.4 29995.9 31149.5 31205.9 31392.7 32210.4 55120.7 55347.0 55506.7 55529.4 55849.2 556884.0 558202.0 562974.0 563212.0 563546.4 24862.9 25519.0 27471.2 27731.2 28334.7 49679.3 49981.5 50107.2 50109.0 50355.0 28883.1 31222.4 33101.3 33725.0 33902.5 33679.0 33834.3 34526.0 34757.3 34970.7 31434.8 36859.0 39510.0 39904.6 40110.4 31519.8 31804.0 32479.5 32556.0 33287.8 25598.9 25645.9 25649.9 25871.9 26205.7 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:41468.73 Median:22165.17 stddev:131289.00 (592.3%) stddev-sans-first:131621.03 15:21:47 INFO - PROCESS | 1916 | Values: 371.2 396.5 397.3 404.4 426.6 14300.4 20263.8 20952.2 21490.9 21693.3 21254.0 21438.7 21564.9 21605.7 21697.8 5866.4 6163.8 6174.1 6176.8 6190.9 5826.2 5963.4 6026.0 6091.8 6108.7 5862.9 6018.9 6022.3 6023.8 6042.7 5034.8 5786.8 5937.1 5957.1 6068.8 4530.2 5299.8 5311.4 5314.0 5349.3 26055.2 26178.7 26254.5 26287.9 26413.9 2334.8 2499.4 2516.9 2653.7 2705.0 11947.9 14012.0 15490.1 16022.5 16143.6 1831.4 1875.3 1911.8 1918.9 1946.1 107359.0 130648.3 135874.0 136509.0 138007.0 9739.4 11437.4 11705.5 11909.0 11965.7 13054.4 13674.5 14697.6 14730.0 14737.6 7416.6 8668.0 8691.0 9040.7 9199.2 9011.9 9050.9 9069.8 9208.3 9211.7 6015.5 7166.8 7220.1 7228.8 7246.0 1975.7 1992.0 1993.1 1997.0 2002.6 930.8 1002.8 1066.4 1099.9 1115.9 21795.6 22534.8 22808.1 23040.5 23162.2 1098.9 1110.4 1117.5 1142.3 1143.4 10358.9 11424.3 11805.8 12061.0 12095.0 788011.0 788643.0 794118.0 796810.0 799231.0 121638.0 137325.0 138622.3 139066.3 141257.0 1059.7 1089.2 1100.5 1101.5 1107.8 16185.8 17903.9 19459.0 19642.1 19717.6 52292.0 56194.2 57723.3 58851.7 60080.0 65499.7 70158.3 88245.6 89451.0 90879.0 7155.6 8343.4 8382.6 8435.8 8688.2 12289.4 12776.3 13119.4 13148.7 13216.3 8797.0 9544.4 10470.8 11020.3 11246.7 10583.9 10646.9 10668.2 10910.8 10917.9 10134.4 10377.5 11481.8 11630.8 11644.4 7291.4 7466.9 7664.5 7867.6 8707.6 9861.2 11106.7 11337.9 11598.0 11600.9 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:5468.75 Median:5290.34 stddev:2964.15 (56.0%) stddev-sans-first:2956.75 15:21:47 INFO - PROCESS | 1916 | Values: 1407.3 1468.1 1495.3 1505.7 1510.2 7746.9 8009.2 8255.4 8701.2 8709.2 8162.6 8483.7 8499.2 8628.2 8639.7 2321.4 2360.7 2362.1 2369.2 2373.4 2229.8 2231.9 2434.7 2445.4 2445.8 2345.4 2383.6 2387.1 2412.5 2416.0 2335.3 2340.2 2346.8 2365.4 2384.1 2326.3 2333.2 2355.2 2355.3 2357.6 8443.3 8520.7 8610.7 8629.8 8633.3 5203.3 5294.6 5321.8 5339.3 5356.6 3517.7 3574.8 3587.2 3600.2 3629.0 1213.0 1251.8 1289.4 1312.1 1325.1 11029.7 11029.9 11036.2 11065.8 11085.7 4689.0 4774.6 4805.2 4833.4 4878.4 7750.5 8287.6 8858.8 8872.2 8958.5 5285.4 5304.7 5305.1 5314.2 5314.3 4403.7 4507.2 4919.2 4962.5 4997.1 3743.8 3818.8 3861.8 3872.3 3887.2 1186.3 1198.2 1200.5 1202.7 1203.5 923.9 927.6 933.7 939.8 971.5 5495.3 5634.2 5716.4 5856.7 5881.6 522.7 564.4 565.4 576.5 588.2 3782.0 3861.9 3903.8 3912.1 3931.5 9638.3 10097.6 10162.8 10182.0 10221.0 8205.5 9730.1 10009.4 10146.7 10188.7 7942.0 8083.4 8090.3 8096.3 8116.0 4781.0 5297.9 5496.8 5632.7 5820.8 9239.4 9282.5 9318.0 9354.3 9369.7 9091.8 9382.9 9442.6 9588.0 9622.4 6772.8 6967.6 7342.9 7343.6 7475.4 8102.7 8115.7 8165.7 8178.4 8181.7 6974.0 7080.7 7145.6 7166.5 7231.3 6934.7 6997.3 7012.0 7090.2 7124.9 5835.1 5867.5 5875.6 5912.3 5956.8 5019.3 5055.9 5081.9 5121.5 5125.5 5068.7 5077.1 5094.1 5136.3 5286.0 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4517.56 Median:5073.22 stddev:1409.31 (27.8%) stddev-sans-first:1377.61 15:21:47 INFO - PROCESS | 1916 | Values: 320.9 358.4 368.1 374.4 379.0 4143.5 5385.4 5536.2 5538.3 5551.7 4178.7 4822.8 5311.8 5348.9 5499.1 3397.9 3990.8 4228.3 4243.7 4285.7 3059.0 3566.0 4000.6 4259.0 4285.6 3876.8 3962.2 4065.0 4072.3 4098.9 3534.5 4067.5 4067.9 4089.0 4107.9 2869.4 3291.8 3554.7 3601.5 3613.8 4986.9 5250.6 5311.9 5344.7 5359.4 3734.6 4036.6 4123.5 4257.1 4708.7 5116.1 5234.3 5796.4 5820.9 5831.3 3428.0 3495.6 3793.8 3801.6 3939.2 5471.2 5999.0 6008.3 6030.6 6047.3 3808.2 4002.9 4019.7 4032.4 4043.2 3923.8 4050.4 4074.9 4092.5 4110.9 4053.8 4328.7 4656.0 4701.7 4731.2 5041.4 5088.9 5765.2 5789.9 5796.6 1048.7 1281.1 1298.8 1307.1 1310.0 742.4 803.4 853.6 882.0 886.9 4808.4 4851.8 5155.7 5184.5 5217.6 4757.1 5376.8 5470.3 5728.3 5769.5 4911.8 5142.7 5155.7 5183.1 5208.8 2535.5 2640.9 2665.6 2935.0 3072.0 5037.6 5326.5 5912.4 5960.3 5990.5 5008.5 5364.7 5817.9 5841.6 5844.5 5214.6 5310.4 5738.1 5894.0 5904.6 4934.4 5445.7 5559.6 5715.7 5737.7 5546.5 5565.9 5618.1 5633.4 5640.7 5018.4 5023.8 5527.0 5835.7 5895.1 3369.3 3521.0 3829.7 3843.1 3854.4 4672.6 5128.5 5297.8 5955.4 5988.6 4593.4 4922.4 4993.5 5245.0 5801.3 4643.5 5230.6 5833.9 5850.0 5864.2 4719.8 5355.9 5701.6 5742.7 5781.2 5057.6 5302.1 5665.3 5863.4 5908.6 5258.7 5731.2 5737.2 5744.9 5756.8 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:20294.64 Median:4047.66 stddev:28663.43 (708.1%) stddev-sans-first:28706.21 15:21:47 INFO - PROCESS | 1916 | Values: 734.8 749.8 787.6 877.5 883.1 15012.8 16089.8 16096.4 16102.7 16109.3 14668.6 14827.8 14899.9 15049.8 15069.8 695.5 787.9 832.8 863.4 875.9 721.3 854.9 872.9 882.1 885.8 904.9 938.5 939.2 941.9 942.5 898.4 931.9 935.6 937.1 938.0 673.5 676.0 691.5 700.5 702.0 13660.6 14929.3 15151.5 15567.1 16256.0 10610.3 13039.5 13152.2 13227.8 13527.3 11738.8 11865.4 11951.6 11985.2 11988.3 3832.3 3879.1 3905.2 4029.0 4066.4 91902.5 104361.0 105433.9 108019.5 109922.5 11422.2 11611.2 11618.9 11843.5 12001.2 24077.0 24585.6 24867.8 24877.6 24883.5 8190.0 8210.1 8226.4 8268.8 8367.8 4406.3 4427.8 4516.2 4601.4 4621.4 3751.4 3813.3 3825.8 3843.9 3852.8 555.9 676.0 690.5 700.9 716.9 616.9 667.4 697.3 699.6 735.8 10509.7 10616.7 10778.8 11021.5 11115.3 680.7 691.1 694.5 739.5 748.3 5112.4 5115.6 5179.7 5184.2 5199.2 80953.6 82829.5 90578.0 100730.0 104650.0 90704.5 91953.1 93718.5 96072.0 96532.5 77171.6 77234.0 78597.8 79968.6 80671.0 9971.0 9992.8 10018.1 10120.2 10155.0 50571.0 50983.7 51035.7 51741.0 52834.9 62387.4 62642.0 62734.0 62766.0 65173.0 12054.3 12179.3 12249.3 12775.5 12926.8 13217.5 13224.7 13263.7 13291.1 13590.7 12257.1 12395.9 12454.7 12580.3 12972.3 12788.7 12825.6 12861.4 13868.5 14423.6 13598.3 13677.3 14279.4 14495.6 14637.9 9467.1 9903.5 10114.5 10230.2 10421.7 11073.4 11167.4 11451.7 11480.9 11502.3 15:21:47 INFO - PROCESS | 1916 | -------- Summary: end -------- 15:21:47 INFO - PROCESS | 1916 | 15:21:47 INFO - TEST-INFO | 1916: exit 0 15:21:47 INFO - TEST-OK | dromaeo_css | took 1114162ms 15:21:47 INFO - TEST-START | kraken 15:21:47 INFO - operating with platform_type : mac_ 15:21:47 INFO - Initialising browser for kraken test... 15:21:47 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpPoqQgL/profile http://localhost:51528/getInfo.html) 15:21:50 INFO - PROCESS | 1923 | 2017-04-07 15:21:50.643 plugin-container[1925:15274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 15:21:50 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:50 INFO - PROCESS | 1923 | 2017-04-07 15:21:50.644 plugin-container[1925:15274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 15:21:50 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:50 INFO - PROCESS | 1923 | 2017-04-07 15:21:50.644 plugin-container[1925:15274] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:21:50 INFO - PROCESS | 1923 | __metrics Screen width/height:1600/1200 15:21:50 INFO - PROCESS | 1923 | colorDepth:24 15:21:50 INFO - PROCESS | 1923 | Browser inner width/height: 1024/689 15:21:50 INFO - PROCESS | 1923 | __metrics 15:21:50 ERROR - PROCESS | 1923 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 15:21:51 INFO - TEST-INFO | 1923: exit 0 15:21:51 INFO - Browser initialized. 15:21:51 INFO - Running cycle 1/1 for kraken test... 15:21:51 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpPoqQgL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:53 INFO - PROCESS | 1926 | 2017-04-07 15:21:53.851 plugin-container[1927:15399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 15:21:53 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:53 INFO - PROCESS | 1926 | 2017-04-07 15:21:53.851 plugin-container[1927:15399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 15:21:53 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:53 INFO - PROCESS | 1926 | 2017-04-07 15:21:53.851 plugin-container[1927:15399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:22:03 INFO - PROCESS | 1926 | RSS: Main: 172867584 15:22:03 INFO - PROCESS | 1926 | 15:22:06 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51528/tests/kraken/driver.html?testName=audio-beat-detection) 15:22:07 INFO - PROCESS | 1926 | RSS: Main: 186007552 15:22:07 INFO - PROCESS | 1926 | 15:22:10 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51528/tests/kraken/driver.html?testName=audio-dft) 15:22:10 INFO - PROCESS | 1926 | RSS: Main: 194883584 15:22:10 INFO - PROCESS | 1926 | 15:22:14 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51528/tests/kraken/driver.html?testName=audio-fft) 15:22:14 INFO - PROCESS | 1926 | RSS: Main: 197873664 15:22:14 INFO - PROCESS | 1926 | 15:22:16 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51528/tests/kraken/driver.html?testName=audio-oscillator) 15:22:17 INFO - PROCESS | 1926 | RSS: Main: 197963776 15:22:17 INFO - PROCESS | 1926 | 15:22:19 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51528/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:22:19 INFO - PROCESS | 1926 | RSS: Main: 198062080 15:22:19 INFO - PROCESS | 1926 | 15:22:23 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51528/tests/kraken/driver.html?testName=imaging-darkroom) 15:22:23 INFO - PROCESS | 1926 | RSS: Main: 203800576 15:22:23 INFO - PROCESS | 1926 | 15:22:26 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51528/tests/kraken/driver.html?testName=imaging-desaturate) 15:22:27 INFO - PROCESS | 1926 | RSS: Main: 202285056 15:22:27 INFO - PROCESS | 1926 | 15:22:30 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51528/tests/kraken/driver.html?testName=json-parse-financial) 15:22:31 INFO - PROCESS | 1926 | RSS: Main: 202379264 15:22:31 INFO - PROCESS | 1926 | 15:22:32 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51528/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:22:33 INFO - PROCESS | 1926 | RSS: Main: 201891840 15:22:33 INFO - PROCESS | 1926 | 15:22:35 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51528/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:22:36 INFO - PROCESS | 1926 | RSS: Main: 201936896 15:22:36 INFO - PROCESS | 1926 | 15:22:38 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51528/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:22:38 INFO - PROCESS | 1926 | RSS: Main: 201875456 15:22:38 INFO - PROCESS | 1926 | 15:22:41 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:22:42 INFO - PROCESS | 1926 | RSS: Main: 202313728 15:22:42 INFO - PROCESS | 1926 | 15:22:45 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:22:46 INFO - PROCESS | 1926 | RSS: Main: 202035200 15:22:46 INFO - PROCESS | 1926 | 15:22:47 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51528/tests/kraken/driver.html?testName=ai-astar) 15:22:48 INFO - PROCESS | 1926 | RSS: Main: 203980800 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | __start_tp_report 15:22:48 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 15:22:48 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 15:22:48 INFO - PROCESS | 1926 | |i|pagename|runs| 15:22:48 INFO - PROCESS | 1926 | |0;ai-astar;103;116;116;126;169;174;168;116;117;115 15:22:48 INFO - PROCESS | 1926 | |1;audio-beat-detection;141;110;129;148;109;107;111;109;129;111 15:22:48 INFO - PROCESS | 1926 | |2;audio-dft;196;195;198;198;199;195;211;198;233;233 15:22:48 INFO - PROCESS | 1926 | |3;audio-fft;85;58;58;86;62;68;70;71;88;51 15:22:48 INFO - PROCESS | 1926 | |4;audio-oscillator;104;102;99;71;69;69;83;99;58;67 15:22:48 INFO - PROCESS | 1926 | |5;imaging-gaussian-blur;93;120;92;131;136;97;92;90;91;133 15:22:48 INFO - PROCESS | 1926 | |6;imaging-darkroom;102;101;130;118;171;174;100;100;102;101 15:22:48 INFO - PROCESS | 1926 | |7;imaging-desaturate;139;91;91;124;83;137;147;115;93;92 15:22:48 INFO - PROCESS | 1926 | |8;json-parse-financial;55;58;58;59;59;61;61;61;61;61 15:22:48 INFO - PROCESS | 1926 | |9;json-stringify-tinderbox;69;72;80;87;108;101;77;70;73;71 15:22:48 INFO - PROCESS | 1926 | |10;stanford-crypto-aes;105;84;83;77;105;104;70;102;71;92 15:22:48 INFO - PROCESS | 1926 | |11;stanford-crypto-ccm;171;126;151;123;153;154;157;132;182;145 15:22:48 INFO - PROCESS | 1926 | |12;stanford-crypto-pbkdf2;167;223;192;166;170;166;168;165;172;167 15:22:48 INFO - PROCESS | 1926 | |13;stanford-crypto-sha256-iterative;68;84;83;66;68;66;65;76;64;70 15:22:48 INFO - PROCESS | 1926 | __end_tp_report 15:22:48 INFO - PROCESS | 1926 | __start_cc_report 15:22:48 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,955 15:22:48 INFO - PROCESS | 1926 | __end_cc_report 15:22:48 INFO - PROCESS | 1926 | __startTimestamp1491603768061__endTimestamp 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | ------- Summary: start ------- 15:22:48 INFO - PROCESS | 1926 | Number of tests: 14 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#0] ai-astar Cycles:10 Average:132.00 Median:121.50 stddev:27.06 (22.3%) stddev-sans-first:26.58 15:22:48 INFO - PROCESS | 1926 | Values: 103.0 116.0 116.0 126.0 169.0 174.0 168.0 116.0 117.0 115.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:120.00 stddev:15.12 (12.6%) stddev-sans-first:14.08 15:22:48 INFO - PROCESS | 1926 | Values: 141.0 110.0 129.0 148.0 109.0 107.0 111.0 109.0 129.0 111.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#2] audio-dft Cycles:10 Average:205.60 Median:198.50 stddev:15.15 (7.6%) stddev-sans-first:15.66 15:22:48 INFO - PROCESS | 1926 | Values: 196.0 195.0 198.0 198.0 199.0 195.0 211.0 198.0 233.0 233.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#3] audio-fft Cycles:10 Average:69.70 Median:70.50 stddev:13.00 (18.4%) stddev-sans-first:12.56 15:22:48 INFO - PROCESS | 1926 | Values: 85.0 58.0 58.0 86.0 62.0 68.0 70.0 71.0 88.0 51.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:70.00 stddev:17.39 (24.8%) stddev-sans-first:16.55 15:22:48 INFO - PROCESS | 1926 | Values: 104.0 102.0 99.0 71.0 69.0 69.0 83.0 99.0 58.0 67.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#5] imaging-gaussian-blur Cycles:10 Average:107.50 Median:91.50 stddev:19.86 (21.7%) stddev-sans-first:20.36 15:22:48 INFO - PROCESS | 1926 | Values: 93.0 120.0 92.0 131.0 136.0 97.0 92.0 90.0 91.0 133.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#6] imaging-darkroom Cycles:10 Average:119.90 Median:110.00 stddev:29.42 (26.7%) stddev-sans-first:30.49 15:22:48 INFO - PROCESS | 1926 | Values: 102.0 101.0 130.0 118.0 171.0 174.0 100.0 100.0 102.0 101.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#7] imaging-desaturate Cycles:10 Average:111.20 Median:87.00 stddev:24.06 (27.7%) stddev-sans-first:23.32 15:22:48 INFO - PROCESS | 1926 | Values: 139.0 91.0 91.0 124.0 83.0 137.0 147.0 115.0 93.0 92.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:61.00 stddev:2.01 (3.3%) stddev-sans-first:1.36 15:22:48 INFO - PROCESS | 1926 | Values: 55.0 58.0 58.0 59.0 59.0 61.0 61.0 61.0 61.0 61.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#9] json-stringify-tinderbox Cycles:10 Average:80.80 Median:72.50 stddev:13.71 (18.9%) stddev-sans-first:13.86 15:22:48 INFO - PROCESS | 1926 | Values: 69.0 72.0 80.0 87.0 108.0 101.0 77.0 70.0 73.0 71.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:74.00 stddev:14.16 (19.1%) stddev-sans-first:13.83 15:22:48 INFO - PROCESS | 1926 | Values: 105.0 84.0 83.0 77.0 105.0 104.0 70.0 102.0 71.0 92.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:153.50 stddev:18.83 (12.3%) stddev-sans-first:18.28 15:22:48 INFO - PROCESS | 1926 | Values: 171.0 126.0 151.0 123.0 153.0 154.0 157.0 132.0 182.0 145.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:169.00 stddev:18.45 (10.9%) stddev-sans-first:19.30 15:22:48 INFO - PROCESS | 1926 | Values: 167.0 223.0 192.0 166.0 170.0 166.0 168.0 165.0 172.0 167.0 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - PROCESS | 1926 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:69.00 stddev:7.39 (10.7%) stddev-sans-first:7.76 15:22:48 INFO - PROCESS | 1926 | Values: 68.0 84.0 83.0 66.0 68.0 66.0 65.0 76.0 64.0 70.0 15:22:48 INFO - PROCESS | 1926 | -------- Summary: end -------- 15:22:48 INFO - PROCESS | 1926 | 15:22:48 INFO - TEST-INFO | 1926: exit 0 15:22:48 INFO - TEST-OK | kraken | took 61032ms 15:22:48 INFO - SUITE-END | took 1175s 15:22:49 INFO - Completed test suite (00:19:35) 15:22:49 INFO - javascript benchmark 15:22:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7431.567419008405, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3491.3890069237877, 3507.649235076492, 3550.769200001998, 3553.1472446612806, 3557.1462848916267, 5889.764409423623, 5903.907777061241, 5966.492848107905, 5983.700814959257, 6019.939800601993, 5914.88170236595, 5953.970230148854, 6000.819975400745, 6004.969975150118, 6018.729157187926, 5271.525562699366, 5329.680219186842, 5344.305240318753, 5373.919391209135, 5412.107002344608, 5383.273258110164, 5399.946000540003, 5403.7027963462, 5408.91886621701, 5419.864503387427, 5257.080010998079, 5259.921101183466, 5409.729513524328, 5419.105847535172, 5455.481729235736, 5266.683998960056, 5315.574754019669, 5327.094393953037, 5361.490658387447, 5405.729713514309, 5218.60860435465, 5307.681539107666, 5319.840404787863, 5335.813246536352, 5341.4925582069645, 5719.799807006735, 5846.7076646167525, 5879.224387746517, 6045.0, 6061.818145455643, 6199.132121502993, 6207.844803879894, 6219.2847822500535, 6275.560710750249, 6373.26707428647, 6422.935770642281, 6425.8393540161405, 6430.1319321891415, 6431.196100487439, 6446.677666116722, 6162.722677479479, 6176.258848938066, 6182.907256391113, 6197.1324014637985, 6200.472959798468, 6326.715297811609, 6343.682815859189, 6398.392152745481, 6408.166938297992, 6440.645764482999, 6188.040853667689, 6237.5321850860455, 6247.312795592481, 6248.937510624926, 6255.6872156392, 6392.073149393312, 6392.648404337806, 6397.10440538325, 6397.136386587844, 6398.744050238031, 6172.845678858064, 6179.814605561751, 6181.258249010148, 6197.442230199304, 6221.96889015552, 6234.251889773256, 6256.562040657111, 6278.277998030194, 6279.246490421087, 6293.370662933761, 4631.791569379318, 5155.716435596003, 5165.509276618715, 5172.405173405032, 5179.974100129475, 4428.867133985986, 4516.751578663205, 4558.612517936012, 4616.584507394351, 4618.214903466352, 6102.084687296852, 6165.013597824416, 6168.814935551941, 6172.444479996821, 6194.938050619436, 6464.547481676237, 6466.353364663496, 6470.870582588322, 6486.0, 6490.415862572391, 4790.592799612072, 5977.820665380045, 6051.425114614105, 6066.302375226817, 6088.726007329592, 5523.613347065747, 5643.633163844337, 5648.971755141198, 5649.548036157097, 5693.800716974886, 5617.466340697709, 6533.18335208099, 6594.175728033996, 6600.141981542369, 6601.636909969998, 6236.220472440945, 6240.625562466266, 6244.094606282155, 6315.5263355247625, 6377.904331435125, 6630.1049358336, 6632.270450250565, 6636.933630663631, 6646.867062658623, 6690.19717633887, 6304.873902521832, 6332.683365831598, 6334.081558173947, 6336.271328797247, 6344.080108384166, 5285.550728188147, 6117.480014198843, 6302.810915672537, 6338.207724034496, 6385.904211436925, 6206.224221972254, 6224.595401298901, 6256.249250089836, 6275.466585340298, 6280.685965701788, 6283.465905398092, 6294.15028971083, 6298.149749783904, 6302.873942521215, 6311.211098612674, 5312.840614781563, 6104.0, 6140.749659041634, 6308.558400911892, 6310.936890631035, 6218.875622487615, 6224.253089629274, 6224.751009959551, 6245.7189426476825, 6331.27190373113, 6270.592411493239, 6279.497640188865, 6290.2766181889665, 6295.685215739286, 6326.588771729823], "value": 5868.829560902324, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.355043296745, 529.4335061484213, 535.9629117657333, 537.2237117365407, 538.1362912525394, 32050.519242211387, 43768.781156094214, 43868.90327741804, 43920.78039609805, 44002.779986100104, 44911.550884491146, 45573.77213113938, 45590.772046139806, 45628.0, 45762.771186144106, 9592.808143837101, 9858.0, 9863.30956833022, 9865.704028879145, 11551.075913926908, 11531.250468719507, 11795.823062654066, 11805.940970295136, 11853.288802671825, 11887.940560297186, 11751.353675547842, 11818.704532386673, 11851.822222666668, 11865.70335741609, 11897.0, 11670.0, 11693.064554835593, 11702.882971170307, 11741.589044383449, 11807.822882656767, 11603.883961160365, 11611.187216894821, 11621.6513504595, 11640.825387619192, 11649.70875728105, 37975.0, 38071.619283807086, 38189.42715859264, 38594.80702596497, 38980.02549936246, 4044.4944381952255, 4072.5112986441522, 4089.488813898263, 4149.937750933738, 4324.978375108136, 29143.271418214503, 29638.0, 29680.257993550335, 30064.54903176454, 31197.0, 3321.485169798685, 3330.550375699274, 3360.092774950774, 3374.9325013499592, 3398.8640454381793, 393074.0, 407925.96037019626, 471155.2884471146, 471314.6434267841, 479119.0, 48799.26801097986, 50915.49084509107, 58009.0, 60327.0, 60587.0, 63149.05276420857, 63486.0, 63856.36143638504, 63983.36016639867, 64131.0, 16412.09733464647, 16558.503244902673, 16559.586010349598, 16844.24200910962, 16939.983600983982, 11058.336499809877, 11132.165087618461, 12865.2924089176, 13115.081944264, 13188.802167967488, 19163.808361916483, 19172.041397930327, 19173.137208825636, 19174.712379314322, 19237.807621923883, 7913.683452661829, 8059.556724380215, 8068.959655201804, 8120.700687889909, 8139.430239883151, 870.5560164316245, 873.5413907698382, 882.2589025218847, 911.9316051296179, 912.543728135932, 31325.866240925894, 31926.042218733473, 31966.68033319637, 32088.87688930876, 32171.839140804146, 1058.9947050264852, 1108.3017698849674, 1126.2510430563748, 1130.7173206698326, 1164.446887728339, 15874.285657145456, 16507.926984745955, 16533.50399488017, 16535.338586456408, 16596.6680666386, 635731.0, 649383.0, 650196.0, 650663.0, 652128.0, 63586.0462093069, 64169.679151604876, 64204.0, 64221.0, 64364.034539481945, 1421.6588018875602, 1442.604796487928, 1466.875315598186, 1492.5298530962712, 1496.3864815425623, 29995.850020749756, 31149.532757008208, 31205.90779322713, 31392.679854413756, 32210.35579288448, 55120.72439637776, 55347.0, 55506.72246638741, 55529.44470555243, 55849.162262566904, 556884.0, 558202.0, 562974.0, 563212.0, 563546.3645363494, 24862.875685621457, 25518.97924083016, 27471.17586472409, 27731.168064958085, 28334.71665283321, 49679.25481117714, 49981.50018499768, 50107.24839127489, 50108.997820044126, 50355.0, 28883.133505994854, 31222.375552489277, 33101.33797324088, 33725.0, 33902.491462628575, 33678.98963031113, 33834.323313534085, 34526.0, 34757.30485390227, 34970.65029349674, 31434.84282578664, 36859.0, 39510.0, 39904.60095399009, 40110.39834402428, 31519.84240078785, 31804.0, 32479.512807307437, 32556.02331930046, 33287.83356083204, 25598.8720056406, 25645.871770641028, 25649.871750641127, 25871.870640646677, 26205.73794262033], "value": 20660.314783898688, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.1852483798063, 396.5083296712076, 397.3086828917684, 404.3934098851722, 426.6075210806058, 14300.427982880672, 20263.797362026377, 20952.16191352348, 21490.89254553727, 21693.34919952402, 21254.0, 21438.678419823715, 21564.89217553914, 21605.67591486129, 21697.783022169773, 5866.354700982888, 6163.753449862, 6174.10475481055, 6176.8455788605415, 6190.876182476348, 5826.15520749491, 5963.4334738199805, 6026.035834266519, 6091.756329746805, 6108.664023478706, 5862.912056319137, 6018.909716354258, 6022.337542870281, 6023.819285421422, 6042.697865106749, 5034.848954531388, 5786.797462088828, 5937.109433584954, 5957.076653118774, 6068.787592434243, 4530.207213737583, 5299.814506492293, 5311.415744268127, 5314.043472175007, 5349.331333583302, 26055.218343449727, 26178.73821261782, 26254.47491050168, 26287.868560657265, 26413.867930660224, 2334.8132149428006, 2499.4253620219147, 2516.874156292178, 2653.7080921098664, 2704.959425608598, 11947.940260298643, 14012.0, 15490.148041857694, 16022.51932442017, 16143.596410089724, 1831.377331707226, 1875.2967637136073, 1911.827935485813, 1918.9232430702755, 1946.143696773415, 107359.0, 130648.34675826655, 135873.96189057172, 136509.0, 138007.0, 9739.415635061778, 11437.37094459796, 11705.531778728926, 11908.98773604236, 11965.700857478458, 13054.412551435018, 13674.521391751241, 14697.55907322782, 14730.0, 14737.557873263819, 7416.592087435243, 8668.046514883357, 8691.043985161627, 9040.728778136536, 9199.218066464426, 9011.864822027675, 9050.909490905138, 9069.818603628022, 9208.26333893287, 9211.723648290563, 6015.488683461867, 7166.8208294792, 7220.0613920190035, 7228.807246804324, 7246.0, 1975.7233987241798, 1991.960160796805, 1993.1031036033553, 1996.9500762480764, 2002.589469158819, 930.7905721212809, 1002.8395456726888, 1066.424130969268, 1099.9285046472114, 1115.9175599668308, 21795.564088718453, 22534.774652253596, 22808.087676493087, 23040.53918921612, 23162.1893233736, 1098.9120870330355, 1110.4392281897751, 1117.458032854065, 1142.257532603814, 1143.4397145398696, 10358.896411035945, 11424.314541127558, 11805.822912656316, 12061.035117190604, 12095.0, 788011.0, 788643.0, 794118.0, 796810.0, 799231.0, 121638.0, 137325.0, 138622.30688846693, 139066.30466847602, 141257.0, 1059.6662051453768, 1089.2429761315968, 1100.5267734873921, 1101.5208384352673, 1107.8449017137607, 16185.75721364204, 17903.910480447514, 19459.0, 19642.116104775607, 19717.60564788725, 52292.0, 56194.15708764453, 57723.26025452572, 58851.70574147102, 60080.0, 65499.672501637186, 70158.29841701721, 88245.55877220572, 89451.0, 90879.0, 7155.570665760071, 8343.374246931626, 8382.580870956306, 8435.83128337442, 8688.174623410765, 12289.385530723606, 12776.297303648016, 13119.409626567018, 13148.737025259632, 13216.339183041002, 8797.0, 9544.379615324982, 10470.842937356098, 11020.338779673246, 11246.66260012201, 10583.894161058292, 10646.8935310649, 10668.1998850085, 10910.836337455103, 10917.890821092005, 10134.391936483835, 10377.481125943823, 11481.770364592494, 11630.76738465209, 11644.41777911118, 7291.41668666516, 7466.850662986819, 7664.501807382502, 7867.567283799446, 8707.564621768759, 9861.161801246973, 11106.666799996014, 11337.943310283395, 11598.0, 11600.88399115998], "value": 10134.703362150763, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.3385508810861, 1468.1484738851468, 1495.3420494982206, 1505.6687528743673, 1510.1920472547183, 7746.883796743054, 8009.1990800919875, 8255.38084643652, 8701.17338852808, 8709.21617054465, 8162.632681529345, 8483.745487635366, 8499.192576705218, 8628.180322869333, 8639.740807775761, 2321.442853715113, 2360.681308023412, 2362.0669835415, 2369.230000249917, 2373.3947843299934, 2229.843910926236, 2231.9286742363674, 2434.7200071991683, 2445.3519817248443, 2445.7798798108165, 2345.4253707841604, 2383.5709572276983, 2387.069043073203, 2412.49337639096, 2415.9879200603973, 2335.252719129879, 2340.20433052762, 2346.7887890089974, 2365.3613524348416, 2384.082128380577, 2326.255598208574, 2333.1600623775425, 2355.234548771656, 2355.316958082154, 2357.622780355135, 8443.324534037261, 8520.74437766868, 8610.698625548075, 8629.827403451958, 8633.266172375355, 5203.271541984125, 5294.629375943686, 5321.840344789663, 5339.252504649352, 5356.571474282087, 3517.66582174693, 3574.767640103385, 3587.246678197582, 3600.1539638185004, 3628.981855090734, 1212.993935030328, 1251.7997120460682, 1289.38109707341, 1312.127435255554, 1325.072449285504, 11029.669109926715, 11029.889701102966, 11036.227464077518, 11065.834012489819, 11085.72285692856, 4688.9531104689195, 4774.618030557547, 4805.207140821725, 4833.429135530966, 4878.365812444359, 7750.534967901944, 8287.62705678246, 8858.822823543493, 8872.24585910192, 8958.507282099547, 5285.392179899284, 5304.734763261821, 5305.071612467803, 5314.176302673091, 5314.256004159421, 4403.691741578123, 4507.233770259064, 4919.2129259318335, 4962.454130045692, 4997.050560393586, 3743.7753734776, 3818.809059547067, 3861.768293902375, 3872.341701910682, 3887.2225554889137, 1186.2763714134371, 1198.1672737447564, 1200.4717924113363, 1202.6632542888005, 1203.464458316041, 923.8752768376318, 927.6125608838597, 933.7012156110115, 939.8026414452904, 971.4754032822339, 5495.313085864267, 5634.1830434586755, 5716.388738306393, 5856.677882716492, 5881.5882888198275, 522.7386306846577, 564.4383838081136, 565.406323360478, 576.5013263527019, 588.1677426441537, 3781.9810900945317, 3861.9034524137123, 3903.843846246118, 3912.1441347816253, 3931.5478719947, 9638.277129215337, 10097.646582369582, 10162.83127440339, 10182.0, 10221.0, 8205.466644668077, 9730.12428881404, 10009.449480278508, 10146.695599132037, 10188.745281367877, 7942.0, 8083.393745469113, 8090.312323452455, 8096.271335579709, 8116.0, 4781.0, 5297.9139276449005, 5496.835094947158, 5632.690202038927, 5820.76716931318, 9239.445633262027, 9282.489463079595, 9317.953410233176, 9354.298427618092, 9369.718908432758, 9091.818163636557, 9382.906170938204, 9442.622295108118, 9588.041195880356, 9622.422654640744, 6772.762953296611, 6967.616781077089, 7342.8898566520475, 7343.5961022142155, 7475.4019678426685, 8102.675892964216, 8115.7159499419595, 8165.714200003209, 8178.427510074238, 8181.672733090848, 6973.965130174317, 7080.716771329296, 7145.64271786419, 7166.462515311477, 7231.2768723127265, 6934.653267336512, 6997.335253150943, 7012.018317435665, 7090.184628767758, 7124.857502850017, 5835.095560188178, 5867.471927526543, 5875.6474611523445, 5912.320083190488, 5956.791512297049, 5019.322391477104, 5055.949440505695, 5081.949180508148, 5121.513456221653, 5125.538701516882, 5068.695878247317, 5077.136886729191, 5094.083065048327, 5136.280920671034, 5286.048511267855], "value": 4414.123150334294, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.93420848726015, 358.42472831106073, 368.12202896092816, 374.3673192305005, 378.95073640426756, 4143.523494798099, 5385.35375754909, 5536.224928510007, 5538.335399752035, 5551.694656793879, 4178.728382655126, 4822.7687449408895, 5311.840644780653, 5348.866278343044, 5499.102809530706, 3397.9490307645283, 3990.840366385341, 4228.344606585984, 4243.681723870706, 4285.657147428214, 3058.9541156882665, 3565.983694647026, 4000.5597984725623, 4259.041715613977, 4285.635720963722, 3876.8175706409675, 3962.187751510933, 4064.959350406488, 4072.2669919414493, 4098.8565400211, 3534.5228394166843, 4067.5118985721606, 4067.9389809152885, 4088.9795551022203, 4107.8973025674295, 2869.4404591104535, 3291.7780498163643, 3554.7156227501996, 3601.477785721082, 3613.8012409317475, 4986.925196122061, 5250.6324557281005, 5311.893762124774, 5344.706041167734, 5359.4189714034455, 3734.607866174067, 4036.636702696742, 4123.463949686521, 4257.127288905767, 4708.670393072452, 5116.05826649678, 5234.272690012325, 5796.420357964212, 5820.941790582082, 5831.300243970751, 3427.9828600856836, 3495.6017592963017, 3793.785988483659, 3801.6388443097862, 3939.231849789292, 5471.23402723619, 5998.97000514999, 6008.279006519202, 6030.577859549833, 6047.274327080734, 3808.200277941608, 4002.8598999034893, 4019.738716983387, 4032.3951407288555, 4043.1711499142607, 3923.7841918694744, 4050.3924411338535, 4074.9388759168637, 4092.5498195198506, 4110.938335924963, 4053.756774593534, 4328.7402755834755, 4655.95344046562, 4701.717896926195, 4731.219348807408, 5041.395032596112, 5088.872778180576, 5765.221695071195, 5789.899919015374, 5796.594238403271, 1048.727330893973, 1281.1032277405666, 1298.8311519502404, 1307.1438207973792, 1310.0043966585463, 742.3726950726627, 803.369355056278, 853.5817449449725, 881.969131080409, 886.9068747781519, 4808.422989241313, 4851.830185943475, 5155.664881782747, 5184.507471790174, 5217.582483473331, 4757.072370887644, 5376.8118115865755, 5470.288862447856, 5728.283964504437, 5769.538436925036, 4911.803527858846, 5142.665726727751, 5155.74221288934, 5183.0563538673, 5208.8437346879655, 2535.4802265535523, 2640.881160347789, 2665.60015997598, 2934.9559756603667, 3072.0, 5037.596992240612, 5326.547243484347, 5912.368995796829, 5960.344362120163, 5990.490808281258, 5008.524190201925, 5364.7049412282695, 5817.854553636108, 5841.591088623841, 5844.473997340175, 5214.634975551675, 5310.415854255952, 5738.073582472969, 5893.966581529366, 5904.586678932434, 4934.432540257916, 5445.727713614224, 5559.63862348946, 5715.657060576379, 5737.684427356536, 5546.500814926676, 5565.944340556543, 5618.073017951992, 5633.352164501135, 5640.717964101696, 5018.44797072329, 5023.764424830915, 5526.955026640008, 5835.7373918174635, 5895.14520394549, 3369.275605744777, 3521.0, 3829.7170447900266, 3843.1184503650957, 3854.3640299350295, 4672.602828759457, 5128.461511541234, 5297.788088476418, 5955.434233747787, 5988.640681559121, 4593.402857628487, 4922.384701912261, 4993.500649934977, 5244.973775131252, 5801.3038435388025, 4643.535646435329, 5230.63385563007, 5833.883322333445, 5850.0, 5864.179014937998, 4719.787609557647, 5355.892882142258, 5701.629394089371, 5742.741576628978, 5781.219535362672, 5057.570106540985, 5302.125149350468, 5665.291838520185, 5863.442972917566, 5908.5568582357355, 5258.710770907637, 5731.19763233156, 5737.196792449144, 5744.856378590569, 5756.827295181151], "value": 4079.7268182125463, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.79425760787, 749.7713197474772, 787.5747096567853, 877.4647465046321, 883.1212943121593, 15012.77480837787, 16089.839101608995, 16096.356145754156, 16102.677946441094, 16109.275082621281, 14668.559943201695, 14827.777583336232, 14899.851001489982, 15049.849501504981, 15069.849301506982, 695.5409429776348, 787.8700014497601, 832.7585000349922, 863.3999370437548, 875.8554838451648, 721.293132729925, 854.9016863060765, 872.8603423452249, 882.1355072029415, 885.7608445719652, 904.9276057915351, 938.5307346326837, 939.1829108675462, 941.948192849393, 942.5334459442586, 898.3980732908967, 931.8881734191906, 935.550935550936, 937.1003836317144, 938.0057139432156, 673.474689742002, 676.0298970976615, 691.5401258163315, 700.5061431690697, 702.0066605752853, 13660.590182294547, 14929.328180231807, 15151.469698560497, 15567.065976041475, 16256.0, 10610.257281990187, 13039.478420863154, 13152.21086734799, 13227.801582976263, 13527.32363381827, 11738.75677474798, 11865.406729663482, 11951.641450756491, 11985.161038727292, 11988.280703157838, 3832.291026160169, 3879.12719638082, 3905.2384784966944, 4028.9597104028876, 4066.369712694537, 91902.54048729714, 104361.0, 105433.94566054318, 108019.45990269998, 109922.45038774835, 11422.200445968703, 11611.245269057485, 11618.88381116195, 11843.526258949545, 12001.1599188056, 24076.965398284054, 24585.631215531783, 24867.767190265513, 24877.62683559748, 24883.5023299533, 8189.959050204711, 8210.055843577946, 8226.383021273428, 8268.8346233075, 8367.790805229795, 4406.294992801137, 4427.800748966304, 4516.23224051912, 4601.447826260804, 4621.376114224604, 3751.362268414376, 3813.2945405100145, 3825.8087095645105, 3843.9039024024064, 3852.8073596320633, 555.927729395176, 675.9729610815513, 690.520088538471, 700.9369156775915, 716.9068021157216, 616.9413905678954, 667.4126768443739, 697.2852825853439, 699.6328659218437, 735.7939776862487, 10509.737256568647, 10616.734581635521, 10778.78442431147, 11021.504032318566, 11115.277506962022, 680.7474247384737, 691.0601581848682, 694.5138403117737, 739.4749727693398, 748.2704363245792, 5112.401919897706, 5115.616328775357, 5179.663321884141, 5184.170532714747, 5199.246109314128, 80953.59523202464, 82829.51511454667, 90578.0, 100730.0, 104650.0, 90704.5464772672, 91953.08046919579, 93718.53140734253, 96072.0, 96532.51733741286, 77171.61414193005, 77234.0, 78597.82103268341, 79968.60015700001, 80671.0, 9971.0, 9992.800143996934, 10018.05684414236, 10120.190384769054, 10155.0, 50571.0, 50983.745081274355, 51035.74482127714, 51741.0, 52834.943301133, 62387.37612623997, 62642.0, 62734.0, 62766.0, 65173.02240466492, 12054.337011464453, 12179.269243845398, 12249.265044097026, 12775.488980440678, 12926.806097908351, 13217.537386191438, 13224.735505289647, 13263.668408289484, 13291.142394418837, 13590.728185436039, 12257.100149476846, 12395.876041239471, 12454.688632784253, 12580.30808305552, 12972.286524241257, 12788.680282993, 12825.551105711254, 12861.356932153169, 13868.544368135113, 14423.567292981228, 13598.320083995959, 13677.31613419345, 14279.357428915931, 14495.637609059859, 14637.853621464075, 9467.147956683932, 9903.50482475859, 10114.51525870865, 10230.209713300292, 10421.739456513647, 11073.390963497084, 11167.441627918734, 11451.713707157387, 11480.909313615193, 11502.309861408343], "value": 7611.292411057179, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1573.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 116.0, 126.0, 169.0, 174.0, 168.0, 116.0, 117.0, 115.0], "value": 132.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 129.0, 148.0, 109.0, 107.0, 111.0, 109.0, 129.0, 111.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 195.0, 198.0, 198.0, 199.0, 195.0, 211.0, 198.0, 233.0, 233.0], "value": 205.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 58.0, 86.0, 62.0, 68.0, 70.0, 71.0, 88.0, 51.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 102.0, 99.0, 71.0, 69.0, 69.0, 83.0, 99.0, 58.0, 67.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 120.0, 92.0, 131.0, 136.0, 97.0, 92.0, 90.0, 91.0, 133.0], "value": 107.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 130.0, 118.0, 171.0, 174.0, 100.0, 100.0, 102.0, 101.0], "value": 119.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 91.0, 91.0, 124.0, 83.0, 137.0, 147.0, 115.0, 93.0, 92.0], "value": 111.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 58.0, 59.0, 59.0, 61.0, 61.0, 61.0, 61.0, 61.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 80.0, 87.0, 108.0, 101.0, 77.0, 70.0, 73.0, 71.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 84.0, 83.0, 77.0, 105.0, 104.0, 70.0, 102.0, 71.0, 92.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 126.0, 151.0, 123.0, 153.0, 154.0, 157.0, 132.0, 182.0, 145.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 223.0, 192.0, 166.0, 170.0, 166.0, 168.0, 165.0, 172.0, 167.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 84.0, 83.0, 66.0, 68.0, 66.0, 65.0, 76.0, 64.0, 70.0], "value": 71.0, "unit": "score"}]}]} 15:22:49 INFO - Return code: 0 15:22:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:22:49 ERROR - # TBPL SUCCESS # 15:22:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:22:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:22:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:22:49 INFO - 2017-04-07 15:22:49.367 screenresolution[1931:15785] starting screenresolution argv=screenresolution get 15:22:49 INFO - 2017-04-07 15:22:49.370 screenresolution[1931:15785] Display 0: 1600x1200x32@60 15:22:49 INFO - 2017-04-07 15:22:49.376 screenresolution[1932:15788] starting screenresolution argv=screenresolution list 15:22:49 INFO - Available Modes on Display 0 15:22:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:22:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:22:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:22:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:22:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:22:49 INFO - Intel Iris: 15:22:49 INFO - Chipset Model: Intel Iris 15:22:49 INFO - Type: GPU 15:22:49 INFO - Bus: Built-In 15:22:49 INFO - VRAM (Dynamic, Max): 1536 MB 15:22:49 INFO - Vendor: Intel (0x8086) 15:22:49 INFO - Device ID: 0x0a2e 15:22:49 INFO - Revision ID: 0x0009 15:22:49 INFO - Displays: 15:22:49 INFO - Display: 15:22:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:22:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:22:49 INFO - Display Serial Number: 1600x1200 60 15:22:49 INFO - Main Display: Yes 15:22:49 INFO - Mirror: Off 15:22:49 INFO - Online: Yes 15:22:49 INFO - Rotation: Supported 15:22:49 INFO - Return code: 0 15:22:49 INFO - Running post-action listener: _resource_record_post_action 15:22:49 INFO - [mozharness: 2017-04-07 22:22:49.613281Z] Finished run-tests step (success) 15:22:49 INFO - Running post-run listener: _resource_record_post_run 15:22:49 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 2723840; Write bytes: 425607680; Read time: 23; Write time: 1459 15:22:49 INFO - TinderboxPrint: CPU usage
54.2% 15:22:49 INFO - TinderboxPrint: I/O read bytes / time
2,723,840 / 23 15:22:49 INFO - TinderboxPrint: I/O write bytes / time
425,607,680 / 1,459 15:22:49 INFO - TinderboxPrint: CPU idle
2,183.2 (45.8%) 15:22:49 INFO - TinderboxPrint: CPU system
960.1 (20.1%) 15:22:49 INFO - TinderboxPrint: CPU user
1,622.4 (34.0%) 15:22:49 INFO - TinderboxPrint: Swap in / out
125,026,304 / 0 15:22:49 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119159296; Write bytes: 112795648; Read time: 10812; Write time: 249 15:22:49 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 2678784; Write bytes: 298052096; Read time: 19; Write time: 1138 15:22:50 INFO - Running post-run listener: _upload_blobber_files 15:22:50 INFO - Blob upload gear active. 15:22:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:22:50 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 15:22:50 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:22:50 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:22:50 INFO - (blobuploader) - INFO - Open directory for files ... 15:22:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:22:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:22:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:51 INFO - (blobuploader) - INFO - Done attempting. 15:22:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:22:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:22:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:52 INFO - (blobuploader) - INFO - Done attempting. 15:22:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:22:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:22:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:53 INFO - (blobuploader) - INFO - Done attempting. 15:22:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:22:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:22:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:56 INFO - (blobuploader) - INFO - Done attempting. 15:22:56 INFO - (blobuploader) - INFO - Iteration through files over. 15:22:56 INFO - Return code: 0 15:22:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:22:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:22:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/793e9b0114f1b9f393079c7f753c81c06c2df2114e75fee73239168cdb76fb7d08e64e83dddd08b74bb07de36368f49157d922bc00fba0f7ef4e0c8cca13405e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a4e23626ab4698bdec2737487ad92103264616ea35b522e77bd25e2302b960ce32c4b96b16161235562a2c1320711933550b2b166f53952e2298152614a8999", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba51b965c89f62c68f318bc7891c2d3902e4be8e8d147b55f2c847b88d2d19391c081381eac4d32cd38cd532d2cb2f0851062f9b5ce17d6e33d0b40642653e6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a16a178c4e90230a0726c9ffa62e25a08992eb6a32dec29a941d026b84c68df3872628b018a57ff6673d12ac542c5b4e134056e53f7894767327bf443789572"} 15:22:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:22:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:22:56 INFO - Contents: 15:22:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/793e9b0114f1b9f393079c7f753c81c06c2df2114e75fee73239168cdb76fb7d08e64e83dddd08b74bb07de36368f49157d922bc00fba0f7ef4e0c8cca13405e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a4e23626ab4698bdec2737487ad92103264616ea35b522e77bd25e2302b960ce32c4b96b16161235562a2c1320711933550b2b166f53952e2298152614a8999", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba51b965c89f62c68f318bc7891c2d3902e4be8e8d147b55f2c847b88d2d19391c081381eac4d32cd38cd532d2cb2f0851062f9b5ce17d6e33d0b40642653e6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a16a178c4e90230a0726c9ffa62e25a08992eb6a32dec29a941d026b84c68df3872628b018a57ff6673d12ac542c5b4e134056e53f7894767327bf443789572"} 15:22:56 INFO - Running post-run listener: copy_logs_to_upload_dir 15:22:56 INFO - Copying logs to upload dir... 15:22:56 INFO - mkdir: /builds/slave/test/build/upload/logs 15:22:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.977030 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-04-07 15:22:56.067266) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 15:22:56.070963) ========= 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.o7nODwaoKi/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.reE1QMxNY1/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/793e9b0114f1b9f393079c7f753c81c06c2df2114e75fee73239168cdb76fb7d08e64e83dddd08b74bb07de36368f49157d922bc00fba0f7ef4e0c8cca13405e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a4e23626ab4698bdec2737487ad92103264616ea35b522e77bd25e2302b960ce32c4b96b16161235562a2c1320711933550b2b166f53952e2298152614a8999", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba51b965c89f62c68f318bc7891c2d3902e4be8e8d147b55f2c847b88d2d19391c081381eac4d32cd38cd532d2cb2f0851062f9b5ce17d6e33d0b40642653e6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a16a178c4e90230a0726c9ffa62e25a08992eb6a32dec29a941d026b84c68df3872628b018a57ff6673d12ac542c5b4e134056e53f7894767327bf443789572"} build_url:https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010881 build_url: 'https://queue.taskcluster.net/v1/task/SUnQggl9RM-GeGKA9Aiplw/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/793e9b0114f1b9f393079c7f753c81c06c2df2114e75fee73239168cdb76fb7d08e64e83dddd08b74bb07de36368f49157d922bc00fba0f7ef4e0c8cca13405e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a4e23626ab4698bdec2737487ad92103264616ea35b522e77bd25e2302b960ce32c4b96b16161235562a2c1320711933550b2b166f53952e2298152614a8999", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba51b965c89f62c68f318bc7891c2d3902e4be8e8d147b55f2c847b88d2d19391c081381eac4d32cd38cd532d2cb2f0851062f9b5ce17d6e33d0b40642653e6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a16a178c4e90230a0726c9ffa62e25a08992eb6a32dec29a941d026b84c68df3872628b018a57ff6673d12ac542c5b4e134056e53f7894767327bf443789572"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 15:22:56.107982) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:22:56.108368) ========= 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.o7nODwaoKi/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.reE1QMxNY1/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.005158 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:22:56.158134) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 15:22:56.158530) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 15:22:56.158946) ========= ========= Total master_lag: 0.22 =========