builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0371 starttime: 1489252344.34 results: success (0) buildid: 20170311083036 builduid: a44d8d95014649f4a0a5331541ab0c59 revision: 83ac51cb9cfdfc5985d68a6ddc30eb86a9ceef67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:24.344983) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:24.345432) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:24.345730) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:24.371415) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:24.371708) ========= 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.SM115eF9EY/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.SAKfODYHSB/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-03-11 09:12:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 53.0M=0s 2017-03-11 09:12:24 (53.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145671 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:24.541181) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:24.541508) ========= 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.SM115eF9EY/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.SAKfODYHSB/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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.024879 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:24.589499) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-11 09:12:24.589871) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 83ac51cb9cfdfc5985d68a6ddc30eb86a9ceef67 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 83ac51cb9cfdfc5985d68a6ddc30eb86a9ceef67 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SM115eF9EY/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.SAKfODYHSB/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-03-11 09:12:24,696 truncating revision to first 12 chars 2017-03-11 09:12:24,696 Setting DEBUG logging. 2017-03-11 09:12:24,697 attempt 1/10 2017-03-11 09:12:24,697 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/83ac51cb9cfd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-11 09:12:25,722 unpacking tar archive at: mozilla-aurora-83ac51cb9cfd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.298265 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-11 09:12:25.912443) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:25.912839) ========= script_repo_revision: 83ac51cb9cfdfc5985d68a6ddc30eb86a9ceef67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:25.913266) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:25.913541) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-11 09:12:25.933386) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-11 09:12:25.933744) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SM115eF9EY/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.SAKfODYHSB/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:12:26 INFO - MultiFileLogger online at 20170311 09:12:26 in /builds/slave/test 09:12:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:12:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:12:26 INFO - {'append_to_log': False, 09:12:26 INFO - 'base_work_dir': '/builds/slave/test', 09:12:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:12:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:12:26 INFO - 'branch': 'Mozilla-Aurora', 09:12:26 INFO - 'buildbot_json_path': 'buildprops.json', 09:12:26 INFO - 'config_files': ('talos/mac_config.py',), 09:12:26 INFO - 'default_actions': ('clobber', 09:12:26 INFO - 'read-buildbot-config', 09:12:26 INFO - 'download-and-extract', 09:12:26 INFO - 'populate-webroot', 09:12:26 INFO - 'create-virtualenv', 09:12:26 INFO - 'install', 09:12:26 INFO - 'run-tests'), 09:12:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:12:26 INFO - 'download_minidump_stackwalk': True, 09:12:26 INFO - 'download_symbols': 'ondemand', 09:12:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:12:26 INFO - 'tooltool.py': '/tools/tooltool.py', 09:12:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:12:26 INFO - '/tools/misc-python/virtualenv.py')}, 09:12:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:12:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:12:26 INFO - 'gecko_profile': False, 09:12:26 INFO - 'gecko_profile_interval': 0, 09:12:26 INFO - 'installer_path': 'installer.exe', 09:12:26 INFO - 'log_level': 'info', 09:12:26 INFO - 'log_name': 'talos', 09:12:26 INFO - 'log_to_console': True, 09:12:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:12:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:12:26 INFO - 'opt_config_files': (), 09:12:26 INFO - 'pip_index': False, 09:12:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:12:26 INFO - 'cmd': ('bash', 09:12:26 INFO - '-c', 09:12:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:12:26 INFO - 'enabled': True, 09:12:26 INFO - 'halt_on_failure': False, 09:12:26 INFO - 'name': 'check_screen_resolution'},), 09:12:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:12:26 INFO - 'cmd': ('bash', 09:12:26 INFO - '-c', 09:12:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:12:26 INFO - 'enabled': True, 09:12:26 INFO - 'halt_on_failure': False, 09:12:26 INFO - 'name': 'check_screen_resolution'},), 09:12:26 INFO - 'run_cmd_checks_enabled': True, 09:12:26 INFO - 'suite': 'dromaeojs-e10s', 09:12:26 INFO - 'system_bits': '32', 09:12:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:12:26 INFO - 'title': 't-yosemite-r7-0371', 09:12:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:12:26 INFO - 'use_talos_json': True, 09:12:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:12:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:12:26 INFO - 'work_dir': 'build'} 09:12:26 INFO - [mozharness: 2017-03-11 17:12:26.151200Z] Running clobber step. 09:12:26 INFO - Running pre-action listener: _resource_record_pre_action 09:12:26 INFO - Running main action method: clobber 09:12:26 INFO - rmtree: /builds/slave/test/build 09:12:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:12:28 INFO - Running post-action listener: _resource_record_post_action 09:12:28 INFO - [mozharness: 2017-03-11 17:12:28.583627Z] Finished clobber step (success) 09:12:28 INFO - [mozharness: 2017-03-11 17:12:28.583821Z] Running read-buildbot-config step. 09:12:28 INFO - Running pre-action listener: _resource_record_pre_action 09:12:28 INFO - Running main action method: read_buildbot_config 09:12:28 INFO - Using buildbot properties: 09:12:28 INFO - { 09:12:28 INFO - "project": "", 09:12:28 INFO - "product": "firefox", 09:12:28 INFO - "script_repo_revision": "production", 09:12:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:12:28 INFO - "repository": "", 09:12:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:12:28 INFO - "buildid": "20170311083036", 09:12:28 INFO - "pgo_build": "False", 09:12:28 INFO - "basedir": "/builds/slave/test", 09:12:28 INFO - "buildnumber": 14, 09:12:28 INFO - "slavename": "t-yosemite-r7-0371", 09:12:28 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:12:28 INFO - "platform": "yosemite_r7", 09:12:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:12:28 INFO - "branch": "mozilla-aurora", 09:12:28 INFO - "repo_path": "releases/mozilla-aurora", 09:12:28 INFO - "revision": "83ac51cb9cfdfc5985d68a6ddc30eb86a9ceef67", 09:12:28 INFO - "stage_platform": "macosx64", 09:12:28 INFO - "builduid": "a44d8d95014649f4a0a5331541ab0c59", 09:12:28 INFO - "slavebuilddir": "test" 09:12:28 INFO - } 09:12:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:12:28 INFO - Running post-action listener: _resource_record_post_action 09:12:28 INFO - [mozharness: 2017-03-11 17:12:28.587366Z] Finished read-buildbot-config step (success) 09:12:28 INFO - [mozharness: 2017-03-11 17:12:28.587497Z] Running download-and-extract step. 09:12:28 INFO - Running pre-action listener: _resource_record_pre_action 09:12:28 INFO - Running main action method: download_and_extract 09:12:28 INFO - mkdir: /builds/slave/test/build/tests 09:12: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')]} 09:12:28 INFO - trying https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:12:28 INFO - Downloading https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:12:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 09:12:29 INFO - Downloaded 1199 bytes. 09:12:29 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:12:29 INFO - Using the following test package requirements: 09:12:29 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:12:29 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:12:29 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:12:29 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:12:29 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:12:29 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:12:29 INFO - u'jsshell-mac.zip'], 09:12:29 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:12:29 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:12:29 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:12:29 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:12:29 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:12:29 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:12:29 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:12:29 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:12:29 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:12:29 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:12:29 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:12:29 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:12:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:12:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:12:29 INFO - Fetch https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:12:31 INFO - Expected file size: 51779036 09:12:31 INFO - Obtained file size: 51779036 09:12:47 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:12:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:12:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:12:47 INFO - Fetch https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:12:49 INFO - Expected file size: 51779036 09:12:49 INFO - Obtained file size: 51779036 09:13:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:13:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:13:04 INFO - Fetch https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:13:05 INFO - Expected file size: 14404424 09:13:05 INFO - Obtained file size: 14404424 09:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:06 INFO - trying https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:13:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:13:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 09:13:08 INFO - Downloaded 74621781 bytes. 09:13:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:13:08 INFO - mkdir: /builds/slave/test/properties 09:13:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:13:08 INFO - Writing to file /builds/slave/test/properties/build_url 09:13:08 INFO - Contents: 09:13:08 INFO - build_url:https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:13:08 INFO - Running post-action listener: _resource_record_post_action 09:13:08 INFO - Running post-action listener: set_extra_try_arguments 09:13:08 INFO - [mozharness: 2017-03-11 17:13:08.883131Z] Finished download-and-extract step (success) 09:13:08 INFO - [mozharness: 2017-03-11 17:13:08.883256Z] Running populate-webroot step. 09:13:08 INFO - Running pre-action listener: _resource_record_pre_action 09:13:08 INFO - Running main action method: populate_webroot 09:13:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:13:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:13:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:13:08 INFO - '--tppagecycles', 09:13:08 INFO - '7'], 09:13:08 INFO - 'tests': ['tsvgm']}}, 09:13:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:13:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:13:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:13:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:13:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:13:08 INFO - 'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:13:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:13:08 INFO - 'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['damp', 'tps']}, 09:13:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:13:08 INFO - 'tests': ['damp', 'tps']}, 09:13:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['dromaeo_dom']}, 09:13:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:13:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['basic_compositor_video']}, 09:13:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:13:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['a11yr', 09:13:08 INFO - 'ts_paint', 09:13:08 INFO - 'tpaint', 09:13:08 INFO - 'sessionrestore', 09:13:08 INFO - 'sessionrestore_no_auto_restore', 09:13:08 INFO - 'tabpaint']}, 09:13:08 INFO - 'other-e10s': {'tests': ['a11yr', 09:13:08 INFO - 'ts_paint', 09:13:08 INFO - 'tpaint', 09:13:08 INFO - 'sessionrestore', 09:13:08 INFO - 'sessionrestore_no_auto_restore', 09:13:08 INFO - 'tabpaint']}, 09:13:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:13:08 INFO - 'ts_paint', 09:13:08 INFO - 'tpaint', 09:13:08 INFO - 'sessionrestore', 09:13:08 INFO - 'sessionrestore_no_auto_restore', 09:13:08 INFO - 'tabpaint']}, 09:13:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:13:08 INFO - 'ts_paint', 09:13:08 INFO - 'tpaint', 09:13:08 INFO - 'sessionrestore', 09:13:08 INFO - 'sessionrestore_no_auto_restore', 09:13:08 INFO - 'tabpaint']}, 09:13:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['a11yr', 09:13:08 INFO - 'ts_paint', 09:13:08 INFO - 'tpaint', 09:13:08 INFO - 'sessionrestore', 09:13:08 INFO - 'sessionrestore_no_auto_restore', 09:13:08 INFO - 'tabpaint']}, 09:13:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['a11yr', 09:13:08 INFO - 'ts_paint', 09:13:08 INFO - 'tpaint', 09:13:08 INFO - 'sessionrestore', 09:13:08 INFO - 'sessionrestore_no_auto_restore', 09:13:08 INFO - 'tabpaint']}, 09:13:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['tsvgx', 09:13:08 INFO - 'tsvgr_opacity', 09:13:08 INFO - 'tart', 09:13:08 INFO - 'tscrollx', 09:13:08 INFO - 'cart', 09:13:08 INFO - 'tsvg_static']}, 09:13:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:13:08 INFO - 'tsvgr_opacity', 09:13:08 INFO - 'tart', 09:13:08 INFO - 'tscrollx', 09:13:08 INFO - 'cart', 09:13:08 INFO - 'tsvg_static']}, 09:13:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:13:08 INFO - 'talos_options': ['--disable-e10s'], 09:13:08 INFO - 'tests': ['tp5o']}, 09:13:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:13:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:13:08 INFO - 'talos_options': ['--disable-e10s', 09:13:08 INFO - '--xperf_path', 09:13:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:08 INFO - 'tests': ['tp5n']}, 09:13:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:13:08 INFO - 'talos_options': ['--xperf_path', 09:13:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:08 INFO - 'tests': ['tp5n']}}, 09:13:08 INFO - 'talos.zip': {'path': '', 09:13:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:13:08 INFO - Running post-action listener: _resource_record_post_action 09:13:08 INFO - [mozharness: 2017-03-11 17:13:08.901244Z] Finished populate-webroot step (success) 09:13:08 INFO - [mozharness: 2017-03-11 17:13:08.901347Z] Running create-virtualenv step. 09:13:08 INFO - Running pre-action listener: _resource_record_pre_action 09:13:08 INFO - Running main action method: create_virtualenv 09:13:08 INFO - Creating virtualenv /builds/slave/test/build/venv 09:13:08 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 09:13:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:13:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:13:09 INFO - Using real prefix '/tools/python27' 09:13:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:13:09 INFO - Installing distribute.............................................................................................................................................................................................done. 09:13:12 INFO - Installing pip.................done. 09:13:12 INFO - Return code: 0 09:13:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:13:12 INFO - Reading from file tmpfile_stdout 09:13:12 INFO - Output received: 09:13:12 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) 09:13:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:13:12 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')]} 09:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:12 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')]} 09:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:12 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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:12 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 09:13:12 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 09:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:12 INFO - 'HOME': '/Users/cltbld', 09:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:12 INFO - 'LOGNAME': 'cltbld', 09:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:12 INFO - 'MOZ_NO_REMOTE': '1', 09:13:12 INFO - 'NO_EM_RESTART': '1', 09:13:12 INFO - 'PAGER': '/bin/cat', 09:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:12 INFO - 'PWD': '/builds/slave/test', 09:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:12 INFO - 'SHELL': '/bin/bash', 09:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:12 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:12 INFO - 'USER': 'cltbld', 09:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:12 INFO - 'XPC_FLAGS': '0x0', 09:13:12 INFO - 'XPC_SERVICE_NAME': '0', 09:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:13 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 09:13:13 INFO - Cleaning up... 09:13:13 INFO - Return code: 0 09:13:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:13 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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:13 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 09:13:13 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 09:13:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:13 INFO - 'HOME': '/Users/cltbld', 09:13:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:13 INFO - 'LOGNAME': 'cltbld', 09:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:13 INFO - 'MOZ_NO_REMOTE': '1', 09:13:13 INFO - 'NO_EM_RESTART': '1', 09:13:13 INFO - 'PAGER': '/bin/cat', 09:13:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:13 INFO - 'PWD': '/builds/slave/test', 09:13:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:13 INFO - 'SHELL': '/bin/bash', 09:13:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:13 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:13 INFO - 'USER': 'cltbld', 09:13:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:13 INFO - 'XPC_FLAGS': '0x0', 09:13:13 INFO - 'XPC_SERVICE_NAME': '0', 09:13:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:13 INFO - Downloading/unpacking psutil>=3.1.1 09:13:13 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 09:13:13 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 09:13:13 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 09:13:13 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 09:13:13 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 09:13:13 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 09:13:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:16 INFO - Installing collected packages: psutil 09:13:16 INFO - Running setup.py install for psutil 09:13:16 INFO - building 'psutil._psutil_osx' extension 09:13:16 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 09:13:17 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 09:13:17 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 09:13:17 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 09:13:17 INFO - building 'psutil._psutil_posix' extension 09:13:17 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 09:13:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:13:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:13:17 INFO - ^ 09:13:17 INFO - 1 warning generated. 09:13:17 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 09:13:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:17 INFO - Successfully installed psutil 09:13:17 INFO - Cleaning up... 09:13:18 INFO - Return code: 0 09:13:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:19 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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:19 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 09:13:19 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 09:13:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:19 INFO - 'HOME': '/Users/cltbld', 09:13:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:19 INFO - 'LOGNAME': 'cltbld', 09:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:19 INFO - 'MOZ_NO_REMOTE': '1', 09:13:19 INFO - 'NO_EM_RESTART': '1', 09:13:19 INFO - 'PAGER': '/bin/cat', 09:13:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:19 INFO - 'PWD': '/builds/slave/test', 09:13:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:19 INFO - 'SHELL': '/bin/bash', 09:13:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:19 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:19 INFO - 'USER': 'cltbld', 09:13:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:19 INFO - 'XPC_FLAGS': '0x0', 09:13:19 INFO - 'XPC_SERVICE_NAME': '0', 09:13:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:13:19 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 09:13:19 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 09:13:19 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 09:13:19 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 09:13:19 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 09:13:19 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 09:13:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:13:22 INFO - Installing collected packages: mozsystemmonitor 09:13:22 INFO - Running setup.py install for mozsystemmonitor 09:13:22 INFO - Successfully installed mozsystemmonitor 09:13:22 INFO - Cleaning up... 09:13:22 INFO - Return code: 0 09:13:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:13:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:22 INFO - 'HOME': '/Users/cltbld', 09:13:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:22 INFO - 'LOGNAME': 'cltbld', 09:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:22 INFO - 'MOZ_NO_REMOTE': '1', 09:13:22 INFO - 'NO_EM_RESTART': '1', 09:13:22 INFO - 'PAGER': '/bin/cat', 09:13:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:22 INFO - 'PWD': '/builds/slave/test', 09:13:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:22 INFO - 'SHELL': '/bin/bash', 09:13:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:22 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:22 INFO - 'USER': 'cltbld', 09:13:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:22 INFO - 'XPC_FLAGS': '0x0', 09:13:22 INFO - 'XPC_SERVICE_NAME': '0', 09:13:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:22 INFO - Downloading/unpacking jsonschema==2.5.1 09:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:26 INFO - warning: no files found matching '*.txt' 09:13:26 INFO - no previously-included directories found matching 'build' 09:13:26 INFO - no previously-included directories found matching 'dist' 09:13:26 INFO - no previously-included directories found matching '.git*' 09:13:26 INFO - Installing collected packages: jsonschema, functools32 09:13:26 INFO - Running setup.py install for functools32 09:13:26 INFO - warning: no files found matching '*.txt' 09:13:26 INFO - no previously-included directories found matching 'build' 09:13:26 INFO - no previously-included directories found matching 'dist' 09:13:26 INFO - no previously-included directories found matching '.git*' 09:13:26 INFO - Successfully installed jsonschema functools32 09:13:26 INFO - Cleaning up... 09:13:26 INFO - Return code: 0 09:13:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:13:26 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')]} 09:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:26 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')]} 09:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:26 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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:26 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 09:13:26 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 09:13:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:26 INFO - 'HOME': '/Users/cltbld', 09:13:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:26 INFO - 'LOGNAME': 'cltbld', 09:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:26 INFO - 'MOZ_NO_REMOTE': '1', 09:13:26 INFO - 'NO_EM_RESTART': '1', 09:13:26 INFO - 'PAGER': '/bin/cat', 09:13:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:26 INFO - 'PWD': '/builds/slave/test', 09:13:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:26 INFO - 'SHELL': '/bin/bash', 09:13:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:26 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:26 INFO - 'USER': 'cltbld', 09:13:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:26 INFO - 'XPC_FLAGS': '0x0', 09:13:26 INFO - 'XPC_SERVICE_NAME': '0', 09:13:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:13:26 INFO - Cleaning up... 09:13:26 INFO - Return code: 0 09:13:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:13:26 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')]} 09:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:26 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')]} 09:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:26 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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:26 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 09:13:26 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 09:13:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:26 INFO - 'HOME': '/Users/cltbld', 09:13:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:26 INFO - 'LOGNAME': 'cltbld', 09:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:26 INFO - 'MOZ_NO_REMOTE': '1', 09:13:26 INFO - 'NO_EM_RESTART': '1', 09:13:26 INFO - 'PAGER': '/bin/cat', 09:13:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:26 INFO - 'PWD': '/builds/slave/test', 09:13:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:26 INFO - 'SHELL': '/bin/bash', 09:13:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:26 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:26 INFO - 'USER': 'cltbld', 09:13:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:26 INFO - 'XPC_FLAGS': '0x0', 09:13:26 INFO - 'XPC_SERVICE_NAME': '0', 09:13:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:26 INFO - Downloading/unpacking blobuploader==1.2.4 09:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:29 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:30 INFO - Downloading docopt-0.6.1.tar.gz 09:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:30 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:30 INFO - Running setup.py install for blobuploader 09:13:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:31 INFO - Running setup.py install for requests 09:13:31 INFO - Running setup.py install for docopt 09:13:31 INFO - Successfully installed blobuploader requests docopt 09:13:31 INFO - Cleaning up... 09:13:31 INFO - Return code: 0 09:13:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:31 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')]} 09:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:31 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')]} 09:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:31 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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:31 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 09:13:31 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 09:13:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:31 INFO - 'HOME': '/Users/cltbld', 09:13:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:31 INFO - 'LOGNAME': 'cltbld', 09:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:31 INFO - 'MOZ_NO_REMOTE': '1', 09:13:31 INFO - 'NO_EM_RESTART': '1', 09:13:31 INFO - 'PAGER': '/bin/cat', 09:13:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:31 INFO - 'PWD': '/builds/slave/test', 09:13:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:31 INFO - 'SHELL': '/bin/bash', 09:13:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:31 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:31 INFO - 'USER': 'cltbld', 09:13:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:31 INFO - 'XPC_FLAGS': '0x0', 09:13:31 INFO - 'XPC_SERVICE_NAME': '0', 09:13:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-E5zFBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-9V6Fjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-OABYdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-CiHv9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-mlKwfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-dICJuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-iGwtYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-yiWCWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-wWW2QK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-vg7t4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-ePB9Nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-o35g1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-PQrZcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-CaBz6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-uBzD5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-LV2sqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-kVF1Bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:33 INFO - Running setup.py install for manifestparser 09:13:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:33 INFO - Running setup.py install for mozcrash 09:13:34 INFO - Running setup.py install for mozdebug 09:13:34 INFO - Running setup.py install for mozdevice 09:13:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:13:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:34 INFO - Running setup.py install for mozfile 09:13:34 INFO - Running setup.py install for mozhttpd 09:13:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:34 INFO - Running setup.py install for mozinfo 09:13:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:34 INFO - Running setup.py install for mozInstall 09:13:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:13:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:13:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:13:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:13:34 INFO - Running setup.py install for mozleak 09:13:35 INFO - Running setup.py install for mozlog 09:13:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:13:35 INFO - Running setup.py install for moznetwork 09:13:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:13:35 INFO - Running setup.py install for mozprocess 09:13:35 INFO - Running setup.py install for mozprofile 09:13:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:13:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:13:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:13:35 INFO - Running setup.py install for mozrunner 09:13:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:13:35 INFO - Running setup.py install for mozscreenshot 09:13:36 INFO - Running setup.py install for moztest 09:13:36 INFO - Running setup.py install for mozversion 09:13:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:13:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:36 INFO - Cleaning up... 09:13:36 INFO - Return code: 0 09:13:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:36 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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:36 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 09:13:36 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 09:13:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:36 INFO - 'HOME': '/Users/cltbld', 09:13:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:36 INFO - 'LOGNAME': 'cltbld', 09:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:36 INFO - 'MOZ_NO_REMOTE': '1', 09:13:36 INFO - 'NO_EM_RESTART': '1', 09:13:36 INFO - 'PAGER': '/bin/cat', 09:13:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:36 INFO - 'PWD': '/builds/slave/test', 09:13:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:36 INFO - 'SHELL': '/bin/bash', 09:13:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:36 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:36 INFO - 'USER': 'cltbld', 09:13:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:36 INFO - 'XPC_FLAGS': '0x0', 09:13:36 INFO - 'XPC_SERVICE_NAME': '0', 09:13:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:36 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-6NGSFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:36 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)) 09:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:36 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Mv_oT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:36 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)) 09:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:36 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-DfIFA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:36 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)) 09:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:36 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-s9nFw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:37 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-NNXzIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:37 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-CL4Nya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:37 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-St8TwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:37 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-BhI5If-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:37 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Z2sCX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:37 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-EoExDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:37 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-mrqpW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:37 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-fEm6nX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 09:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-6XKKJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:38 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)) 09:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-YyUG28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:38 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)) 09:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-b4BE7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:38 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)) 09:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-BG38_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:38 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)) 09:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-jZ_rlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:38 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)) 09:13:38 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)) 09:13:38 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)) 09:13:38 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)) 09:13:38 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)) 09:13:38 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)) 09:13:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:38 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 09:13:38 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 09:13:38 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 09:13:38 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 09:13:38 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 09:13:38 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 09:13:41 INFO - Downloading blessings-1.6.tar.gz 09:13:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:13:41 INFO - Installing collected packages: blessings 09:13:41 INFO - Running setup.py install for blessings 09:13:41 INFO - Successfully installed blessings 09:13:41 INFO - Cleaning up... 09:13:41 INFO - Return code: 0 09:13:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:13:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:41 INFO - Reading from file tmpfile_stdout 09:13:41 INFO - Current package versions: 09:13:41 INFO - blessings == 1.6 09:13:41 INFO - blobuploader == 1.2.4 09:13:41 INFO - docopt == 0.6.1 09:13:41 INFO - functools32 == 3.2.3-2 09:13:41 INFO - jsonschema == 2.5.1 09:13:41 INFO - manifestparser == 1.1 09:13:41 INFO - mozInstall == 1.12 09:13:41 INFO - mozcrash == 1.0 09:13:41 INFO - mozdebug == 0.1 09:13:41 INFO - mozdevice == 0.49 09:13:41 INFO - mozfile == 1.2 09:13:41 INFO - mozhttpd == 0.7 09:13:41 INFO - mozinfo == 0.9 09:13:41 INFO - mozleak == 0.1 09:13:41 INFO - mozlog == 3.4 09:13:41 INFO - moznetwork == 0.27 09:13:41 INFO - mozprocess == 0.25 09:13:41 INFO - mozprofile == 0.28 09:13:41 INFO - mozrunner == 6.13 09:13:41 INFO - mozscreenshot == 0.1 09:13:41 INFO - mozsystemmonitor == 0.3 09:13:41 INFO - moztest == 0.8 09:13:41 INFO - mozversion == 1.4 09:13:41 INFO - psutil == 3.1.1 09:13:41 INFO - requests == 1.2.3 09:13:41 INFO - wsgiref == 0.1.2 09:13:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:41 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')]} 09:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:41 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')]} 09:13:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:41 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 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:41 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 09:13:41 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 09:13:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:41 INFO - 'HOME': '/Users/cltbld', 09:13:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:41 INFO - 'LOGNAME': 'cltbld', 09:13:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:41 INFO - 'MOZ_NO_REMOTE': '1', 09:13:41 INFO - 'NO_EM_RESTART': '1', 09:13:41 INFO - 'PAGER': '/bin/cat', 09:13:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:41 INFO - 'PWD': '/builds/slave/test', 09:13:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:41 INFO - 'SHELL': '/bin/bash', 09:13:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:41 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:41 INFO - 'USER': 'cltbld', 09:13:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:41 INFO - 'XPC_FLAGS': '0x0', 09:13:41 INFO - 'XPC_SERVICE_NAME': '0', 09:13:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:13:42 INFO - Cleaning up... 09:13:42 INFO - Return code: 0 09:13:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101390b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101393768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f876bd72720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, '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.SM115eF9EY/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.SAKfODYHSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 09:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:13:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:13:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:42 INFO - 'HOME': '/Users/cltbld', 09:13:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:42 INFO - 'LOGNAME': 'cltbld', 09:13:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:42 INFO - 'MOZ_NO_REMOTE': '1', 09:13:42 INFO - 'NO_EM_RESTART': '1', 09:13:42 INFO - 'PAGER': '/bin/cat', 09:13:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:42 INFO - 'PWD': '/builds/slave/test', 09:13:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:42 INFO - 'SHELL': '/bin/bash', 09:13:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:13:42 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:13:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:42 INFO - 'USER': 'cltbld', 09:13:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:42 INFO - 'XPC_FLAGS': '0x0', 09:13:42 INFO - 'XPC_SERVICE_NAME': '0', 09:13:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:13:42 INFO - Cleaning up... 09:13:42 INFO - Return code: 0 09:13:42 INFO - Running post-action listener: _resource_record_post_action 09:13:42 INFO - Running post-action listener: _start_resource_monitoring 09:13:42 INFO - Starting resource monitoring. 09:13:42 INFO - [mozharness: 2017-03-11 17:13:42.333730Z] Finished create-virtualenv step (success) 09:13:42 INFO - [mozharness: 2017-03-11 17:13:42.334702Z] Running install step. 09:13:42 INFO - Running pre-action listener: _resource_record_pre_action 09:13:42 INFO - Running main action method: install 09:13:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:42 INFO - Reading from file tmpfile_stdout 09:13:42 INFO - Detecting whether we're running mozinstall >=1.0... 09:13:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:13:42 INFO - Reading from file tmpfile_stdout 09:13:42 INFO - Output received: 09:13:42 INFO - Usage: mozinstall [options] installer 09:13:42 INFO - Options: 09:13:42 INFO - -h, --help show this help message and exit 09:13:42 INFO - -d DEST, --destination=DEST 09:13:42 INFO - Directory to install application into. [default: 09:13:42 INFO - "/builds/slave/test"] 09:13:42 INFO - --app=APP Application being installed. [default: firefox] 09:13:42 INFO - mkdir: /builds/slave/test/build/application 09:13:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:14:02 INFO - Reading from file tmpfile_stdout 09:14:02 INFO - Output received: 09:14:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:14:02 INFO - Running post-action listener: _resource_record_post_action 09:14:02 INFO - [mozharness: 2017-03-11 17:14:02.557650Z] Finished install step (success) 09:14:02 INFO - [mozharness: 2017-03-11 17:14:02.557765Z] Running run-tests step. 09:14:02 INFO - Running pre-action listener: _resource_record_pre_action 09:14:02 INFO - Running main action method: run_tests 09:14:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:02 INFO - 2017-03-11 09:14:02.572 screenresolution[1633:7416] starting screenresolution argv=screenresolution get 09:14:02 INFO - 2017-03-11 09:14:02.574 screenresolution[1633:7416] Display 0: 1600x1200x32@60 09:14:02 INFO - 2017-03-11 09:14:02.580 screenresolution[1634:7419] starting screenresolution argv=screenresolution list 09:14:02 INFO - Available Modes on Display 0 09:14:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:02 INFO - Intel Iris: 09:14:02 INFO - Chipset Model: Intel Iris 09:14:02 INFO - Type: GPU 09:14:02 INFO - Bus: Built-In 09:14:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:02 INFO - Vendor: Intel (0x8086) 09:14:02 INFO - Device ID: 0x0a2e 09:14:02 INFO - Revision ID: 0x0009 09:14:02 INFO - Displays: 09:14:02 INFO - Display: 09:14:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:02 INFO - Display Serial Number: 1600x1200 60 09:14:02 INFO - Main Display: Yes 09:14:02 INFO - Mirror: Off 09:14:02 INFO - Online: Yes 09:14:02 INFO - Rotation: Supported 09:14:02 INFO - Return code: 0 09:14:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:14:02 INFO - Python 2.7.3 09:14:02 INFO - Return code: 0 09:14:02 INFO - grabbing minidump binary from tooltool 09:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:02 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 0x10116ed30>, '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 0x101095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101163be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:14:02 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 09:14:02 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 09:14:02 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 09:14:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:14:02 INFO - Return code: 0 09:14:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:14:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:14:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:14:02 INFO - ENV: RUST_BACKTRACE is now 1 09:14:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:14:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:14:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0371', '--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 09:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0371 --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 09:14:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM115eF9EY/Render', 09:14:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:02 INFO - 'HOME': '/Users/cltbld', 09:14:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:02 INFO - 'LOGNAME': 'cltbld', 09:14:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:14:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:02 INFO - 'MOZ_NO_REMOTE': '1', 09:14:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:14:02 INFO - 'NO_EM_RESTART': '1', 09:14:02 INFO - 'PAGER': '/bin/cat', 09:14:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:02 INFO - 'PWD': '/builds/slave/test', 09:14:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:14:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:02 INFO - 'RUST_BACKTRACE': '1', 09:14:02 INFO - 'SHELL': '/bin/bash', 09:14:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SAKfODYHSB/Listeners', 09:14:02 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 09:14:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:02 INFO - 'USER': 'cltbld', 09:14:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:02 INFO - 'XPC_FLAGS': '0x0', 09:14:02 INFO - 'XPC_SERVICE_NAME': '0', 09:14:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0371', '--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 09:14:03 INFO - mozversion application_buildid: 20170311083036 09:14:03 INFO - mozversion application_changeset: 83ac51cb9cfdfc5985d68a6ddc30eb86a9ceef67 09:14:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:14:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:14:03 INFO - mozversion application_name: Firefox 09:14:03 INFO - mozversion application_remotingname: firefox-dev 09:14:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:14:03 INFO - mozversion application_vendor: Mozilla 09:14:03 INFO - mozversion application_version: 54.0a2 09:14:03 INFO - mozversion platform_buildid: 20170311083036 09:14:03 INFO - mozversion platform_changeset: 83ac51cb9cfdfc5985d68a6ddc30eb86a9ceef67 09:14:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:14:03 INFO - mozversion platform_version: 54.0a2 09:14:03 INFO - using testdate: 1489252443 09:14:03 INFO - actual date: 1489252443 09:14:03 INFO - starting webserver on 'localhost:49251' 09:14:03 INFO - SUITE-START | Running 2 tests 09:14:03 INFO - TEST-START | dromaeo_css 09:14:03 INFO - operating with platform_type : mac_ 09:14:03 INFO - Initialising browser for dromaeo_css test... 09:14:03 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpGWB7Ov/profile http://localhost:49251/getInfo.html) 09:14:06 INFO - PROCESS | 1644 | 2017-03-11 09:14:06.008 plugin-container[1646:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4953, name = 'com.apple.tsm.portname' 09:14:06 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:14:06 INFO - PROCESS | 1644 | 2017-03-11 09:14:06.010 plugin-container[1646:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 09:14:06 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:14:06 INFO - PROCESS | 1644 | 2017-03-11 09:14:06.011 plugin-container[1646:7593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:14:06 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 09:14:06 INFO - PROCESS | 1644 | colorDepth:24 09:14:06 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 09:14:06 INFO - PROCESS | 1644 | __metrics 09:14:06 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:14:06 INFO - TEST-INFO | 1644: exit 0 09:14:06 INFO - Browser initialized. 09:14:06 INFO - Running cycle 1/1 for dromaeo_css test... 09:14:06 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpGWB7Ov/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:14:09 INFO - PROCESS | 1647 | 2017-03-11 09:14:09.413 plugin-container[1648:7727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:14:09 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:14:09 INFO - PROCESS | 1647 | 2017-03-11 09:14:09.414 plugin-container[1648:7727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 09:14:09 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:14:09 INFO - PROCESS | 1647 | 2017-03-11 09:14:09.414 plugin-container[1648:7727] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:14:18 INFO - PROCESS | 1647 | RSS: Main: 220172288 09:14:18 INFO - PROCESS | 1647 | 09:17:22 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 09:17:24 INFO - PROCESS | 1647 | RSS: Main: 238301184 09:17:24 INFO - PROCESS | 1647 | 09:19:07 INFO - PROCESS | 1647 | ************************* 09:19:07 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 09:19:07 INFO - PROCESS | 1647 | 09:19:07 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:19:07 INFO - PROCESS | 1647 | Full stack: 09:19:07 INFO - PROCESS | 1647 | ************************* 09:20:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 09:20:27 INFO - PROCESS | 1647 | RSS: Main: 248115200 09:20:27 INFO - PROCESS | 1647 | 09:23:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 09:23:29 INFO - PROCESS | 1647 | RSS: Main: 254447616 09:23:29 INFO - PROCESS | 1647 | 09:26:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 09:26:32 INFO - PROCESS | 1647 | RSS: Main: 250753024 09:26:32 INFO - PROCESS | 1647 | 09:29:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 09:29:36 INFO - PROCESS | 1647 | RSS: Main: 256135168 09:29:36 INFO - PROCESS | 1647 | 09:32:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 09:32:39 INFO - PROCESS | 1647 | RSS: Main: 252461056 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - PROCESS | 1647 | __start_tp_report 09:32:39 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 09:32:39 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 09:32:39 INFO - PROCESS | 1647 | |i|pagename|runs| 09:32:39 INFO - PROCESS | 1647 | |0;dojo.html;2918.693537178596;3413.214960559071;3441.6730410611;3468.1849765305155;3473.687368136867;5962.79130230442;5994.520438364932;6012.3987601239905;6034.336223015464;6090.5736598438125;5981.342052374246;5982.700864956757;6053.485453736426;6057.09143628456;6061.45446909778;5404.189371594253;5444.91832622511;5483.561315094783;5501.9174712379145;5543.722813859311;5506.256655351516;5514.062609356419;5536.944630553703;5550.028744969634;5553.389127196006;5308.362996440433;5323.973380133113;5454.400015998237;5501.037318469272;5518.91721624176;5517.751701173457;5524.751386187631;5534.640248383842;5542.057850165462;5579.163125531203;5326.813561525367;5444.727763611843;5458.590605704588;5462.071447853875;5488.780448782044;5737.3688894221605;5768.134779783024;5970.462658360769;6038.788642397539;6148.723307451175;6291.874162516724;6293.779717709858;6331.55679102463;6342.714577844008;6349.650769207692;6491;6523.380278873499;6524.347565243438;6529.608223506557;6542.051402546652;6126.693665316761;6128.3258841527395;6181.907271390933;6191.102290167945;6198.008318669036;5697.829065128053;6303.212098487689;6359.523035772336;6365.745370185234;6478.190226221723;6309.747610095636;6313.494920406357;6331.303556608769;6334.366563343721;6340.112384266206;6376.7130479128555;6451.838704032343;6457.806265812033;6464.709088091047;6478.222613286436;6210.968945155336;6264.718087686065;6304.180456540712;6324.557280990379;6329.620222786648;6121.757838957688;6243.219597550306;6257.374262573706;6271.592346497463;6276.905846412307;5174.172132458863;5182.585393168538;5184.429712731597;5217.878156196436;5225;4582.427196600425;4618.03021365517;4624.28323609841;4643.210654188729;4687.32033855096;5827.096799996008;5893.528517718572;5985.970070149621;5996.670183139882;6082.635041897412;6057.57596968208;6478.481721462272;6520.380563846427;6525.314841941623;6544.083828264047;6009.2188015557695;6033.5173186145;6074.210352654215;6086.695665216722;6095.664738439428;4649.349091127245;4880.194767863263;4902.534259245366;5575.080111781511;5722.685252311079;6534.542582019213;6570.77002304917;6653.068570400051;6666.300038495888;6687.364700353459;6420.6789660517725;6453.838654033687;6458.967705161444;6465.094886715958;6547.279799222177;5281.920771188512;5609.962157000968;6339;6598.37315455031;6636.170478690164;6274.341194174728;6308.0853276273765;6308.716107775253;6401;6413.551051426355;6449.548531602931;6456.225252969673;6462.063000864942;6472.190976127984;6482.189726284215;6247.406496382836;6250.812475625738;6286.842828929313;6306.747730090745;6310.432061114522;4894.583960363267;6137.346341658241;6163.3528479509005;6183.319834818077;6236.282827474898;5726.713664316684;6177.96911015442;6256.593321434092;6271;6293.339199383999;6145.262568491809;6218.906716399167;6267.1539342188225;6326.33573474797;6351.3331100235655;6150.954337762681;6206.131141640084;6208.844778880564;6211.937880621136;6217.844553886189 09:32:39 INFO - PROCESS | 1647 | |1;ext.html;743.8326376565274;808.2604416958483;823.6417158536035;958.6117622362946;963.7253382785907;39145.60854391455;39952.40071398928;41578.376324355086;41722.7913860431;41763.58236417635;39790.20419591606;39991.400128998015;39998.60001399985;40107.79946100265;40137.59862401375;7862.0958589762195;7988.4408091433625;10618.840717389245;10910.672679819618;11082;10820.458977051156;10906.072983796386;10907.29102608332;10912.290701104403;10935.726606834813;10784.5686172553;10833.67498975032;10879.456027198648;10895.782084358309;10898.128149748001;10854.99999999996;10879.782404351949;10910.727231819188;10921.399323037229;10926.125909927187;10585.84121238182;10643.733906652318;10673.1995100367;10673.306235094695;10688;35029;35320.646793531996;35406.64593354059;35413.64586354129;35511.64488355135;3156.7474602031784;3723.1995121049104;3751.531058617673;3769.3026790043655;3952.0910190656023;25069;25085.62371564428;25267.62098568523;26678.5998210027;26785.330366740793;3086.965866434747;3087.6294844618565;3099.643540992792;3106.0992312229428;3115.750739940799;345592;349139.2543037294;349949.99999999744;350235;350417;45210.32184517235;45497.31754023692;45680.77159614181;45753.77123114363;45788.5421145791;46984;47432;47436;47467.28799068017;47583.810404739466;13900.652483687989;14280.42878284857;14292.571222863331;14293.142411455347;14298.785518217235;12068.215565988183;12366.319852408204;12387.009039276836;12387.380630968415;12397.504099836086;11595.130365222642;12021.398930053638;12078.396080195955;12102;12138.878611213775;5760.683162426023;5779.450952159538;5791.536677065825;5816.41835816415;5843.1235314703135;1109.7281166114353;1110.5280255891214;1135.846660700795;1165.6736113888126;1193.1588230297793;21117.944102794798;22334;26769;26930.192094237205;27078.458430831273;1230.8215308780177;1237.7882469625588;1241.5716577780652;1292.2956988441133;1299.1036185032485;12020.699482512833;13048.760367765048;13815.930920345534;13896.930515347358;14143.080699754482;472098;473665;473709;474231;485328;46913;47743.522564774605;59546;60040;60160.39839601548;1448.4930274403873;1468.6622076922367;1471.374665767066;1473.7715654073636;1486.3534362552325;25973.740262597134;30216.69783302139;31587.84206078955;31665;32024.839875800473;50168;50465.74767126141;50549;50565.241521376476;50614.2407863875;457494;460163.6991815019;460674;461074;461658.6917065393;30223.546646800754;30909.845450772602;31968.6803131975;33409.33181336311;33447.33105337928;40016;40248.798756006036;40668.1866362677;41006.38490422706;41034.563790267195;32509.349813003133;32729.34541309208;32945.505817412275;33278;33315.00054998354;32187.51718724174;32692.855750048635;33620.66379336175;33670;33835;28097.157085287476;33256.66743332632;33759.4936075964;34008.65991340055;34104.63581456714;25747.18797559053;26973.460530789667;28381.290467738472;29122;29545.409091818474;20838.479038024172;22852.4286892829;22977.655335169653;23053.423664408525;23129.65305520385 09:32:39 INFO - PROCESS | 1647 | |2;jquery.html;389.9337112690842;430.4318299844205;433.2331772762211;440.07364497732254;444.0763212517963;18931.432057038277;20138.89930550347;21208.045637946292;21335.35993920181;21478.89260553699;18632.068396580184;19311.227550897947;19646;20063.1974721011;21631.567368652617;6161.661108639034;6480.513961452886;6519.869602607945;6521.869562608768;6523.706433210493;6388.968055159718;6479.676016199195;6975.825604359881;7340.063974410252;7397.075365579302;7216.855662886739;7439.888401673979;7463.925360746404;7486.2139475355125;7506.512076715024;7407.407407407394;7492.325690687837;7540.321371076575;7540.396768258526;7549.584772837465;7058.505904586681;7333.669984850694;7335.633218339061;7342.229065948105;7394.778156655255;25607.61588576173;25817;26089;26230.34424139412;26420.867895660398;2785.234060633322;2848.1170837040586;2861.842598657073;2863.527517959534;2872.224499385154;15853.01837270339;16071.19644017806;16082.919585402115;16160;16744.24650890713;2079.99120426593;2084.9687254691194;2105.0632468551503;2109.483176621722;2141.4004078857943;159123.43067992566;159763;162887;166555;170741;14212.255046608192;20690.34477241847;20764.68852967207;21220.787792121882;21317.401194910133;31413.842930785657;31491.527627085612;31984.680153198635;32050;32174.35651286961;8959.74563561102;10273.075423211947;10335.94832025835;10600.893991059991;10806.405647689295;7098.396636285974;8765.211130998241;8925.464472131693;8953.462792232487;9079.773005674911;9669.758256043515;9803.509824508747;9877.506124693737;9914.008599140028;9958.900410995797;3653.4472849039053;3669.449582562637;3882.5923278055398;3958.9618589039596;4339.1972485090355;766.865798485274;932.9953350233205;950.6150009246267;955.3742298794301;968.3763060665085;17047.48857534276;21053.368398948056;21089.156433742784;21105.89447052755;21209.681854771883;883.726044926075;990.9108180263811;993.8956409577034;1028.5577201803296;1035.9430231337199;10087.04173103554;10782.946085269523;10801.729956750987;10820.458977051116;10823.56705731762;768811;771553;777215;778612;788647;130966;159599;163242;164885;165865;1140.4411838199223;1174.512577280436;1176.0681048545125;1199.0287866827898;1200.33981310279;17930.820691792916;18028.819711802713;18363.54091147732;18492;18532.629347412705;65779.67110164418;66703.99944000941;67085.32286692872;68132.659336703;68371.31628683649;73453;89623.55188224016;93170;94699;94819.52590237005;8769.38614296993;10149.832769231632;11319.264247823865;11795.587154449553;11935.343556104497;13148.40832162574;13247.337633118499;13257.553036210847;13283.734325313633;13627.318634068455;10539.736506587396;11115.277506962022;11151.386673733023;11312.6040588579;11433.428328583705;10851.45742712877;11030.117590592894;11152.721181970192;11338.716532086764;11438.084953203557;11326.716832079264;11413.687659989828;11480.196386252883;11618.360990145624;11667.474963626826;11467.96788289058;11692;11856.762864742484;11895.940520297343;11904.226225295328;11136.665900023012;11229.27009744364;11361.431928403712;11392.544298227978;11458.77082458363 09:32:39 INFO - PROCESS | 1647 | |3;mootools.html;1476;1529.3270960777256;1554.13745371319;1555.2301610702698;1578.084710867697;9419.62321507139;9625.278104142182;10547.73630659233;11179.161562882797;11189.32864028158;10496.160307175427;10810.135189184846;10820.72948176294;10833.512491937861;10856.891431085707;2340.8010319122855;2361.315218586608;2378.0250097460043;2379.1553998330605;2381.761823817613;2321.8839058047115;2358.056777289081;2359.6460530920413;2362.988185059081;2373.1693907132453;2320.825938054644;2329.4176455886027;2338.6024375856146;2345.296411076679;2350.764923507644;2154.34292540775;2170.5441857210003;2176.793204645564;2188.737351517812;2193.2762188477764;2252.8197744180425;2304.481491664379;2304.5275718477674;2310.4570425949887;2327;10937.781244375148;10988;11011.669649910515;11012.119030477543;11044.392558409285;5407.702576358299;5533.253010843525;5561.054620714488;5561.416051314634;5622.971885140548;3169.524571314298;3363.899083027513;3381.340638575479;3385.5768028996376;3501.719862411001;1196.635026316973;1229.51434183497;1231.3289257354763;1251.7684228417681;1252.5616034387983;15398.230088495531;15467;15589;15614.687706245924;15715.764263536055;4487.169873573399;4691;4728.05438912219;4788.186008378584;4798.328234047236;12865.03512236586;12915.483380664857;12921.870781292067;12922.353882305848;12925;5734.770609175586;5735.4551317624755;5741.5119714824705;5744.310682718101;5766.279215098113;4364.1708075466195;4786.832460863922;5024.648274620815;5070.062038522885;5161.664491808096;3853.421986702017;3932.9016774580864;3941.1723538056754;3964.4647972523917;4000.9199816004098;915.231205787142;1082.1667316166508;1088.4394536813547;1099.6700989703056;1102.2559772153763;646.5581752691525;668.5954997226705;753.4349238071446;797.7447216890539;814.0882211922684;6372.6495042773095;6580.440662543738;6678.866422671519;6745.123133992648;6761.72953081881;890.701614958983;899.2221728205055;973.2943615878573;981.3964411886781;1004.8894621591619;4037.717359784787;4081.714280000431;4091.6112969267874;4119.8352065917625;4198.6221240088535;13244.801327980089;13901.79147312791;13944.023918325824;13946.372413241428;13986.090904091201;13860.722785544434;13861.86138138626;13862.96027797899;13882.167069975834;13917.373718182504;9567.760805979906;9705.417674939526;10258.487075646188;10412.010858968235;10442.530086146142;6494.512911531749;6561.1470508833545;6592.604443733391;6604.339566043357;6662.06731057662;11564.248323858923;11711.1802173847;12380;12395.442205100611;12501.687457813627;12130.211536250115;12335.568255111028;12533.498660053496;12668.049896257633;12704.618861434172;9217.170454659114;9326.020767819282;9330.95334523323;9340.392874463138;9463.621455141993;10159.695209143738;10321.793564128826;10378.740531486774;10386.688399348031;10410.947945260225;8407.95796021016;9184.632614695338;9254.907450925673;9257.583408746488;9326.766830829283;8882.955585222033;8972.013078561484;9017.278617710435;9025.684101056431;9026.638934442548;7758.573278469513;7839.804004899923;7859.960700196462;7929.682812687659;7939.523628582304;5856.970715146397;5897.705114744332;5925.288965324189;6050.546209034252;6109.2974307955155;5884.764609415575;5906.143609176731;6020.729067191899;6052.394760523913;6198.535109866688 09:32:39 INFO - PROCESS | 1647 | |4;prototype.html;333.99999999999994;365.9835307411166;374.6964958383711;389.21961467258154;389.777826638816;4564.748938808364;5058.140116180244;5292.470752924711;5316.6544174628625;5527.226188333631;4677.719336839792;5164.767585458654;5240.292560504331;5314.256004159421;5391.32608423947;4343.804528796196;4465.24090904547;4604.493505714368;4653.6044436222965;4709.152352576535;3822.7132965027595;3926.391409331558;3993.4009898515164;4494.651604518648;4728.598069164125;3727.700984628596;4385.995593018776;4386.627136693385;4452.953555914358;4454.287314029756;4447;4471.3293006049025;4474;4475.507694153641;4500.369948207253;3416.8120753358558;3610.4403817408083;4078.8572399966156;4115.773632450214;4140.8136633851545;4599.954000459986;4715.221988371914;4810.584485015643;4983.277424773388;5074.619403544749;4217.5993280638595;5118.07874582575;5126.5898728101665;5132.74336283188;5189.481051894781;5247.737613119329;5383.1697222944385;5740.454656807597;5780.942190578083;5784;3822.8400953461205;3915.6280153385383;4461.866144015685;4510.503844577127;4520.638348932078;4965.751712414365;5442.809501667462;5506;5907.999999999957;6066.272047354346;4275.1022285319805;4331.133773245364;4411.941014266882;4561.931571026437;4633.791479383436;4618.776024353549;4659.8136074557315;4700.764961751967;4703.270992996023;4739.1706451371565;3980.919557453575;4178.91642167155;4384.583464570861;5127.717975511308;5135.435102138762;4997.600191984705;5102.719350435687;5141.974290128525;5697.259356283656;5806.4193580641595;1438.719449707318;1556.3618916244511;1602.796155286481;1631.8939268947481;1632.4531282020419;981.6858605246252;1161.3960740415105;1209.4799236328463;1213.2962881528692;1235.7590269897285;4618.976905115453;4767.642426818003;4996.025774973918;5055.814836652885;5398.730063496888;5267.420583735786;5862.70686465675;5978.521718262528;6020.819375418744;6023.518118550505;4684.203685373494;4886.413630364379;4947.455779864212;5151.304573882478;5653.575981801381;2518.6725725655547;2905.7675385969496;2906.9273268168463;2950.749186319144;2970.5247160454223;5548.72256387179;5645.915311270334;6009.088372463851;6093.4515893568905;6178.4668286002525;5102.821401250939;5656.575756818255;5880.382559831156;6017.398260173947;6124.5100391968535;5609.9902017636405;5945.851353716192;6063.787767428119;6099.420555047263;6110.869489144522;5205.973970130125;5566.554675626021;5963.642181469126;6009.54928380382;6031.69841507914;4984.7258400788305;5483.2049352844415;5549.250851135045;5772.278465191851;5919.253820122988;5910.970445147746;5915.319738230159;6119.663418512025;6164.229471316085;6203.689815509297;4144.093716890265;4178.35235538492;4402.229609818205;4600.792964316681;4628.884277893079;5107.399283505046;5111.744412779271;5468.890622187454;5838.32859221195;5929.962256605163;5077.121465057952;5117.462666420121;5187.377514698261;5692.288463942007;5724.685142317213;4968.826091086795;5528.530074943675;5618.157276408571;5636.4781508993165;5751.884962300647;4560.931586026146;4611.884702882455;4854.90290194187;5351.438098999704;5613.101903695552;5221.483416844479;5275.709835958905;5726.599138060463;5727.599068065196;5793.217915581343;5055.620828437955;5534.703098214196;5559.804642001989;5574.331080270394;5604.907043126551 09:32:39 INFO - PROCESS | 1647 | |5;yui.html;730.3718801830428;741.6625435426878;758.188738050286;820.6758330459469;831.0318478972;15102.773458398118;16189.514314570568;16192;16195.514134575982;16208.351665933364;14430.927845360784;15617.297221625025;15637.530874073767;15655.373785048585;15666.216689165552;922.7693076730817;949.1362859797586;949.577438040069;949.5821838391121;953.3088510829663;914.9588268527932;945.4563625915092;953.0279115302387;961.7595601099725;962.8459446488563;1007.3905287301187;1007.7128018514725;1011.6206422591529;1013.3413281367134;1014.8883622801486;985.4629227071227;1002.5739060899125;1004.0712341084504;1005.7887843552862;1006.2704539209014;735.2867718313281;750.8535835512077;754.3587950242305;756.0775853458772;758.2720588235301;16051.759223611654;16468;16475.505734827973;16960.491185264462;17236.56908577283;11141.610043648434;12922.966162707055;14081.078924267771;14204.360803763751;14207.573772786833;11437.42812859362;11666.791557387287;11880.108991825562;11972;12456.875431245753;3597.3524765542193;3731.328360895038;3740.9251814963823;3748.606396328401;3757.9853439571434;107571.46214268879;108497.45751271272;108548.45725771399;108995;109397;10571.15430765537;10649.148068154529;10733.463326833627;11249.83125253122;11430.714232144262;18310.81689183118;22444.10223591071;22800.087996480288;22835.885820571122;22862.42843928882;7556.773296801105;7609.124950630638;7610.8097297568;7628.54228746277;7868.84262314762;4181.393697913785;4224.239636865394;4302.870913872589;4490.326451032306;4506.504284528699;3465.428204346305;3504.684578387906;3514.38498262803;3545.3263879862743;3553.378158822196;629.8079085878759;630.0304613784357;641.4195153386192;644.1127496435489;650.7884937395365;652.6573548886778;677.5934439336359;685.5886468119089;691.865086308165;720.2797202797203;9983.700488985342;10119.24105692076;10154.628657313697;10399.740006499747;10525.684229473129;692.5117791956659;706.6925887239067;710.733474946895;710.7903168565285;718.9460790440634;4745.786439610226;4951.851444456672;4969.254611808257;4973.2540118981715;4975.651704380659;87423;94999.05000949903;95488.52255738676;96951.54572681415;109406.45296773466;77321;77330.6133469329;77498.22501775023;84604.15395846085;93537;67183;67895.98156027663;72014.27985720181;73470.63264683643;76280.47439051188;11070.225084244026;11138.27601205919;11382.317060976367;11480;11490.310581365145;43596;47984.28023579579;48962.51037489722;48967;50268.74865625649;58144.70927645504;61792.38207617866;62313.75372492615;62314.68842655758;62895;12574.434150463068;12689;12731.490740370282;12843.165194262343;13259.037431030412;14102.36539355711;14218.92890535582;14843.40626374933;15417.614559636098;15505.069695818287;13339.333033348488;13550.796738049134;13701.785255333094;13732.725345493234;14204.807735261558;13318.733625327633;13717.588472345846;13810.93094534521;13818.37817298244;14034.78947815804;12906.67733306675;13281.335933203109;13381.19712817234;13910.930445347709;14005.089669171472;10820.56717731282;11149.888501114885;11247.775044499227;11415.37215453158;11418.543258269576;9561.165010078375;9888.456134912649;10183;11167.609133680282;11406.258593191416 09:32:39 INFO - PROCESS | 1647 | __end_tp_report 09:32:39 INFO - PROCESS | 1647 | __start_cc_report 09:32:39 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,422 09:32:39 INFO - PROCESS | 1647 | __end_cc_report 09:32:39 INFO - PROCESS | 1647 | __startTimestamp1489253559014__endTimestamp 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - PROCESS | 1647 | ------- Summary: start ------- 09:32:39 INFO - PROCESS | 1647 | Number of tests: 6 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5923.80 Median:6163.35 stddev:670.71 (10.9%) stddev-sans-first:629.98 09:32:39 INFO - PROCESS | 1647 | Values: 2918.7 3413.2 3441.7 3468.2 3473.7 5962.8 5994.5 6012.4 6034.3 6090.6 5981.3 5982.7 6053.5 6057.1 6061.5 5404.2 5444.9 5483.6 5501.9 5543.7 5506.3 5514.1 5536.9 5550.0 5553.4 5308.4 5324.0 5454.4 5501.0 5518.9 5517.8 5524.8 5534.6 5542.1 5579.2 5326.8 5444.7 5458.6 5462.1 5488.8 5737.4 5768.1 5970.5 6038.8 6148.7 6291.9 6293.8 6331.6 6342.7 6349.7 6491.0 6523.4 6524.3 6529.6 6542.1 6126.7 6128.3 6181.9 6191.1 6198.0 5697.8 6303.2 6359.5 6365.7 6478.2 6309.7 6313.5 6331.3 6334.4 6340.1 6376.7 6451.8 6457.8 6464.7 6478.2 6211.0 6264.7 6304.2 6324.6 6329.6 6121.8 6243.2 6257.4 6271.6 6276.9 5174.2 5182.6 5184.4 5217.9 5225.0 4582.4 4618.0 4624.3 4643.2 4687.3 5827.1 5893.5 5986.0 5996.7 6082.6 6057.6 6478.5 6520.4 6525.3 6544.1 6009.2 6033.5 6074.2 6086.7 6095.7 4649.3 4880.2 4902.5 5575.1 5722.7 6534.5 6570.8 6653.1 6666.3 6687.4 6420.7 6453.8 6459.0 6465.1 6547.3 5281.9 5610.0 6339.0 6598.4 6636.2 6274.3 6308.1 6308.7 6401.0 6413.6 6449.5 6456.2 6462.1 6472.2 6482.2 6247.4 6250.8 6286.8 6306.7 6310.4 4894.6 6137.3 6163.4 6183.3 6236.3 5726.7 6178.0 6256.6 6271.0 6293.3 6145.3 6218.9 6267.2 6326.3 6351.3 6151.0 6206.1 6208.8 6211.9 6217.8 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:56239.30 Median:31816.84 stddev:114747.97 (360.7%) stddev-sans-first:114994.22 09:32:39 INFO - PROCESS | 1647 | Values: 743.8 808.3 823.6 958.6 963.7 39145.6 39952.4 41578.4 41722.8 41763.6 39790.2 39991.4 39998.6 40107.8 40137.6 7862.1 7988.4 10618.8 10910.7 11082.0 10820.5 10906.1 10907.3 10912.3 10935.7 10784.6 10833.7 10879.5 10895.8 10898.1 10855.0 10879.8 10910.7 10921.4 10926.1 10585.8 10643.7 10673.2 10673.3 10688.0 35029.0 35320.6 35406.6 35413.6 35511.6 3156.7 3723.2 3751.5 3769.3 3952.1 25069.0 25085.6 25267.6 26678.6 26785.3 3087.0 3087.6 3099.6 3106.1 3115.8 345592.0 349139.3 349950.0 350235.0 350417.0 45210.3 45497.3 45680.8 45753.8 45788.5 46984.0 47432.0 47436.0 47467.3 47583.8 13900.7 14280.4 14292.6 14293.1 14298.8 12068.2 12366.3 12387.0 12387.4 12397.5 11595.1 12021.4 12078.4 12102.0 12138.9 5760.7 5779.5 5791.5 5816.4 5843.1 1109.7 1110.5 1135.8 1165.7 1193.2 21117.9 22334.0 26769.0 26930.2 27078.5 1230.8 1237.8 1241.6 1292.3 1299.1 12020.7 13048.8 13815.9 13896.9 14143.1 472098.0 473665.0 473709.0 474231.0 485328.0 46913.0 47743.5 59546.0 60040.0 60160.4 1448.5 1468.7 1471.4 1473.8 1486.4 25973.7 30216.7 31587.8 31665.0 32024.8 50168.0 50465.7 50549.0 50565.2 50614.2 457494.0 460163.7 460674.0 461074.0 461658.7 30223.5 30909.8 31968.7 33409.3 33447.3 40016.0 40248.8 40668.2 41006.4 41034.6 32509.3 32729.3 32945.5 33278.0 33315.0 32187.5 32692.9 33620.7 33670.0 33835.0 28097.2 33256.7 33759.5 34008.7 34104.6 25747.2 26973.5 28381.3 29122.0 29545.4 20838.5 22852.4 22977.7 23053.4 23129.7 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:44304.19 Median:21326.38 stddev:129758.58 (608.4%) stddev-sans-first:130080.69 09:32:39 INFO - PROCESS | 1647 | Values: 389.9 430.4 433.2 440.1 444.1 18931.4 20138.9 21208.0 21335.4 21478.9 18632.1 19311.2 19646.0 20063.2 21631.6 6161.7 6480.5 6519.9 6521.9 6523.7 6389.0 6479.7 6975.8 7340.1 7397.1 7216.9 7439.9 7463.9 7486.2 7506.5 7407.4 7492.3 7540.3 7540.4 7549.6 7058.5 7333.7 7335.6 7342.2 7394.8 25607.6 25817.0 26089.0 26230.3 26420.9 2785.2 2848.1 2861.8 2863.5 2872.2 15853.0 16071.2 16082.9 16160.0 16744.2 2080.0 2085.0 2105.1 2109.5 2141.4 159123.4 159763.0 162887.0 166555.0 170741.0 14212.3 20690.3 20764.7 21220.8 21317.4 31413.8 31491.5 31984.7 32050.0 32174.4 8959.7 10273.1 10335.9 10600.9 10806.4 7098.4 8765.2 8925.5 8953.5 9079.8 9669.8 9803.5 9877.5 9914.0 9958.9 3653.4 3669.4 3882.6 3959.0 4339.2 766.9 933.0 950.6 955.4 968.4 17047.5 21053.4 21089.2 21105.9 21209.7 883.7 990.9 993.9 1028.6 1035.9 10087.0 10782.9 10801.7 10820.5 10823.6 768811.0 771553.0 777215.0 778612.0 788647.0 130966.0 159599.0 163242.0 164885.0 165865.0 1140.4 1174.5 1176.1 1199.0 1200.3 17930.8 18028.8 18363.5 18492.0 18532.6 65779.7 66704.0 67085.3 68132.7 68371.3 73453.0 89623.6 93170.0 94699.0 94819.5 8769.4 10149.8 11319.3 11795.6 11935.3 13148.4 13247.3 13257.6 13283.7 13627.3 10539.7 11115.3 11151.4 11312.6 11433.4 10851.5 11030.1 11152.7 11338.7 11438.1 11326.7 11413.7 11480.2 11618.4 11667.5 11468.0 11692.0 11856.8 11895.9 11904.2 11136.7 11229.3 11361.4 11392.5 11458.8 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:6752.18 Median:2775.64 stddev:4309.58 (155.3%) stddev-sans-first:4303.43 09:32:39 INFO - PROCESS | 1647 | Values: 1476.0 1529.3 1554.1 1555.2 1578.1 9419.6 9625.3 10547.7 11179.2 11189.3 10496.2 10810.1 10820.7 10833.5 10856.9 2340.8 2361.3 2378.0 2379.2 2381.8 2321.9 2358.1 2359.6 2363.0 2373.2 2320.8 2329.4 2338.6 2345.3 2350.8 2154.3 2170.5 2176.8 2188.7 2193.3 2252.8 2304.5 2304.5 2310.5 2327.0 10937.8 10988.0 11011.7 11012.1 11044.4 5407.7 5533.3 5561.1 5561.4 5623.0 3169.5 3363.9 3381.3 3385.6 3501.7 1196.6 1229.5 1231.3 1251.8 1252.6 15398.2 15467.0 15589.0 15614.7 15715.8 4487.2 4691.0 4728.1 4788.2 4798.3 12865.0 12915.5 12921.9 12922.4 12925.0 5734.8 5735.5 5741.5 5744.3 5766.3 4364.2 4786.8 5024.6 5070.1 5161.7 3853.4 3932.9 3941.2 3964.5 4000.9 915.2 1082.2 1088.4 1099.7 1102.3 646.6 668.6 753.4 797.7 814.1 6372.6 6580.4 6678.9 6745.1 6761.7 890.7 899.2 973.3 981.4 1004.9 4037.7 4081.7 4091.6 4119.8 4198.6 13244.8 13901.8 13944.0 13946.4 13986.1 13860.7 13861.9 13863.0 13882.2 13917.4 9567.8 9705.4 10258.5 10412.0 10442.5 6494.5 6561.1 6592.6 6604.3 6662.1 11564.2 11711.2 12380.0 12395.4 12501.7 12130.2 12335.6 12533.5 12668.0 12704.6 9217.2 9326.0 9331.0 9340.4 9463.6 10159.7 10321.8 10378.7 10386.7 10410.9 8408.0 9184.6 9254.9 9257.6 9326.8 8883.0 8972.0 9017.3 9025.7 9026.6 7758.6 7839.8 7860.0 7929.7 7939.5 5857.0 5897.7 5925.3 6050.5 6109.3 5884.8 5906.1 6020.7 6052.4 6198.5 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4707.74 Median:5075.87 stddev:1332.84 (26.3%) stddev-sans-first:1295.52 09:32:39 INFO - PROCESS | 1647 | Values: 334.0 366.0 374.7 389.2 389.8 4564.7 5058.1 5292.5 5316.7 5527.2 4677.7 5164.8 5240.3 5314.3 5391.3 4343.8 4465.2 4604.5 4653.6 4709.2 3822.7 3926.4 3993.4 4494.7 4728.6 3727.7 4386.0 4386.6 4453.0 4454.3 4447.0 4471.3 4474.0 4475.5 4500.4 3416.8 3610.4 4078.9 4115.8 4140.8 4600.0 4715.2 4810.6 4983.3 5074.6 4217.6 5118.1 5126.6 5132.7 5189.5 5247.7 5383.2 5740.5 5780.9 5784.0 3822.8 3915.6 4461.9 4510.5 4520.6 4965.8 5442.8 5506.0 5908.0 6066.3 4275.1 4331.1 4411.9 4561.9 4633.8 4618.8 4659.8 4700.8 4703.3 4739.2 3980.9 4178.9 4384.6 5127.7 5135.4 4997.6 5102.7 5142.0 5697.3 5806.4 1438.7 1556.4 1602.8 1631.9 1632.5 981.7 1161.4 1209.5 1213.3 1235.8 4619.0 4767.6 4996.0 5055.8 5398.7 5267.4 5862.7 5978.5 6020.8 6023.5 4684.2 4886.4 4947.5 5151.3 5653.6 2518.7 2905.8 2906.9 2950.7 2970.5 5548.7 5645.9 6009.1 6093.5 6178.5 5102.8 5656.6 5880.4 6017.4 6124.5 5610.0 5945.9 6063.8 6099.4 6110.9 5206.0 5566.6 5963.6 6009.5 6031.7 4984.7 5483.2 5549.3 5772.3 5919.3 5911.0 5915.3 6119.7 6164.2 6203.7 4144.1 4178.4 4402.2 4600.8 4628.9 5107.4 5111.7 5468.9 5838.3 5930.0 5077.1 5117.5 5187.4 5692.3 5724.7 4968.8 5528.5 5618.2 5636.5 5751.9 4560.9 4611.9 4854.9 5351.4 5613.1 5221.5 5275.7 5726.6 5727.6 5793.2 5055.6 5534.7 5559.8 5574.3 5604.9 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:19936.54 Median:22849.16 stddev:28027.06 (122.7%) stddev-sans-first:28068.58 09:32:39 INFO - PROCESS | 1647 | Values: 730.4 741.7 758.2 820.7 831.0 15102.8 16189.5 16192.0 16195.5 16208.4 14430.9 15617.3 15637.5 15655.4 15666.2 922.8 949.1 949.6 949.6 953.3 915.0 945.5 953.0 961.8 962.8 1007.4 1007.7 1011.6 1013.3 1014.9 985.5 1002.6 1004.1 1005.8 1006.3 735.3 750.9 754.4 756.1 758.3 16051.8 16468.0 16475.5 16960.5 17236.6 11141.6 12923.0 14081.1 14204.4 14207.6 11437.4 11666.8 11880.1 11972.0 12456.9 3597.4 3731.3 3740.9 3748.6 3758.0 107571.5 108497.5 108548.5 108995.0 109397.0 10571.2 10649.1 10733.5 11249.8 11430.7 18310.8 22444.1 22800.1 22835.9 22862.4 7556.8 7609.1 7610.8 7628.5 7868.8 4181.4 4224.2 4302.9 4490.3 4506.5 3465.4 3504.7 3514.4 3545.3 3553.4 629.8 630.0 641.4 644.1 650.8 652.7 677.6 685.6 691.9 720.3 9983.7 10119.2 10154.6 10399.7 10525.7 692.5 706.7 710.7 710.8 718.9 4745.8 4951.9 4969.3 4973.3 4975.7 87423.0 94999.1 95488.5 96951.5 109406.5 77321.0 77330.6 77498.2 84604.2 93537.0 67183.0 67896.0 72014.3 73470.6 76280.5 11070.2 11138.3 11382.3 11480.0 11490.3 43596.0 47984.3 48962.5 48967.0 50268.7 58144.7 61792.4 62313.8 62314.7 62895.0 12574.4 12689.0 12731.5 12843.2 13259.0 14102.4 14218.9 14843.4 15417.6 15505.1 13339.3 13550.8 13701.8 13732.7 14204.8 13318.7 13717.6 13810.9 13818.4 14034.8 12906.7 13281.3 13381.2 13910.9 14005.1 10820.6 11149.9 11247.8 11415.4 11418.5 9561.2 9888.5 10183.0 11167.6 11406.3 09:32:39 INFO - PROCESS | 1647 | -------- Summary: end -------- 09:32:39 INFO - PROCESS | 1647 | 09:32:39 INFO - TEST-INFO | 1647: exit 0 09:32:39 INFO - TEST-OK | dromaeo_css | took 1116518ms 09:32:39 INFO - TEST-START | kraken 09:32:39 INFO - operating with platform_type : mac_ 09:32:39 INFO - Initialising browser for kraken test... 09:32:39 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmphP2IVp/profile http://localhost:49251/getInfo.html) 09:32:42 INFO - PROCESS | 1660 | 2017-03-11 09:32:42.337 plugin-container[1661:10236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 09:32:42 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:42 INFO - PROCESS | 1660 | 2017-03-11 09:32:42.340 plugin-container[1661:10236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:32:42 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:42 INFO - PROCESS | 1660 | 2017-03-11 09:32:42.340 plugin-container[1661:10236] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:32:42 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 09:32:42 INFO - PROCESS | 1660 | colorDepth:24 09:32:42 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 09:32:42 INFO - PROCESS | 1660 | __metrics 09:32:42 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:32:43 INFO - TEST-INFO | 1660: exit 0 09:32:43 INFO - Browser initialized. 09:32:43 INFO - Running cycle 1/1 for kraken test... 09:32:43 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmphP2IVp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:45 INFO - PROCESS | 1662 | 2017-03-11 09:32:45.619 plugin-container[1663:10369] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 09:32:45 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:45 INFO - PROCESS | 1662 | 2017-03-11 09:32:45.619 plugin-container[1663:10369] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 09:32:45 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:45 INFO - PROCESS | 1662 | 2017-03-11 09:32:45.619 plugin-container[1663:10369] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:32:55 INFO - PROCESS | 1662 | RSS: Main: 220450816 09:32:55 INFO - PROCESS | 1662 | 09:32:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:59 INFO - PROCESS | 1662 | RSS: Main: 234983424 09:32:59 INFO - PROCESS | 1662 | 09:33:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 09:33:02 INFO - PROCESS | 1662 | RSS: Main: 243183616 09:33:02 INFO - PROCESS | 1662 | 09:33:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 09:33:06 INFO - PROCESS | 1662 | RSS: Main: 246861824 09:33:06 INFO - PROCESS | 1662 | 09:33:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 09:33:08 INFO - PROCESS | 1662 | RSS: Main: 247250944 09:33:08 INFO - PROCESS | 1662 | 09:33:10 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:33:11 INFO - PROCESS | 1662 | RSS: Main: 247320576 09:33:11 INFO - PROCESS | 1662 | 09:33:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 09:33:15 INFO - PROCESS | 1662 | RSS: Main: 251289600 09:33:15 INFO - PROCESS | 1662 | 09:33:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:19 INFO - PROCESS | 1662 | RSS: Main: 251322368 09:33:19 INFO - PROCESS | 1662 | 09:33:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 09:33:22 INFO - PROCESS | 1662 | RSS: Main: 252633088 09:33:22 INFO - PROCESS | 1662 | 09:33:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:25 INFO - PROCESS | 1662 | RSS: Main: 251715584 09:33:25 INFO - PROCESS | 1662 | 09:33:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:27 INFO - PROCESS | 1662 | RSS: Main: 251711488 09:33:27 INFO - PROCESS | 1662 | 09:33:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:30 INFO - PROCESS | 1662 | RSS: Main: 251822080 09:33:30 INFO - PROCESS | 1662 | 09:33:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:34 INFO - PROCESS | 1662 | RSS: Main: 252108800 09:33:34 INFO - PROCESS | 1662 | 09:33:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:38 INFO - PROCESS | 1662 | RSS: Main: 251940864 09:33:38 INFO - PROCESS | 1662 | 09:33:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 09:33:40 INFO - PROCESS | 1662 | RSS: Main: 253820928 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | __start_tp_report 09:33:40 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 09:33:40 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 09:33:40 INFO - PROCESS | 1662 | |i|pagename|runs| 09:33:40 INFO - PROCESS | 1662 | |0;ai-astar;106;121;121;121;120;120;123;121;120;120 09:33:40 INFO - PROCESS | 1662 | |1;audio-beat-detection;131;108;110;144;110;112;109;113;133;115 09:33:40 INFO - PROCESS | 1662 | |2;audio-dft;194;203;198;200;189;196;198;238;199;195 09:33:40 INFO - PROCESS | 1662 | |3;audio-fft;85;59;59;88;62;62;52;55;78;77 09:33:40 INFO - PROCESS | 1662 | |4;audio-oscillator;85;68;78;71;73;70;83;71;70;84 09:33:40 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;88;93;105;88;89;88;89;88;116;124 09:33:40 INFO - PROCESS | 1662 | |6;imaging-darkroom;96;96;95;98;94;95;96;96;96;98 09:33:40 INFO - PROCESS | 1662 | |7;imaging-desaturate;144;131;90;92;91;92;92;117;147;89 09:33:40 INFO - PROCESS | 1662 | |8;json-parse-financial;68;67;64;70;72;71;70;69;69;70 09:33:40 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;59;56;59;71;61;58;56;58;58;58 09:33:40 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;85;84;90;101;85;88;84;90;82;89 09:33:40 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;138;137;140;138;136;149;143;143;194;146 09:33:40 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;202;212;221;229;174;169;222;168;193;168 09:33:40 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;66;85;83;85;84;65;67;65;67;66 09:33:40 INFO - PROCESS | 1662 | __end_tp_report 09:33:40 INFO - PROCESS | 1662 | __start_cc_report 09:33:40 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1008 09:33:40 INFO - PROCESS | 1662 | __end_cc_report 09:33:40 INFO - PROCESS | 1662 | __startTimestamp1489253620066__endTimestamp 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | ------- Summary: start ------- 09:33:40 INFO - PROCESS | 1662 | Number of tests: 14 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:119.30 Median:121.00 stddev:4.76 (3.9%) stddev-sans-first:0.97 09:33:40 INFO - PROCESS | 1662 | Values: 106.0 121.0 121.0 121.0 120.0 120.0 123.0 121.0 120.0 120.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:114.00 stddev:12.68 (11.1%) stddev-sans-first:12.61 09:33:40 INFO - PROCESS | 1662 | Values: 131.0 108.0 110.0 144.0 110.0 112.0 109.0 113.0 133.0 115.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:201.00 Median:198.50 stddev:13.54 (6.8%) stddev-sans-first:14.12 09:33:40 INFO - PROCESS | 1662 | Values: 194.0 203.0 198.0 200.0 189.0 196.0 198.0 238.0 199.0 195.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:67.70 Median:69.50 stddev:13.03 (18.7%) stddev-sans-first:12.22 09:33:40 INFO - PROCESS | 1662 | Values: 85.0 59.0 59.0 88.0 62.0 62.0 52.0 55.0 78.0 77.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:75.50 stddev:6.57 (8.7%) stddev-sans-first:5.95 09:33:40 INFO - PROCESS | 1662 | Values: 85.0 68.0 78.0 71.0 73.0 70.0 83.0 71.0 70.0 84.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:88.00 stddev:13.42 (15.3%) stddev-sans-first:13.85 09:33:40 INFO - PROCESS | 1662 | Values: 88.0 93.0 105.0 88.0 89.0 88.0 89.0 88.0 116.0 124.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:1.25 (1.3%) stddev-sans-first:1.32 09:33:40 INFO - PROCESS | 1662 | Values: 96.0 96.0 95.0 98.0 94.0 95.0 96.0 96.0 96.0 98.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:90.50 stddev:23.96 (26.5%) stddev-sans-first:21.70 09:33:40 INFO - PROCESS | 1662 | Values: 144.0 131.0 90.0 92.0 91.0 92.0 92.0 117.0 147.0 89.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:69.00 Median:70.00 stddev:2.26 (3.2%) stddev-sans-first:2.37 09:33:40 INFO - PROCESS | 1662 | Values: 68.0 67.0 64.0 70.0 72.0 71.0 70.0 69.0 69.0 70.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:58.50 stddev:4.33 (7.4%) stddev-sans-first:4.59 09:33:40 INFO - PROCESS | 1662 | Values: 59.0 56.0 59.0 71.0 61.0 58.0 56.0 58.0 58.0 58.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:86.50 stddev:5.41 (6.3%) stddev-sans-first:5.64 09:33:40 INFO - PROCESS | 1662 | Values: 85.0 84.0 90.0 101.0 85.0 88.0 84.0 90.0 82.0 89.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:143.00 stddev:17.24 (12.1%) stddev-sans-first:18.01 09:33:40 INFO - PROCESS | 1662 | Values: 138.0 137.0 140.0 138.0 136.0 149.0 143.0 143.0 194.0 146.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.80 Median:207.00 stddev:24.66 (11.9%) stddev-sans-first:26.05 09:33:40 INFO - PROCESS | 1662 | Values: 202.0 212.0 221.0 229.0 174.0 169.0 222.0 168.0 193.0 168.0 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:75.00 stddev:9.46 (12.6%) stddev-sans-first:9.66 09:33:40 INFO - PROCESS | 1662 | Values: 66.0 85.0 83.0 85.0 84.0 65.0 67.0 65.0 67.0 66.0 09:33:40 INFO - PROCESS | 1662 | -------- Summary: end -------- 09:33:40 INFO - PROCESS | 1662 | 09:33:40 INFO - TEST-INFO | 1662: exit 0 09:33:40 INFO - TEST-OK | kraken | took 61307ms 09:33:40 INFO - SUITE-END | took 1177s 09:33:41 INFO - Completed test suite (00:19:38) 09:33:41 INFO - javascript benchmark 09:33:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7740.449440346813, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2918.693537178596, 3413.214960559071, 3441.6730410611, 3468.1849765305155, 3473.687368136867, 5962.79130230442, 5994.520438364932, 6012.3987601239905, 6034.336223015464, 6090.5736598438125, 5981.342052374246, 5982.700864956757, 6053.485453736426, 6057.09143628456, 6061.45446909778, 5404.189371594253, 5444.91832622511, 5483.561315094783, 5501.9174712379145, 5543.722813859311, 5506.256655351516, 5514.062609356419, 5536.944630553703, 5550.028744969634, 5553.389127196006, 5308.362996440433, 5323.973380133113, 5454.400015998237, 5501.037318469272, 5518.91721624176, 5517.751701173457, 5524.751386187631, 5534.640248383842, 5542.057850165462, 5579.163125531203, 5326.813561525367, 5444.727763611843, 5458.590605704588, 5462.071447853875, 5488.780448782044, 5737.3688894221605, 5768.134779783024, 5970.462658360769, 6038.788642397539, 6148.723307451175, 6291.874162516724, 6293.779717709858, 6331.55679102463, 6342.714577844008, 6349.650769207692, 6491.0, 6523.380278873499, 6524.347565243438, 6529.608223506557, 6542.051402546652, 6126.693665316761, 6128.3258841527395, 6181.907271390933, 6191.102290167945, 6198.008318669036, 5697.829065128053, 6303.212098487689, 6359.523035772336, 6365.745370185234, 6478.190226221723, 6309.747610095636, 6313.494920406357, 6331.303556608769, 6334.366563343721, 6340.112384266206, 6376.7130479128555, 6451.838704032343, 6457.806265812033, 6464.709088091047, 6478.222613286436, 6210.968945155336, 6264.718087686065, 6304.180456540712, 6324.557280990379, 6329.620222786648, 6121.757838957688, 6243.219597550306, 6257.374262573706, 6271.592346497463, 6276.905846412307, 5174.172132458863, 5182.585393168538, 5184.429712731597, 5217.878156196436, 5225.0, 4582.427196600425, 4618.03021365517, 4624.28323609841, 4643.210654188729, 4687.32033855096, 5827.096799996008, 5893.528517718572, 5985.970070149621, 5996.670183139882, 6082.635041897412, 6057.57596968208, 6478.481721462272, 6520.380563846427, 6525.314841941623, 6544.083828264047, 6009.2188015557695, 6033.5173186145, 6074.210352654215, 6086.695665216722, 6095.664738439428, 4649.349091127245, 4880.194767863263, 4902.534259245366, 5575.080111781511, 5722.685252311079, 6534.542582019213, 6570.77002304917, 6653.068570400051, 6666.300038495888, 6687.364700353459, 6420.6789660517725, 6453.838654033687, 6458.967705161444, 6465.094886715958, 6547.279799222177, 5281.920771188512, 5609.962157000968, 6339.0, 6598.37315455031, 6636.170478690164, 6274.341194174728, 6308.0853276273765, 6308.716107775253, 6401.0, 6413.551051426355, 6449.548531602931, 6456.225252969673, 6462.063000864942, 6472.190976127984, 6482.189726284215, 6247.406496382836, 6250.812475625738, 6286.842828929313, 6306.747730090745, 6310.432061114522, 4894.583960363267, 6137.346341658241, 6163.3528479509005, 6183.319834818077, 6236.282827474898, 5726.713664316684, 6177.96911015442, 6256.593321434092, 6271.0, 6293.339199383999, 6145.262568491809, 6218.906716399167, 6267.1539342188225, 6326.33573474797, 6351.3331100235655, 6150.954337762681, 6206.131141640084, 6208.844778880564, 6211.937880621136, 6217.844553886189], "value": 5880.66520867583, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.8326376565274, 808.2604416958483, 823.6417158536035, 958.6117622362946, 963.7253382785907, 39145.60854391455, 39952.40071398928, 41578.376324355086, 41722.7913860431, 41763.58236417635, 39790.20419591606, 39991.400128998015, 39998.60001399985, 40107.79946100265, 40137.59862401375, 7862.0958589762195, 7988.4408091433625, 10618.840717389245, 10910.672679819618, 11082.0, 10820.458977051156, 10906.072983796386, 10907.29102608332, 10912.290701104403, 10935.726606834813, 10784.5686172553, 10833.67498975032, 10879.456027198648, 10895.782084358309, 10898.128149748001, 10854.99999999996, 10879.782404351949, 10910.727231819188, 10921.399323037229, 10926.125909927187, 10585.84121238182, 10643.733906652318, 10673.1995100367, 10673.306235094695, 10688.0, 35029.0, 35320.646793531996, 35406.64593354059, 35413.64586354129, 35511.64488355135, 3156.7474602031784, 3723.1995121049104, 3751.531058617673, 3769.3026790043655, 3952.0910190656023, 25069.0, 25085.62371564428, 25267.62098568523, 26678.5998210027, 26785.330366740793, 3086.965866434747, 3087.6294844618565, 3099.643540992792, 3106.0992312229428, 3115.750739940799, 345592.0, 349139.2543037294, 349949.99999999744, 350235.0, 350417.0, 45210.32184517235, 45497.31754023692, 45680.77159614181, 45753.77123114363, 45788.5421145791, 46984.0, 47432.0, 47436.0, 47467.28799068017, 47583.810404739466, 13900.652483687989, 14280.42878284857, 14292.571222863331, 14293.142411455347, 14298.785518217235, 12068.215565988183, 12366.319852408204, 12387.009039276836, 12387.380630968415, 12397.504099836086, 11595.130365222642, 12021.398930053638, 12078.396080195955, 12102.0, 12138.878611213775, 5760.683162426023, 5779.450952159538, 5791.536677065825, 5816.41835816415, 5843.1235314703135, 1109.7281166114353, 1110.5280255891214, 1135.846660700795, 1165.6736113888126, 1193.1588230297793, 21117.944102794798, 22334.0, 26769.0, 26930.192094237205, 27078.458430831273, 1230.8215308780177, 1237.7882469625588, 1241.5716577780652, 1292.2956988441133, 1299.1036185032485, 12020.699482512833, 13048.760367765048, 13815.930920345534, 13896.930515347358, 14143.080699754482, 472098.0, 473665.0, 473709.0, 474231.0, 485328.0, 46913.0, 47743.522564774605, 59546.0, 60040.0, 60160.39839601548, 1448.4930274403873, 1468.6622076922367, 1471.374665767066, 1473.7715654073636, 1486.3534362552325, 25973.740262597134, 30216.69783302139, 31587.84206078955, 31665.0, 32024.839875800473, 50168.0, 50465.74767126141, 50549.0, 50565.241521376476, 50614.2407863875, 457494.0, 460163.6991815019, 460674.0, 461074.0, 461658.6917065393, 30223.546646800754, 30909.845450772602, 31968.6803131975, 33409.33181336311, 33447.33105337928, 40016.0, 40248.798756006036, 40668.1866362677, 41006.38490422706, 41034.563790267195, 32509.349813003133, 32729.34541309208, 32945.505817412275, 33278.0, 33315.00054998354, 32187.51718724174, 32692.855750048635, 33620.66379336175, 33670.0, 33835.0, 28097.157085287476, 33256.66743332632, 33759.4936075964, 34008.65991340055, 34104.63581456714, 25747.18797559053, 26973.460530789667, 28381.290467738472, 29122.0, 29545.409091818474, 20838.479038024172, 22852.4286892829, 22977.655335169653, 23053.423664408525, 23129.65305520385], "value": 18751.986707282627, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.9337112690842, 430.4318299844205, 433.2331772762211, 440.07364497732254, 444.0763212517963, 18931.432057038277, 20138.89930550347, 21208.045637946292, 21335.35993920181, 21478.89260553699, 18632.068396580184, 19311.227550897947, 19646.0, 20063.1974721011, 21631.567368652617, 6161.661108639034, 6480.513961452886, 6519.869602607945, 6521.869562608768, 6523.706433210493, 6388.968055159718, 6479.676016199195, 6975.825604359881, 7340.063974410252, 7397.075365579302, 7216.855662886739, 7439.888401673979, 7463.925360746404, 7486.2139475355125, 7506.512076715024, 7407.407407407394, 7492.325690687837, 7540.321371076575, 7540.396768258526, 7549.584772837465, 7058.505904586681, 7333.669984850694, 7335.633218339061, 7342.229065948105, 7394.778156655255, 25607.61588576173, 25817.0, 26089.0, 26230.34424139412, 26420.867895660398, 2785.234060633322, 2848.1170837040586, 2861.842598657073, 2863.527517959534, 2872.224499385154, 15853.01837270339, 16071.19644017806, 16082.919585402115, 16160.0, 16744.24650890713, 2079.99120426593, 2084.9687254691194, 2105.0632468551503, 2109.483176621722, 2141.4004078857943, 159123.43067992566, 159763.0, 162887.0, 166555.0, 170741.0, 14212.255046608192, 20690.34477241847, 20764.68852967207, 21220.787792121882, 21317.401194910133, 31413.842930785657, 31491.527627085612, 31984.680153198635, 32050.0, 32174.35651286961, 8959.74563561102, 10273.075423211947, 10335.94832025835, 10600.893991059991, 10806.405647689295, 7098.396636285974, 8765.211130998241, 8925.464472131693, 8953.462792232487, 9079.773005674911, 9669.758256043515, 9803.509824508747, 9877.506124693737, 9914.008599140028, 9958.900410995797, 3653.4472849039053, 3669.449582562637, 3882.5923278055398, 3958.9618589039596, 4339.1972485090355, 766.865798485274, 932.9953350233205, 950.6150009246267, 955.3742298794301, 968.3763060665085, 17047.48857534276, 21053.368398948056, 21089.156433742784, 21105.89447052755, 21209.681854771883, 883.726044926075, 990.9108180263811, 993.8956409577034, 1028.5577201803296, 1035.9430231337199, 10087.04173103554, 10782.946085269523, 10801.729956750987, 10820.458977051116, 10823.56705731762, 768811.0, 771553.0, 777215.0, 778612.0, 788647.0, 130966.0, 159599.0, 163242.0, 164885.0, 165865.0, 1140.4411838199223, 1174.512577280436, 1176.0681048545125, 1199.0287866827898, 1200.33981310279, 17930.820691792916, 18028.819711802713, 18363.54091147732, 18492.0, 18532.629347412705, 65779.67110164418, 66703.99944000941, 67085.32286692872, 68132.659336703, 68371.31628683649, 73453.0, 89623.55188224016, 93170.0, 94699.0, 94819.52590237005, 8769.38614296993, 10149.832769231632, 11319.264247823865, 11795.587154449553, 11935.343556104497, 13148.40832162574, 13247.337633118499, 13257.553036210847, 13283.734325313633, 13627.318634068455, 10539.736506587396, 11115.277506962022, 11151.386673733023, 11312.6040588579, 11433.428328583705, 10851.45742712877, 11030.117590592894, 11152.721181970192, 11338.716532086764, 11438.084953203557, 11326.716832079264, 11413.687659989828, 11480.196386252883, 11618.360990145624, 11667.474963626826, 11467.96788289058, 11692.0, 11856.762864742484, 11895.940520297343, 11904.226225295328, 11136.665900023012, 11229.27009744364, 11361.431928403712, 11392.544298227978, 11458.77082458363], "value": 11632.485831822967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.0, 1529.3270960777256, 1554.13745371319, 1555.2301610702698, 1578.084710867697, 9419.62321507139, 9625.278104142182, 10547.73630659233, 11179.161562882797, 11189.32864028158, 10496.160307175427, 10810.135189184846, 10820.72948176294, 10833.512491937861, 10856.891431085707, 2340.8010319122855, 2361.315218586608, 2378.0250097460043, 2379.1553998330605, 2381.761823817613, 2321.8839058047115, 2358.056777289081, 2359.6460530920413, 2362.988185059081, 2373.1693907132453, 2320.825938054644, 2329.4176455886027, 2338.6024375856146, 2345.296411076679, 2350.764923507644, 2154.34292540775, 2170.5441857210003, 2176.793204645564, 2188.737351517812, 2193.2762188477764, 2252.8197744180425, 2304.481491664379, 2304.5275718477674, 2310.4570425949887, 2327.0, 10937.781244375148, 10988.0, 11011.669649910515, 11012.119030477543, 11044.392558409285, 5407.702576358299, 5533.253010843525, 5561.054620714488, 5561.416051314634, 5622.971885140548, 3169.524571314298, 3363.899083027513, 3381.340638575479, 3385.5768028996376, 3501.719862411001, 1196.635026316973, 1229.51434183497, 1231.3289257354763, 1251.7684228417681, 1252.5616034387983, 15398.230088495531, 15467.0, 15589.0, 15614.687706245924, 15715.764263536055, 4487.169873573399, 4691.0, 4728.05438912219, 4788.186008378584, 4798.328234047236, 12865.03512236586, 12915.483380664857, 12921.870781292067, 12922.353882305848, 12925.0, 5734.770609175586, 5735.4551317624755, 5741.5119714824705, 5744.310682718101, 5766.279215098113, 4364.1708075466195, 4786.832460863922, 5024.648274620815, 5070.062038522885, 5161.664491808096, 3853.421986702017, 3932.9016774580864, 3941.1723538056754, 3964.4647972523917, 4000.9199816004098, 915.231205787142, 1082.1667316166508, 1088.4394536813547, 1099.6700989703056, 1102.2559772153763, 646.5581752691525, 668.5954997226705, 753.4349238071446, 797.7447216890539, 814.0882211922684, 6372.6495042773095, 6580.440662543738, 6678.866422671519, 6745.123133992648, 6761.72953081881, 890.701614958983, 899.2221728205055, 973.2943615878573, 981.3964411886781, 1004.8894621591619, 4037.717359784787, 4081.714280000431, 4091.6112969267874, 4119.8352065917625, 4198.6221240088535, 13244.801327980089, 13901.79147312791, 13944.023918325824, 13946.372413241428, 13986.090904091201, 13860.722785544434, 13861.86138138626, 13862.96027797899, 13882.167069975834, 13917.373718182504, 9567.760805979906, 9705.417674939526, 10258.487075646188, 10412.010858968235, 10442.530086146142, 6494.512911531749, 6561.1470508833545, 6592.604443733391, 6604.339566043357, 6662.06731057662, 11564.248323858923, 11711.1802173847, 12380.0, 12395.442205100611, 12501.687457813627, 12130.211536250115, 12335.568255111028, 12533.498660053496, 12668.049896257633, 12704.618861434172, 9217.170454659114, 9326.020767819282, 9330.95334523323, 9340.392874463138, 9463.621455141993, 10159.695209143738, 10321.793564128826, 10378.740531486774, 10386.688399348031, 10410.947945260225, 8407.95796021016, 9184.632614695338, 9254.907450925673, 9257.583408746488, 9326.766830829283, 8882.955585222033, 8972.013078561484, 9017.278617710435, 9025.684101056431, 9026.638934442548, 7758.573278469513, 7839.804004899923, 7859.960700196462, 7929.682812687659, 7939.523628582304, 5856.970715146397, 5897.705114744332, 5925.288965324189, 6050.546209034252, 6109.2974307955155, 5884.764609415575, 5906.143609176731, 6020.729067191899, 6052.394760523913, 6198.535109866688], "value": 5078.144483481573, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.99999999999994, 365.9835307411166, 374.6964958383711, 389.21961467258154, 389.777826638816, 4564.748938808364, 5058.140116180244, 5292.470752924711, 5316.6544174628625, 5527.226188333631, 4677.719336839792, 5164.767585458654, 5240.292560504331, 5314.256004159421, 5391.32608423947, 4343.804528796196, 4465.24090904547, 4604.493505714368, 4653.6044436222965, 4709.152352576535, 3822.7132965027595, 3926.391409331558, 3993.4009898515164, 4494.651604518648, 4728.598069164125, 3727.700984628596, 4385.995593018776, 4386.627136693385, 4452.953555914358, 4454.287314029756, 4447.0, 4471.3293006049025, 4474.0, 4475.507694153641, 4500.369948207253, 3416.8120753358558, 3610.4403817408083, 4078.8572399966156, 4115.773632450214, 4140.8136633851545, 4599.954000459986, 4715.221988371914, 4810.584485015643, 4983.277424773388, 5074.619403544749, 4217.5993280638595, 5118.07874582575, 5126.5898728101665, 5132.74336283188, 5189.481051894781, 5247.737613119329, 5383.1697222944385, 5740.454656807597, 5780.942190578083, 5784.0, 3822.8400953461205, 3915.6280153385383, 4461.866144015685, 4510.503844577127, 4520.638348932078, 4965.751712414365, 5442.809501667462, 5506.0, 5907.999999999957, 6066.272047354346, 4275.1022285319805, 4331.133773245364, 4411.941014266882, 4561.931571026437, 4633.791479383436, 4618.776024353549, 4659.8136074557315, 4700.764961751967, 4703.270992996023, 4739.1706451371565, 3980.919557453575, 4178.91642167155, 4384.583464570861, 5127.717975511308, 5135.435102138762, 4997.600191984705, 5102.719350435687, 5141.974290128525, 5697.259356283656, 5806.4193580641595, 1438.719449707318, 1556.3618916244511, 1602.796155286481, 1631.8939268947481, 1632.4531282020419, 981.6858605246252, 1161.3960740415105, 1209.4799236328463, 1213.2962881528692, 1235.7590269897285, 4618.976905115453, 4767.642426818003, 4996.025774973918, 5055.814836652885, 5398.730063496888, 5267.420583735786, 5862.70686465675, 5978.521718262528, 6020.819375418744, 6023.518118550505, 4684.203685373494, 4886.413630364379, 4947.455779864212, 5151.304573882478, 5653.575981801381, 2518.6725725655547, 2905.7675385969496, 2906.9273268168463, 2950.749186319144, 2970.5247160454223, 5548.72256387179, 5645.915311270334, 6009.088372463851, 6093.4515893568905, 6178.4668286002525, 5102.821401250939, 5656.575756818255, 5880.382559831156, 6017.398260173947, 6124.5100391968535, 5609.9902017636405, 5945.851353716192, 6063.787767428119, 6099.420555047263, 6110.869489144522, 5205.973970130125, 5566.554675626021, 5963.642181469126, 6009.54928380382, 6031.69841507914, 4984.7258400788305, 5483.2049352844415, 5549.250851135045, 5772.278465191851, 5919.253820122988, 5910.970445147746, 5915.319738230159, 6119.663418512025, 6164.229471316085, 6203.689815509297, 4144.093716890265, 4178.35235538492, 4402.229609818205, 4600.792964316681, 4628.884277893079, 5107.399283505046, 5111.744412779271, 5468.890622187454, 5838.32859221195, 5929.962256605163, 5077.121465057952, 5117.462666420121, 5187.377514698261, 5692.288463942007, 5724.685142317213, 4968.826091086795, 5528.530074943675, 5618.157276408571, 5636.4781508993165, 5751.884962300647, 4560.931586026146, 4611.884702882455, 4854.90290194187, 5351.438098999704, 5613.101903695552, 5221.483416844479, 5275.709835958905, 5726.599138060463, 5727.599068065196, 5793.217915581343, 5055.620828437955, 5534.703098214196, 5559.804642001989, 5574.331080270394, 5604.907043126551], "value": 4315.389303131817, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.3718801830428, 741.6625435426878, 758.188738050286, 820.6758330459469, 831.0318478972, 15102.773458398118, 16189.514314570568, 16192.0, 16195.514134575982, 16208.351665933364, 14430.927845360784, 15617.297221625025, 15637.530874073767, 15655.373785048585, 15666.216689165552, 922.7693076730817, 949.1362859797586, 949.577438040069, 949.5821838391121, 953.3088510829663, 914.9588268527932, 945.4563625915092, 953.0279115302387, 961.7595601099725, 962.8459446488563, 1007.3905287301187, 1007.7128018514725, 1011.6206422591529, 1013.3413281367134, 1014.8883622801486, 985.4629227071227, 1002.5739060899125, 1004.0712341084504, 1005.7887843552862, 1006.2704539209014, 735.2867718313281, 750.8535835512077, 754.3587950242305, 756.0775853458772, 758.2720588235301, 16051.759223611654, 16468.0, 16475.505734827973, 16960.491185264462, 17236.56908577283, 11141.610043648434, 12922.966162707055, 14081.078924267771, 14204.360803763751, 14207.573772786833, 11437.42812859362, 11666.791557387287, 11880.108991825562, 11972.0, 12456.875431245753, 3597.3524765542193, 3731.328360895038, 3740.9251814963823, 3748.606396328401, 3757.9853439571434, 107571.46214268879, 108497.45751271272, 108548.45725771399, 108995.0, 109397.0, 10571.15430765537, 10649.148068154529, 10733.463326833627, 11249.83125253122, 11430.714232144262, 18310.81689183118, 22444.10223591071, 22800.087996480288, 22835.885820571122, 22862.42843928882, 7556.773296801105, 7609.124950630638, 7610.8097297568, 7628.54228746277, 7868.84262314762, 4181.393697913785, 4224.239636865394, 4302.870913872589, 4490.326451032306, 4506.504284528699, 3465.428204346305, 3504.684578387906, 3514.38498262803, 3545.3263879862743, 3553.378158822196, 629.8079085878759, 630.0304613784357, 641.4195153386192, 644.1127496435489, 650.7884937395365, 652.6573548886778, 677.5934439336359, 685.5886468119089, 691.865086308165, 720.2797202797203, 9983.700488985342, 10119.24105692076, 10154.628657313697, 10399.740006499747, 10525.684229473129, 692.5117791956659, 706.6925887239067, 710.733474946895, 710.7903168565285, 718.9460790440634, 4745.786439610226, 4951.851444456672, 4969.254611808257, 4973.2540118981715, 4975.651704380659, 87423.0, 94999.05000949903, 95488.52255738676, 96951.54572681415, 109406.45296773466, 77321.0, 77330.6133469329, 77498.22501775023, 84604.15395846085, 93537.0, 67183.0, 67895.98156027663, 72014.27985720181, 73470.63264683643, 76280.47439051188, 11070.225084244026, 11138.27601205919, 11382.317060976367, 11480.0, 11490.310581365145, 43596.0, 47984.28023579579, 48962.51037489722, 48967.0, 50268.74865625649, 58144.70927645504, 61792.38207617866, 62313.75372492615, 62314.68842655758, 62895.0, 12574.434150463068, 12689.0, 12731.490740370282, 12843.165194262343, 13259.037431030412, 14102.36539355711, 14218.92890535582, 14843.40626374933, 15417.614559636098, 15505.069695818287, 13339.333033348488, 13550.796738049134, 13701.785255333094, 13732.725345493234, 14204.807735261558, 13318.733625327633, 13717.588472345846, 13810.93094534521, 13818.37817298244, 14034.78947815804, 12906.67733306675, 13281.335933203109, 13381.19712817234, 13910.930445347709, 14005.089669171472, 10820.56717731282, 11149.888501114885, 11247.775044499227, 11415.37215453158, 11418.543258269576, 9561.165010078375, 9888.456134912649, 10183.0, 11167.609133680282, 11406.258593191416], "value": 7650.417578596848, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 121.0, 121.0, 121.0, 120.0, 120.0, 123.0, 121.0, 120.0, 120.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 108.0, 110.0, 144.0, 110.0, 112.0, 109.0, 113.0, 133.0, 115.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 203.0, 198.0, 200.0, 189.0, 196.0, 198.0, 238.0, 199.0, 195.0], "value": 201.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 59.0, 59.0, 88.0, 62.0, 62.0, 52.0, 55.0, 78.0, 77.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 78.0, 71.0, 73.0, 70.0, 83.0, 71.0, 70.0, 84.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 105.0, 88.0, 89.0, 88.0, 89.0, 88.0, 116.0, 124.0], "value": 96.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 98.0, 94.0, 95.0, 96.0, 96.0, 96.0, 98.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 131.0, 90.0, 92.0, 91.0, 92.0, 92.0, 117.0, 147.0, 89.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 64.0, 70.0, 72.0, 71.0, 70.0, 69.0, 69.0, 70.0], "value": 69.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 59.0, 71.0, 61.0, 58.0, 56.0, 58.0, 58.0, 58.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 90.0, 101.0, 85.0, 88.0, 84.0, 90.0, 82.0, 89.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 140.0, 138.0, 136.0, 149.0, 143.0, 143.0, 194.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 212.0, 221.0, 229.0, 174.0, 169.0, 222.0, 168.0, 193.0, 168.0], "value": 195.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 85.0, 83.0, 85.0, 84.0, 65.0, 67.0, 65.0, 67.0, 66.0], "value": 73.3, "unit": "score"}]}]} 09:33:41 INFO - Return code: 0 09:33:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:33:41 ERROR - # TBPL SUCCESS # 09:33:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:33:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:33:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:33:41 INFO - 2017-03-11 09:33:41.403 screenresolution[1667:10762] starting screenresolution argv=screenresolution get 09:33:41 INFO - 2017-03-11 09:33:41.405 screenresolution[1667:10762] Display 0: 1600x1200x32@60 09:33:41 INFO - 2017-03-11 09:33:41.411 screenresolution[1668:10765] starting screenresolution argv=screenresolution list 09:33:41 INFO - Available Modes on Display 0 09:33:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:33:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:33:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:33:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:33:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:33:41 INFO - Intel Iris: 09:33:41 INFO - Chipset Model: Intel Iris 09:33:41 INFO - Type: GPU 09:33:41 INFO - Bus: Built-In 09:33:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:33:41 INFO - Vendor: Intel (0x8086) 09:33:41 INFO - Device ID: 0x0a2e 09:33:41 INFO - Revision ID: 0x0009 09:33:41 INFO - Displays: 09:33:41 INFO - Display: 09:33:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:33:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:33:41 INFO - Display Serial Number: 1600x1200 60 09:33:41 INFO - Main Display: Yes 09:33:41 INFO - Mirror: Off 09:33:41 INFO - Online: Yes 09:33:41 INFO - Rotation: Supported 09:33:41 INFO - Return code: 0 09:33:41 INFO - Running post-action listener: _resource_record_post_action 09:33:41 INFO - [mozharness: 2017-03-11 17:33:41.647711Z] Finished run-tests step (success) 09:33:41 INFO - Running post-run listener: _resource_record_post_run 09:33:41 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 38577664; Write bytes: 511121920; Read time: 237; Write time: 1618 09:33:41 INFO - TinderboxPrint: CPU usage
54.6% 09:33:41 INFO - TinderboxPrint: I/O read bytes / time
38,577,664 / 237 09:33:41 INFO - TinderboxPrint: I/O write bytes / time
511,121,920 / 1,618 09:33:41 INFO - TinderboxPrint: CPU idle
2,178.5 (45.4%) 09:33:41 INFO - TinderboxPrint: CPU system
957.4 (20.0%) 09:33:41 INFO - TinderboxPrint: CPU user
1,657.8 (34.6%) 09:33:41 INFO - TinderboxPrint: Swap in / out
247,586,816 / 0 09:33:41 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208357888; Write bytes: 201336320; Read time: 15936; Write time: 420 09:33:41 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 34915840; Write bytes: 298546176; Read time: 212; Write time: 1160 09:33:42 INFO - Running post-run listener: _upload_blobber_files 09:33:42 INFO - Blob upload gear active. 09:33:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:33:42 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 09:33:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:33:42 INFO - (blobuploader) - INFO - Open directory for files ... 09:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:33:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:43 INFO - (blobuploader) - INFO - Done attempting. 09:33:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:33:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:33:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:44 INFO - (blobuploader) - INFO - Done attempting. 09:33:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:33:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:33:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:45 INFO - (blobuploader) - INFO - Done attempting. 09:33:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:33:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:33:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:48 INFO - (blobuploader) - INFO - Done attempting. 09:33:48 INFO - (blobuploader) - INFO - Iteration through files over. 09:33:48 INFO - Return code: 0 09:33:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:33:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:33:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc63d6de2a344aef6e973d09d97f70d46c1b884e809fc9db3bcdbef21a5b456b083cf3c37759683e4a74e6fe32adfd93a38541fafaa6b2cd7fc5fe9974d8c45", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06d74b1820d2a95175cf57d025767f7b907f34e4d1f3cf7f4243685a40bd01b5f0cea36239303d229d2c03188324d725a66fe168cd5523e9bb138a63ffd83553", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/940d4fefd42f7226977c1b9d5860a9e9137dd67b3c5b7508b45ba549c550cdeac786ce704815b8008eb3b6868c5e9dd9943edf53dcae713a88e4834f9f43d917", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b5e3fd017eeb37e40a2537fb04fbda5bf5a49fbf078cedbc7f061c06aa5bad72fd6d5652359d68d8cbe404a52d84a2225c7987099dc248b0c7fb8d84cc82b6a"} 09:33:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:33:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:33:48 INFO - Contents: 09:33:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc63d6de2a344aef6e973d09d97f70d46c1b884e809fc9db3bcdbef21a5b456b083cf3c37759683e4a74e6fe32adfd93a38541fafaa6b2cd7fc5fe9974d8c45", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06d74b1820d2a95175cf57d025767f7b907f34e4d1f3cf7f4243685a40bd01b5f0cea36239303d229d2c03188324d725a66fe168cd5523e9bb138a63ffd83553", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/940d4fefd42f7226977c1b9d5860a9e9137dd67b3c5b7508b45ba549c550cdeac786ce704815b8008eb3b6868c5e9dd9943edf53dcae713a88e4834f9f43d917", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b5e3fd017eeb37e40a2537fb04fbda5bf5a49fbf078cedbc7f061c06aa5bad72fd6d5652359d68d8cbe404a52d84a2225c7987099dc248b0c7fb8d84cc82b6a"} 09:33:48 INFO - Running post-run listener: copy_logs_to_upload_dir 09:33:48 INFO - Copying logs to upload dir... 09:33:48 INFO - mkdir: /builds/slave/test/build/upload/logs 09:33:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.558088 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-11 09:33:48.532846) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-11 09:33:48.535982) ========= 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.SM115eF9EY/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.SAKfODYHSB/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-Aurora/sha512/acc63d6de2a344aef6e973d09d97f70d46c1b884e809fc9db3bcdbef21a5b456b083cf3c37759683e4a74e6fe32adfd93a38541fafaa6b2cd7fc5fe9974d8c45", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06d74b1820d2a95175cf57d025767f7b907f34e4d1f3cf7f4243685a40bd01b5f0cea36239303d229d2c03188324d725a66fe168cd5523e9bb138a63ffd83553", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/940d4fefd42f7226977c1b9d5860a9e9137dd67b3c5b7508b45ba549c550cdeac786ce704815b8008eb3b6868c5e9dd9943edf53dcae713a88e4834f9f43d917", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b5e3fd017eeb37e40a2537fb04fbda5bf5a49fbf078cedbc7f061c06aa5bad72fd6d5652359d68d8cbe404a52d84a2225c7987099dc248b0c7fb8d84cc82b6a"} build_url:https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/CPM_wKn7SfG9AoJvN3ibuw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc63d6de2a344aef6e973d09d97f70d46c1b884e809fc9db3bcdbef21a5b456b083cf3c37759683e4a74e6fe32adfd93a38541fafaa6b2cd7fc5fe9974d8c45", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06d74b1820d2a95175cf57d025767f7b907f34e4d1f3cf7f4243685a40bd01b5f0cea36239303d229d2c03188324d725a66fe168cd5523e9bb138a63ffd83553", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/940d4fefd42f7226977c1b9d5860a9e9137dd67b3c5b7508b45ba549c550cdeac786ce704815b8008eb3b6868c5e9dd9943edf53dcae713a88e4834f9f43d917", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b5e3fd017eeb37e40a2537fb04fbda5bf5a49fbf078cedbc7f061c06aa5bad72fd6d5652359d68d8cbe404a52d84a2225c7987099dc248b0c7fb8d84cc82b6a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-11 09:33:48.569869) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-11 09:33:48.570165) ========= 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.SM115eF9EY/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.SAKfODYHSB/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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.005173 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-11 09:33:48.618152) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-11 09:33:48.618448) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-11 09:33:48.618752) ========= ========= Total master_lag: 0.18 =========