builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0226 starttime: 1481240973.11 results: success (0) buildid: 20161208133259 builduid: 28e1b5184bc84a1387ddbb1538626e86 revision: 02fd565e7be4772964dca269de08c21b6b46fef4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:33.107655) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:33.108091) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:33.108369) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:33.138746) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:33.139142) ========= 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.laEXQja3Dx/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.K3ohBisZzP/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-08 15:49:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2016-12-08 15:49:33 (147 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.353057 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:33.515773) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:33.516115) ========= 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.laEXQja3Dx/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.K3ohBisZzP/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.019346 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:33.564912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-08 15:49:33.565272) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 02fd565e7be4772964dca269de08c21b6b46fef4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 02fd565e7be4772964dca269de08c21b6b46fef4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.laEXQja3Dx/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.K3ohBisZzP/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-08 15:49:33,636 truncating revision to first 12 chars 2016-12-08 15:49:33,637 Setting DEBUG logging. 2016-12-08 15:49:33,637 attempt 1/10 2016-12-08 15:49:33,637 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/02fd565e7be4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-08 15:49:34,867 unpacking tar archive at: larch-02fd565e7be4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.469070 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-08 15:49:35.061306) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:35.061638) ========= script_repo_revision: 02fd565e7be4772964dca269de08c21b6b46fef4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:35.062040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:35.062328) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 15:49:35.082356) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-08 15:49:35.082724) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.laEXQja3Dx/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.K3ohBisZzP/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:49:35 INFO - MultiFileLogger online at 20161208 15:49:35 in /builds/slave/test 15:49:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 15:49:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:49:35 INFO - {'append_to_log': False, 15:49:35 INFO - 'base_work_dir': '/builds/slave/test', 15:49:35 INFO - 'blob_upload_branch': 'Larch', 15:49:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:49:35 INFO - 'branch': 'Larch', 15:49:35 INFO - 'buildbot_json_path': 'buildprops.json', 15:49:35 INFO - 'config_files': ('talos/mac_config.py',), 15:49:35 INFO - 'default_actions': ('clobber', 15:49:35 INFO - 'read-buildbot-config', 15:49:35 INFO - 'download-and-extract', 15:49:35 INFO - 'populate-webroot', 15:49:35 INFO - 'create-virtualenv', 15:49:35 INFO - 'install', 15:49:35 INFO - 'run-tests'), 15:49:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:49:35 INFO - 'download_minidump_stackwalk': True, 15:49:35 INFO - 'download_symbols': 'ondemand', 15:49:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:49:35 INFO - 'tooltool.py': '/tools/tooltool.py', 15:49:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:49:35 INFO - '/tools/misc-python/virtualenv.py')}, 15:49:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:49:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:49:35 INFO - 'installer_path': 'installer.exe', 15:49:35 INFO - 'log_level': 'info', 15:49:35 INFO - 'log_name': 'talos', 15:49:35 INFO - 'log_to_console': True, 15:49:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:49:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:49:35 INFO - 'opt_config_files': (), 15:49:35 INFO - 'pip_index': False, 15:49:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:49:35 INFO - 'cmd': ('bash', 15:49:35 INFO - '-c', 15:49:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:49:35 INFO - 'enabled': True, 15:49:35 INFO - 'halt_on_failure': False, 15:49:35 INFO - 'name': 'check_screen_resolution'},), 15:49:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:49:35 INFO - 'cmd': ('bash', 15:49:35 INFO - '-c', 15:49:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:49:35 INFO - 'enabled': True, 15:49:35 INFO - 'halt_on_failure': False, 15:49:35 INFO - 'name': 'check_screen_resolution'},), 15:49:35 INFO - 'run_cmd_checks_enabled': True, 15:49:35 INFO - 'sps_profile': False, 15:49:35 INFO - 'sps_profile_interval': 0, 15:49:35 INFO - 'suite': 'dromaeojs-e10s', 15:49:35 INFO - 'system_bits': '32', 15:49:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:49:35 INFO - 'title': 't-yosemite-r7-0226', 15:49:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:49:35 INFO - 'use_talos_json': True, 15:49:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:49:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:49:35 INFO - 'work_dir': 'build'} 15:49:35 INFO - [mozharness: 2016-12-08 23:49:35.268711Z] Running clobber step. 15:49:35 INFO - Running pre-action listener: _resource_record_pre_action 15:49:35 INFO - Running main action method: clobber 15:49:35 INFO - rmtree: /builds/slave/test/build 15:49:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:49:36 INFO - Running post-action listener: _resource_record_post_action 15:49:36 INFO - [mozharness: 2016-12-08 23:49:36.800284Z] Finished clobber step (success) 15:49:36 INFO - [mozharness: 2016-12-08 23:49:36.800405Z] Running read-buildbot-config step. 15:49:36 INFO - Running pre-action listener: _resource_record_pre_action 15:49:36 INFO - Running main action method: read_buildbot_config 15:49:36 INFO - Using buildbot properties: 15:49:36 INFO - { 15:49:36 INFO - "project": "", 15:49:36 INFO - "product": "firefox", 15:49:36 INFO - "script_repo_revision": "production", 15:49:36 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 15:49:36 INFO - "repository": "", 15:49:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 15:49:36 INFO - "buildid": "20161208133259", 15:49:36 INFO - "pgo_build": "False", 15:49:36 INFO - "basedir": "/builds/slave/test", 15:49:36 INFO - "buildnumber": 1, 15:49:36 INFO - "slavename": "t-yosemite-r7-0226", 15:49:36 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:49:36 INFO - "platform": "yosemite_r7", 15:49:36 INFO - "scheduler": "tests-larch-macosx64-talos", 15:49:36 INFO - "branch": "larch", 15:49:36 INFO - "repo_path": "projects/larch", 15:49:36 INFO - "revision": "02fd565e7be4772964dca269de08c21b6b46fef4", 15:49:36 INFO - "stage_platform": "macosx64", 15:49:36 INFO - "builduid": "28e1b5184bc84a1387ddbb1538626e86", 15:49:36 INFO - "slavebuilddir": "test" 15:49:36 INFO - } 15:49:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 15:49:36 INFO - Running post-action listener: _resource_record_post_action 15:49:36 INFO - [mozharness: 2016-12-08 23:49:36.803751Z] Finished read-buildbot-config step (success) 15:49:36 INFO - [mozharness: 2016-12-08 23:49:36.803860Z] Running download-and-extract step. 15:49:36 INFO - Running pre-action listener: _resource_record_pre_action 15:49:36 INFO - Running main action method: download_and_extract 15:49:36 INFO - mkdir: /builds/slave/test/build/tests 15:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:36 INFO - trying https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 15:49:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 15:49:38 INFO - Downloaded 1199 bytes. 15:49:38 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:49:38 INFO - Using the following test package requirements: 15:49:38 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:49:38 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:49:38 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 15:49:38 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:49:38 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 15:49:38 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:49:38 INFO - u'jsshell-mac.zip'], 15:49:38 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:49:38 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 15:49:38 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:49:38 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:49:38 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 15:49:38 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:49:38 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 15:49:38 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:49:38 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 15:49:38 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:49:38 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 15:49:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:49:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:49:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:49:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:49:39 INFO - Expected file size: 16283810 15:49:39 INFO - Obtained file size: 16283810 15:49:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:49:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:49:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:49:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:49:46 INFO - Expected file size: 16283810 15:49:46 INFO - Obtained file size: 16283810 15:49:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 15:49:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:49:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 15:49:53 INFO - Expected file size: 14409734 15:49:53 INFO - Obtained file size: 14409734 15:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:54 INFO - trying https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:49:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 15:49:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 15:49:56 INFO - Downloaded 107556216 bytes. 15:49:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:49:56 INFO - mkdir: /builds/slave/test/properties 15:49:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:49:56 INFO - Writing to file /builds/slave/test/properties/build_url 15:49:56 INFO - Contents: 15:49:56 INFO - build_url:https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:49:57 INFO - Running post-action listener: _resource_record_post_action 15:49:57 INFO - Running post-action listener: set_extra_try_arguments 15:49:57 INFO - [mozharness: 2016-12-08 23:49:57.568151Z] Finished download-and-extract step (success) 15:49:57 INFO - [mozharness: 2016-12-08 23:49:57.568283Z] Running populate-webroot step. 15:49:57 INFO - Running pre-action listener: _resource_record_pre_action 15:49:57 INFO - Running main action method: populate_webroot 15:49:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:49:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:49:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:49:57 INFO - '--tppagecycles', 15:49:57 INFO - '7'], 15:49:57 INFO - 'tests': ['tsvgm']}}, 15:49:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:49:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:49:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:49:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:49:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:49:57 INFO - 'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:49:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:49:57 INFO - 'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['damp', 'tps']}, 15:49:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:49:57 INFO - 'tests': ['damp', 'tps']}, 15:49:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['dromaeo_dom']}, 15:49:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:49:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['basic_compositor_video']}, 15:49:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:49:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other-e10s': {'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['tsvgx', 15:49:57 INFO - 'tsvgr_opacity', 15:49:57 INFO - 'tart', 15:49:57 INFO - 'tscrollx', 15:49:57 INFO - 'cart', 15:49:57 INFO - 'tsvg_static']}, 15:49:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:49:57 INFO - 'tsvgr_opacity', 15:49:57 INFO - 'tart', 15:49:57 INFO - 'tscrollx', 15:49:57 INFO - 'cart', 15:49:57 INFO - 'tsvg_static']}, 15:49:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:49:57 INFO - 'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['tp5o']}, 15:49:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:49:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:49:57 INFO - 'talos_options': ['--disable-e10s', 15:49:57 INFO - '--xperf_path', 15:49:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:57 INFO - 'tests': ['tp5n']}, 15:49:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:49:57 INFO - 'talos_options': ['--xperf_path', 15:49:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:57 INFO - 'tests': ['tp5n']}}, 15:49:57 INFO - 'talos.zip': {'path': '', 15:49:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:49:57 INFO - Running post-action listener: _resource_record_post_action 15:49:57 INFO - [mozharness: 2016-12-08 23:49:57.587246Z] Finished populate-webroot step (success) 15:49:57 INFO - [mozharness: 2016-12-08 23:49:57.587343Z] Running create-virtualenv step. 15:49:57 INFO - Running pre-action listener: _resource_record_pre_action 15:49:57 INFO - Running main action method: create_virtualenv 15:49:57 INFO - Creating virtualenv /builds/slave/test/build/venv 15:49:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:49:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:49:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:49:57 INFO - Using real prefix '/tools/python27' 15:49:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:49:58 INFO - Installing distribute.............................................................................................................................................................................................done. 15:50:01 INFO - Installing pip.................done. 15:50:01 INFO - Return code: 0 15:50:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:50:01 INFO - Reading from file tmpfile_stdout 15:50:01 INFO - Output received: 15:50:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:50:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:01 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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:50:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:01 INFO - 'HOME': '/Users/cltbld', 15:50:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:01 INFO - 'LOGNAME': 'cltbld', 15:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:01 INFO - 'MOZ_NO_REMOTE': '1', 15:50:01 INFO - 'NO_EM_RESTART': '1', 15:50:01 INFO - 'PAGER': '/bin/cat', 15:50:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:01 INFO - 'PWD': '/builds/slave/test', 15:50:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:01 INFO - 'SHELL': '/bin/bash', 15:50:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:01 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:01 INFO - 'USER': 'cltbld', 15:50:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:01 INFO - 'XPC_FLAGS': '0x0', 15:50:01 INFO - 'XPC_SERVICE_NAME': '0', 15:50:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:50:01 INFO - Cleaning up... 15:50:01 INFO - Return code: 0 15:50:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:01 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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:50:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:01 INFO - 'HOME': '/Users/cltbld', 15:50:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:01 INFO - 'LOGNAME': 'cltbld', 15:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:01 INFO - 'MOZ_NO_REMOTE': '1', 15:50:01 INFO - 'NO_EM_RESTART': '1', 15:50:01 INFO - 'PAGER': '/bin/cat', 15:50:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:01 INFO - 'PWD': '/builds/slave/test', 15:50:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:01 INFO - 'SHELL': '/bin/bash', 15:50:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:01 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:01 INFO - 'USER': 'cltbld', 15:50:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:01 INFO - 'XPC_FLAGS': '0x0', 15:50:01 INFO - 'XPC_SERVICE_NAME': '0', 15:50:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:02 INFO - Downloading/unpacking psutil>=3.1.1 15:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:50:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:05 INFO - Installing collected packages: psutil 15:50:05 INFO - Running setup.py install for psutil 15:50:05 INFO - building 'psutil._psutil_osx' extension 15:50:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:50:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:50:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:50:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:50:06 INFO - building 'psutil._psutil_posix' extension 15:50:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:50:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:50:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:50:06 INFO - ^ 15:50:06 INFO - 1 warning generated. 15:50:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:50:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:06 INFO - Successfully installed psutil 15:50:06 INFO - Cleaning up... 15:50:06 INFO - Return code: 0 15:50:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:50:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:06 INFO - 'HOME': '/Users/cltbld', 15:50:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:06 INFO - 'LOGNAME': 'cltbld', 15:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:06 INFO - 'MOZ_NO_REMOTE': '1', 15:50:06 INFO - 'NO_EM_RESTART': '1', 15:50:06 INFO - 'PAGER': '/bin/cat', 15:50:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:06 INFO - 'PWD': '/builds/slave/test', 15:50:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:06 INFO - 'SHELL': '/bin/bash', 15:50:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:06 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:06 INFO - 'USER': 'cltbld', 15:50:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:06 INFO - 'XPC_FLAGS': '0x0', 15:50:06 INFO - 'XPC_SERVICE_NAME': '0', 15:50:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:50:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:50:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:50:09 INFO - Installing collected packages: mozsystemmonitor 15:50:09 INFO - Running setup.py install for mozsystemmonitor 15:50:09 INFO - Successfully installed mozsystemmonitor 15:50:09 INFO - Cleaning up... 15:50:09 INFO - Return code: 0 15:50:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:50:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:09 INFO - 'HOME': '/Users/cltbld', 15:50:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:09 INFO - 'LOGNAME': 'cltbld', 15:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:09 INFO - 'MOZ_NO_REMOTE': '1', 15:50:09 INFO - 'NO_EM_RESTART': '1', 15:50:09 INFO - 'PAGER': '/bin/cat', 15:50:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:09 INFO - 'PWD': '/builds/slave/test', 15:50:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:09 INFO - 'SHELL': '/bin/bash', 15:50:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:09 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:09 INFO - 'USER': 'cltbld', 15:50:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:09 INFO - 'XPC_FLAGS': '0x0', 15:50:09 INFO - 'XPC_SERVICE_NAME': '0', 15:50:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:09 INFO - Downloading/unpacking jsonschema==2.5.1 15:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:50:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:12 INFO - Downloading functools32-3.2.3-2.tar.gz 15:50:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:50:12 INFO - warning: no files found matching '*.txt' 15:50:12 INFO - no previously-included directories found matching 'build' 15:50:12 INFO - no previously-included directories found matching 'dist' 15:50:12 INFO - no previously-included directories found matching '.git*' 15:50:12 INFO - Installing collected packages: jsonschema, functools32 15:50:13 INFO - Running setup.py install for functools32 15:50:13 INFO - warning: no files found matching '*.txt' 15:50:13 INFO - no previously-included directories found matching 'build' 15:50:13 INFO - no previously-included directories found matching 'dist' 15:50:13 INFO - no previously-included directories found matching '.git*' 15:50:13 INFO - Successfully installed jsonschema functools32 15:50:13 INFO - Cleaning up... 15:50:13 INFO - Return code: 0 15:50:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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', '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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:50: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 functools32==3.2.3-2 15:50:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:13 INFO - 'HOME': '/Users/cltbld', 15:50:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:13 INFO - 'LOGNAME': 'cltbld', 15:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:13 INFO - 'MOZ_NO_REMOTE': '1', 15:50:13 INFO - 'NO_EM_RESTART': '1', 15:50:13 INFO - 'PAGER': '/bin/cat', 15:50:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:13 INFO - 'PWD': '/builds/slave/test', 15:50:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:13 INFO - 'SHELL': '/bin/bash', 15:50:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:13 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:13 INFO - 'USER': 'cltbld', 15:50:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:13 INFO - 'XPC_FLAGS': '0x0', 15:50:13 INFO - 'XPC_SERVICE_NAME': '0', 15:50:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:50:13 INFO - Cleaning up... 15:50:13 INFO - Return code: 0 15:50:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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', '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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:50: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 blobuploader==1.2.4 15:50:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:13 INFO - 'HOME': '/Users/cltbld', 15:50:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:13 INFO - 'LOGNAME': 'cltbld', 15:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:13 INFO - 'MOZ_NO_REMOTE': '1', 15:50:13 INFO - 'NO_EM_RESTART': '1', 15:50:13 INFO - 'PAGER': '/bin/cat', 15:50:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:13 INFO - 'PWD': '/builds/slave/test', 15:50:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:13 INFO - 'SHELL': '/bin/bash', 15:50:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:13 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:13 INFO - 'USER': 'cltbld', 15:50:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:13 INFO - 'XPC_FLAGS': '0x0', 15:50:13 INFO - 'XPC_SERVICE_NAME': '0', 15:50:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:13 INFO - Downloading/unpacking blobuploader==1.2.4 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50:16 INFO - Downloading blobuploader-1.2.4.tar.gz 15:50:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:50:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:50:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:17 INFO - Downloading docopt-0.6.1.tar.gz 15:50:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:50:17 INFO - Installing collected packages: blobuploader, requests, docopt 15:50:17 INFO - Running setup.py install for blobuploader 15:50:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:50:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:50:17 INFO - Running setup.py install for requests 15:50:18 INFO - Running setup.py install for docopt 15:50:18 INFO - Successfully installed blobuploader requests docopt 15:50:18 INFO - Cleaning up... 15:50:18 INFO - Return code: 0 15:50:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:19 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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:50:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:19 INFO - 'HOME': '/Users/cltbld', 15:50:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:19 INFO - 'LOGNAME': 'cltbld', 15:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:19 INFO - 'MOZ_NO_REMOTE': '1', 15:50:19 INFO - 'NO_EM_RESTART': '1', 15:50:19 INFO - 'PAGER': '/bin/cat', 15:50:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:19 INFO - 'PWD': '/builds/slave/test', 15:50:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:19 INFO - 'SHELL': '/bin/bash', 15:50:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:19 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:19 INFO - 'USER': 'cltbld', 15:50:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:19 INFO - 'XPC_FLAGS': '0x0', 15:50:19 INFO - 'XPC_SERVICE_NAME': '0', 15:50:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:19 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-cdONEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:19 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-qDlhvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:19 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-svxVes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:19 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ujQUkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-WWwsm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-bCpQ3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-9tA9QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-WjSqfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-9bnWqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-T0pPCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-2AAv1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-jk4nrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:20 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-umeUUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:21 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-2YGSp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:21 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-gY6NIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:21 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-jZze33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:21 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-FpyBMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:50:21 INFO - Running setup.py install for manifestparser 15:50:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:50:21 INFO - Running setup.py install for mozcrash 15:50:21 INFO - Running setup.py install for mozdebug 15:50:21 INFO - Running setup.py install for mozdevice 15:50:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Running setup.py install for mozfile 15:50:22 INFO - Running setup.py install for mozhttpd 15:50:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Running setup.py install for mozinfo 15:50:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Running setup.py install for mozInstall 15:50:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Running setup.py install for mozleak 15:50:22 INFO - Running setup.py install for mozlog 15:50:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:50:22 INFO - Running setup.py install for moznetwork 15:50:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:50:23 INFO - Running setup.py install for mozprocess 15:50:23 INFO - Running setup.py install for mozprofile 15:50:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:50:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:50:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:50:23 INFO - Running setup.py install for mozrunner 15:50:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:50:23 INFO - Running setup.py install for mozscreenshot 15:50:23 INFO - Running setup.py install for moztest 15:50:23 INFO - Running setup.py install for mozversion 15:50:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:50:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:50:24 INFO - Cleaning up... 15:50:24 INFO - Return code: 0 15:50:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:24 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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:50:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:24 INFO - 'HOME': '/Users/cltbld', 15:50:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:24 INFO - 'LOGNAME': 'cltbld', 15:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:24 INFO - 'MOZ_NO_REMOTE': '1', 15:50:24 INFO - 'NO_EM_RESTART': '1', 15:50:24 INFO - 'PAGER': '/bin/cat', 15:50:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:24 INFO - 'PWD': '/builds/slave/test', 15:50:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:24 INFO - 'SHELL': '/bin/bash', 15:50:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:24 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:24 INFO - 'USER': 'cltbld', 15:50:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:24 INFO - 'XPC_FLAGS': '0x0', 15:50:24 INFO - 'XPC_SERVICE_NAME': '0', 15:50:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:24 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-FWfEtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:24 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-F61hQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-mQbGgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-KlfQlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-vXdcu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-X5krUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-CO18lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-fuAJD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-uA_4tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-XVvLxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-LPuriR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:25 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zkzxUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:26 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-viQGb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:26 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-XfRj3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:26 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-MfOkAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:26 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-nwQhDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:26 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-FnzKRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:50:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50:29 INFO - Downloading blessings-1.6.tar.gz 15:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:50:29 INFO - Installing collected packages: blessings 15:50:29 INFO - Running setup.py install for blessings 15:50:29 INFO - Successfully installed blessings 15:50:29 INFO - Cleaning up... 15:50:29 INFO - Return code: 0 15:50:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:50:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:30 INFO - Reading from file tmpfile_stdout 15:50:30 INFO - Current package versions: 15:50:30 INFO - blessings == 1.6 15:50:30 INFO - blobuploader == 1.2.4 15:50:30 INFO - docopt == 0.6.1 15:50:30 INFO - functools32 == 3.2.3-2 15:50:30 INFO - jsonschema == 2.5.1 15:50:30 INFO - manifestparser == 1.1 15:50:30 INFO - mozInstall == 1.12 15:50:30 INFO - mozcrash == 1.0 15:50:30 INFO - mozdebug == 0.1 15:50:30 INFO - mozdevice == 0.49 15:50:30 INFO - mozfile == 1.2 15:50:30 INFO - mozhttpd == 0.7 15:50:30 INFO - mozinfo == 0.9 15:50:30 INFO - mozleak == 0.1 15:50:30 INFO - mozlog == 3.4 15:50:30 INFO - moznetwork == 0.27 15:50:30 INFO - mozprocess == 0.23 15:50:30 INFO - mozprofile == 0.28 15:50:30 INFO - mozrunner == 6.13 15:50:30 INFO - mozscreenshot == 0.1 15:50:30 INFO - mozsystemmonitor == 0.3 15:50:30 INFO - moztest == 0.7 15:50:30 INFO - mozversion == 1.4 15:50:30 INFO - psutil == 3.1.1 15:50:30 INFO - requests == 1.2.3 15:50:30 INFO - wsgiref == 0.1.2 15:50:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:30 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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:50:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:50:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:30 INFO - 'HOME': '/Users/cltbld', 15:50:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:30 INFO - 'LOGNAME': 'cltbld', 15:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:30 INFO - 'MOZ_NO_REMOTE': '1', 15:50:30 INFO - 'NO_EM_RESTART': '1', 15:50:30 INFO - 'PAGER': '/bin/cat', 15:50:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:30 INFO - 'PWD': '/builds/slave/test', 15:50:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:30 INFO - 'SHELL': '/bin/bash', 15:50:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:30 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:30 INFO - 'USER': 'cltbld', 15:50:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:30 INFO - 'XPC_FLAGS': '0x0', 15:50:30 INFO - 'XPC_SERVICE_NAME': '0', 15:50:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:50:30 INFO - Cleaning up... 15:50:30 INFO - Return code: 0 15:50:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:30 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 0x102a583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc63d93540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, '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.laEXQja3Dx/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.K3ohBisZzP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:50:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:50:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:50:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:50:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:30 INFO - 'HOME': '/Users/cltbld', 15:50:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:30 INFO - 'LOGNAME': 'cltbld', 15:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:30 INFO - 'MOZ_NO_REMOTE': '1', 15:50:30 INFO - 'NO_EM_RESTART': '1', 15:50:30 INFO - 'PAGER': '/bin/cat', 15:50:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:30 INFO - 'PWD': '/builds/slave/test', 15:50:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:30 INFO - 'SHELL': '/bin/bash', 15:50:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:50:30 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:50:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:30 INFO - 'USER': 'cltbld', 15:50:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:30 INFO - 'XPC_FLAGS': '0x0', 15:50:30 INFO - 'XPC_SERVICE_NAME': '0', 15:50:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:50:30 INFO - Cleaning up... 15:50:30 INFO - Return code: 0 15:50:30 INFO - Running post-action listener: _resource_record_post_action 15:50:30 INFO - Running post-action listener: _start_resource_monitoring 15:50:30 INFO - Starting resource monitoring. 15:50:30 INFO - [mozharness: 2016-12-08 23:50:30.830071Z] Finished create-virtualenv step (success) 15:50:30 INFO - [mozharness: 2016-12-08 23:50:30.831085Z] Running install step. 15:50:30 INFO - Running pre-action listener: _resource_record_pre_action 15:50:30 INFO - Running main action method: install 15:50:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:31 INFO - Reading from file tmpfile_stdout 15:50:31 INFO - Detecting whether we're running mozinstall >=1.0... 15:50:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:50:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:50:31 INFO - Reading from file tmpfile_stdout 15:50:31 INFO - Output received: 15:50:31 INFO - Usage: mozinstall [options] installer 15:50:31 INFO - Options: 15:50:31 INFO - -h, --help show this help message and exit 15:50:31 INFO - -d DEST, --destination=DEST 15:50:31 INFO - Directory to install application into. [default: 15:50:31 INFO - "/builds/slave/test"] 15:50:31 INFO - --app=APP Application being installed. [default: firefox] 15:50:31 INFO - mkdir: /builds/slave/test/build/application 15:50:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:50:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:51:04 INFO - Reading from file tmpfile_stdout 15:51:04 INFO - Output received: 15:51:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:51:04 INFO - Running post-action listener: _resource_record_post_action 15:51:04 INFO - [mozharness: 2016-12-08 23:51:04.075542Z] Finished install step (success) 15:51:04 INFO - [mozharness: 2016-12-08 23:51:04.075668Z] Running run-tests step. 15:51:04 INFO - Running pre-action listener: _resource_record_pre_action 15:51:04 INFO - Running main action method: run_tests 15:51:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:51:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:51:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:51:04 INFO - 2016-12-08 15:51:04.088 screenresolution[1905:18321] starting screenresolution argv=screenresolution get 15:51:04 INFO - 2016-12-08 15:51:04.091 screenresolution[1905:18321] Display 0: 1600x1200x32@60 15:51:04 INFO - 2016-12-08 15:51:04.096 screenresolution[1906:18324] starting screenresolution argv=screenresolution list 15:51:04 INFO - Available Modes on Display 0 15:51:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:51:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:51:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:51:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:51:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:51:04 INFO - Intel Iris: 15:51:04 INFO - Chipset Model: Intel Iris 15:51:04 INFO - Type: GPU 15:51:04 INFO - Bus: Built-In 15:51:04 INFO - VRAM (Dynamic, Max): 1536 MB 15:51:04 INFO - Vendor: Intel (0x8086) 15:51:04 INFO - Device ID: 0x0a2e 15:51:04 INFO - Revision ID: 0x0009 15:51:04 INFO - Displays: 15:51:04 INFO - Display: 15:51:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:51:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:51:04 INFO - Display Serial Number: 1600x1200 60 15:51:04 INFO - Main Display: Yes 15:51:04 INFO - Mirror: Off 15:51:04 INFO - Online: Yes 15:51:04 INFO - Rotation: Supported 15:51:04 INFO - Return code: 0 15:51:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:51:04 INFO - Python 2.7.3 15:51:04 INFO - Return code: 0 15:51:04 INFO - grabbing minidump binary from tooltool 15:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:04 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 0x10286dd30>, '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 0x1026d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102865b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:51:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:51:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:51:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:51:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:51:04 INFO - Return code: 0 15:51:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:51:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:51:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:51:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:51:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:51:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:51:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.laEXQja3Dx/Render', 15:51:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:04 INFO - 'HOME': '/Users/cltbld', 15:51:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:04 INFO - 'LOGNAME': 'cltbld', 15:51:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:04 INFO - 'MOZ_NO_REMOTE': '1', 15:51:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:04 INFO - 'NO_EM_RESTART': '1', 15:51:04 INFO - 'PAGER': '/bin/cat', 15:51:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:04 INFO - 'PWD': '/builds/slave/test', 15:51:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:04 INFO - 'SHELL': '/bin/bash', 15:51:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3ohBisZzP/Listeners', 15:51:04 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 15:51:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:04 INFO - 'USER': 'cltbld', 15:51:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:04 INFO - 'XPC_FLAGS': '0x0', 15:51:04 INFO - 'XPC_SERVICE_NAME': '0', 15:51:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:51:04 INFO - mozversion application_buildid: 20161208133259 15:51:04 INFO - mozversion application_changeset: 02fd565e7be4772964dca269de08c21b6b46fef4 15:51:04 INFO - mozversion application_display_name: Nightly 15:51:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:51:04 INFO - mozversion application_name: Firefox 15:51:04 INFO - mozversion application_remotingname: firefox 15:51:04 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:51:04 INFO - mozversion application_vendor: Mozilla 15:51:04 INFO - mozversion application_version: 53.0a1 15:51:04 INFO - mozversion platform_buildid: 20161208133259 15:51:04 INFO - mozversion platform_changeset: 02fd565e7be4772964dca269de08c21b6b46fef4 15:51:04 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:51:04 INFO - mozversion platform_version: 53.0a1 15:51:04 INFO - using testdate: 1481241064 15:51:04 INFO - actual date: 1481241064 15:51:04 INFO - starting webserver on 'localhost:53675' 15:51:04 INFO - SUITE-START | Running 2 tests 15:51:04 INFO - TEST-START | dromaeo_css 15:51:04 INFO - operating with platform_type : mac_ 15:51:04 INFO - Initialising browser for dromaeo_css test... 15:51:04 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpFRxZBP/profile http://localhost:53675/getInfo.html) 15:51:07 INFO - PROCESS | 1916 | 2016-12-08 15:51:07.905 plugin-container[1918:18456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 15:51:07 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:51:07 INFO - PROCESS | 1916 | 2016-12-08 15:51:07.908 plugin-container[1918:18456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a2b, name = 'com.apple.CFPasteboardClient' 15:51:07 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:51:07 INFO - PROCESS | 1916 | 2016-12-08 15:51:07.908 plugin-container[1918:18456] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:51:07 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 15:51:07 INFO - PROCESS | 1916 | colorDepth:24 15:51:07 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 15:51:07 INFO - PROCESS | 1916 | __metrics 15:51:08 ERROR - PROCESS | 1916 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 15:51:08 INFO - TEST-INFO | 1916: exit 0 15:51:08 INFO - Browser initialized. 15:51:08 INFO - Running cycle 1/1 for dromaeo_css test... 15:51:08 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpFRxZBP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:11 INFO - PROCESS | 1920 | 2016-12-08 15:51:11.595 plugin-container[1921:18579] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.tsm.portname' 15:51:11 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:51:11 INFO - PROCESS | 1920 | 2016-12-08 15:51:11.596 plugin-container[1921:18579] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 15:51:11 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:51:11 INFO - PROCESS | 1920 | 2016-12-08 15:51:11.596 plugin-container[1921:18579] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:51:21 INFO - PROCESS | 1920 | RSS: Main: 247824384 15:51:21 INFO - PROCESS | 1920 | 15:52:10 INFO - PROCESS | 1920 | 1481241130853 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:54:24 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:53675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53675/tests/dromaeo/cssquery-ext.html) 15:54:26 INFO - PROCESS | 1920 | RSS: Main: 254824448 15:54:26 INFO - PROCESS | 1920 | 15:57:28 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:53675/tests/dromaeo/cssquery-ext.html (next: http://localhost:53675/tests/dromaeo/cssquery-jquery.html) 15:57:29 INFO - PROCESS | 1920 | RSS: Main: 264392704 15:57:29 INFO - PROCESS | 1920 | 16:00:31 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:53675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53675/tests/dromaeo/cssquery-mootools.html) 16:00:31 INFO - PROCESS | 1920 | RSS: Main: 268894208 16:00:31 INFO - PROCESS | 1920 | 16:03:33 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:53675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53675/tests/dromaeo/cssquery-prototype.html) 16:03:34 INFO - PROCESS | 1920 | RSS: Main: 268124160 16:03:34 INFO - PROCESS | 1920 | 16:06:37 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:53675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53675/tests/dromaeo/cssquery-yui.html) 16:06:38 INFO - PROCESS | 1920 | RSS: Main: 272613376 16:06:38 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:53675/tests/dromaeo/cssquery-yui.html (next: http://localhost:53675/tests/dromaeo/cssquery-dojo.html) 16:09:40 INFO - PROCESS | 1920 | RSS: Main: 275136512 16:09:40 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | __start_tp_report 16:09:40 INFO - PROCESS | 1920 | _x_x_mozilla_page_load 16:09:40 INFO - PROCESS | 1920 | _x_x_mozilla_page_load_details 16:09:40 INFO - PROCESS | 1920 | |i|pagename|runs| 16:09:40 INFO - PROCESS | 1920 | |0;dojo.html;3405.387030334539;3407.6251612322644;3408.2842603053364;3409.6931276185137;3410.164509695125;5835.183074369586;5869.706514674265;5878.0301250293705;5932.911006334898;5950.285965684113;5041.243813427987;5495.945040549594;5922.851928701773;5957.821265362035;5993.670348130862;4941.308216849644;5197.532222100011;5329.786808527654;5337.3061502004875;5366.9463305366835;5213.921791173136;5296.523312901838;5356.196570514415;5444.809431669893;5455.1271796512565;5295.761690723926;5305.575553955674;5321.228421878825;5338.225957236198;5339.359276886759;4656.905627177611;4854.6359023073055;5196.61025423091;5215.687058776485;5317.229001794718;5245.79016839326;5265.394479634852;5288.206768984644;5290.259363689087;5358.892822143574;5242.528172464458;5889.646621202742;5907.320658124328;5924.348321684612;5931.347551769302;6173.2900716417735;6180.412860778264;6183.536234782365;6189.783357582463;6228.626282423023;4682.204025315705;5091.821786237501;6091.90389342639;6155.292141403751;6545.574537655085;5001.899582091934;5645.887082258332;5866.882662346772;6030.30651475086;6057.697115144225;6140.263168419729;6205.689715514206;6345.555811093179;6350.428461438492;6375.553711240169;6080.665563394055;6123.479504242189;6136.0902143038675;6147.754089836446;6195.81412557624;5300;6199.938000620026;6270.968645156836;6272.18461599989;6303.684815759194;4777.737224452619;5073.7716802743225;6121.5139442231075;6166;6194.473469755031;6235.282942461675;6236.6258024518675;6239.500839932794;6260.436560709558;6268.216472940882;4971.1797553404;4993.525615066563;5015.84952451427;5025;5035.320231768737;4497.8425755098415;4502.099580083996;4508.729476231437;4517.41273634432;4537.160625284333;5842.970785146132;6031.668258245751;6073.058675905242;6076.75692972285;6087.086936959403;6176.66028368435;6184.567080304336;6187.102870083902;6228.750849965951;6247.031710084944;5089.440161582223;5969.403059693996;6031.698415079229;6036.939630603725;6039.63762174271;5521.806736764274;5525.668459892339;5543.223948647193;5543.307086614173;5552.888942221133;6235.064740288903;6241.001439769797;6330.493560515056;6331.145295385064;6428.839279018062;6304.432601065926;6310.463610593151;6343.080253363329;6349.523785716182;6400.039994000937;5804.3905389935135;6393.6483493408305;6402;6433.935660643334;6480.319566445644;5138.306328645585;6013.609115407659;6141.884937092248;6259.499240060875;6279.183706118175;5134.6662466941025;5961.101288204017;6166.383361663798;6207.844803879939;6273.65494897785;5584.274044374368;6078.149059131813;6230.968845155927;6232.034034724625;6241.095041218907;6129.233845769279;6135.98756205239;6145.248604147797;6169.56813023084;6172.845678858064;6057.273127224761;6090.147379366804;6115.938840611537;6137.835406613001;6150.661713605795;6136.6011209271255;6173.382661733791;6177.4749146323065;6180.783672571439;6181.196444462191;6043.21404456456;6119.020956646915;6128.356522565066;6130.417610327012;6176.629402235881 16:09:40 INFO - PROCESS | 1920 | |1;ext.html;501.42336313239775;518.75099952023;524.897644959233;527.7783331000979;542.869711269295;37523.249535009316;39416.80291598545;39423.80288098559;39604.20791584167;39628;39979.80010099953;40483.59516404835;40496.39255411171;40550.59449405505;40692.796536017355;8130.471519351223;8350.415470917038;9203.401778884385;9383.202427793645;9434.622615095388;8556.18716221961;11077.612283570077;11334.603288884891;11377.94311028448;11510.021648159916;11384.544618215261;11443.970042696155;11449.484773185184;11457.140714446408;11465.59870404536;11235.325880447159;11316.094712422986;11327;11349.602763903224;11359.716007099889;11236;11291.322520648788;11298.152638552141;11323.490442930088;11330.660080197607;37981;38048;38256.80871595652;38279.85160445191;38331.85004449871;3239.6274428440797;3250.561174241471;3307.2228026413773;3716.888493345177;4040.1313717550574;29523.70476295231;29579.70420295791;29786.451577634285;30269.394612107855;31003.689963100307;3180.093673303121;3206.9417092359467;3215.1801290670965;3250.6424293327714;3376.74674399421;467901;469776;476876;481396;481676;61859;63352.68323658352;64547;64578.677106615105;64658.67670661616;67069.6646516774;67096;67474;67662.98505522421;67672.58619310542;15629.218539073001;16209.837901620833;16221.59446013859;16265.268062937197;16267.755983660254;11155.832662510069;11346.262492937933;12155.939220303842;12266.141370104007;12426.130170888131;19035.809641903405;19347.4195774127;19393.709094363312;19400.902995485216;19506.02469876529;6872.553284036521;7209.675564599605;7972.960135199287;8146.796330091676;8177;812.8374325134997;863.8920134983127;879.7360791762574;985.5811280206027;1009.8182327181178;27571.86214068957;29125.85437072801;29179.832806687496;30165.849170754005;31877.681223187472;971.8104969530874;1002.644061358222;1027.342500799489;1029.4801125431609;1034.2760067952463;14697.41210351574;14876.404943802343;14934.775978360334;15000.849991500163;15027.849721502645;649045;658275.6689732453;658710;662613;663051;51913;52377.476225238024;52547;52645;52679.47320526822;1310.7181955879341;1343.7245364700213;1349.8920086393066;1362.2848004797363;1367.172860419432;24454.021839126235;27658.308542286602;28410.57947102678;28667.27660872214;29082.41835163327;47365.76317118392;47961.5203847957;48002.79993000203;48097.75951120222;48272;603574;604012;604110;604584;606168;23236.76763232346;23585.646215306442;25692.816110630058;26068.608970865833;26841.328966775207;35563.57745690143;44009;44926.77536612296;45658.54341456543;45660;30723.539146913263;36832.26335473329;37178.442323365714;37295.06762330963;37909.620903791714;32967.01098967035;35570.822145889106;36724.63275367212;37634.8118259407;38082;36017;36235.637643623224;36412.81793591015;36544.45183322199;37024;33279.833600831844;33296.500552492216;33476.49785253272;33756;34575.65424345825;22425.32724018282;24523.264302070966;24709.87645061763;25091.87454062718;25416.618750718386 16:09:40 INFO - PROCESS | 1920 | |2;jquery.html;320.773854432625;325.3509249048149;342.92798512312413;380.6421963354445;390.4143784323515;18602.25590976361;19306.613867722637;19541.413757587277;19645.31241406551;19735.013249337513;17677.7348339775;20563.691544626843;20942.37172884812;21312.040958156882;21486.89256553719;6000.009998350267;6416.871662566746;6728.899066514006;6754.66226688666;6798.150231221098;6562.639054851981;6720.193576770795;6731.09130267415;6745.224299205581;6785.355391237825;6470.611763294218;6712.160979877515;6734.225564060147;6793.966030169842;6895.793126206222;6151.630902145886;6763.898541521881;6797.728090876359;6822.4200942919915;7137.321954414322;7246.7463638772915;7399.4450416219;7408.481406301561;7418.962905185494;7419;26297.868510657514;26429.074982375525;27136.592951105747;27236.59145112825;27511.31221719453;2221.366910430536;2262.988685056564;2284.5430913817304;2287.759785222561;2469.76537228963;15578.118048161126;16319;16536.090515021668;16819.495415137564;17119.747248022835;1926.5568919148534;1928.2672584417971;1977.377126205255;2016.878987260769;2068.90689918954;123029.76970230273;125078.24765257057;125392;133092.33453832765;139325.3033734835;20872.37382878516;21366.57266854654;21398.358049258546;21489.247876324254;21550;34704.132396690286;34706.13234669103;34722.78470253529;34800.47799283013;34830.82584587111;8971.013188549254;10122.15557228547;10344.5344959477;10481.213908956859;10749.62376316825;8514.191151840565;8612.18184272493;8642.524661143572;8758;8803.867941980876;8851.64593416269;9088.681896133603;9456;9638.518074096268;9677.564509597085;3193.6327322358225;3301.8443544759143;3695.5934847167327;3732.8133593320226;3812.4853144825647;795.3716563914559;835.1815221083251;871.7864123289835;902.1429641840278;926.9896604999395;19151.007934645422;19680.40958771239;19820.504487387643;19827.909464979275;20028.59942801165;816.3550794871985;920.2224120618067;931.1014870649856;943.7027336389016;956.2015716876347;9248.907510924804;10469.895301047045;10497.107745841537;10501.002404771534;10519.368837869752;499653;573097.1345143331;575812;587077;587548;114151;119235;122701;126927.36536317444;129455.05817412747;963.3834346018554;977.1449981266392;1006.9194464442827;1007.2495990487134;1013.9442330671884;17064.317427303264;17533.035683037557;17587.29650813953;17935.013574253542;17957.10214489244;41435.1712965745;43812;47343.81640459016;48206.794830129526;53050;69541;77523;79906;80479.19520804717;80799;7478.551286922802;7605.315521603082;7783.338416234684;7806.258405451473;8073.636686349246;10152.898471015491;11849.585264515701;12220.572279970158;12222.511099555919;12349.221425179263;10429.478526073817;10456.372617642966;10710.143188544742;10722;10833.187510936868;10155.441450720282;10382.688519344432;10684.626038088629;10808.135349172204;10876.293040952312;10476.528556214836;10535.4732263385;10902.727431814268;11030.172737044913;11122;7688.8077798053255;7791.766247012599;7821.413393995359;8010.319122874388;8024.334297397168;9040.367174297999;9960;10516.316439431412;10819.242653014213;10839.458027098772 16:09:40 INFO - PROCESS | 1920 | |3;mootools.html;1471.124680814915;1517.5447365790264;1689.6029433083231;1787.5084351803243;1818.7726534183219;7468.51454655447;7667.3866090712745;8085.151059138794;8470.407071504997;8490.490570565762;7980.880286795688;8038.517688938653;8261.297789687884;8409.159084091587;8431.747047588568;2272.556851413971;2321.7330007049236;2323.4191452136965;2329.312852708447;2334.673145759595;2101.949025487256;2307.7461479237354;2328.639060945556;2351.0243249051623;2355.3993731598416;1984.9281388050435;2044.8140078754286;2097.286922446368;2120.978790212101;2327.592671282527;1908.3225454963497;2297.173017713622;2297.7357603875603;2309.84985975911;2310.9768902311016;2300.5168914527967;2320.640300753386;2321.1063740460045;2323.581755284048;2336.5677349690363;7239.674214660353;8411.369147313915;8414.410991230616;8452;8485.787855303604;5024.095662780698;5134.948650513485;5163.896722065575;5167.819126330598;5169.767360468788;3084.4293805646025;3272.443684573628;3487.5640544931885;3490.336836001177;3496.965030349689;1033.2508931025022;1036.43514284715;1071.276888100529;1073.0717928991444;1100.8403781451725;8894.02165761772;10508.0542751152;10825.350478971286;10879.83680244797;10905.618303359424;4469.217886869785;4536.659750518725;4619.313950408077;4627.120847039119;4690.624750019991;9339.066093390607;9446.244300455946;9446.85829712555;9449.90550094504;9461.38500997433;5151.2530683050745;5169.534741873174;5169.53474187325;5184.870378240499;5186.844394668091;4228.112096459717;4250.176050183064;4664.160451118763;4689.976550117296;4749.002709431058;3718.3307004738867;3772.0946972726347;3822.980885095557;3829.7702137871815;3887.455756194135;1273.6879464531064;1278.7953927371761;1278.833751612303;1284.4926254129753;1289.9871001290055;702.6205848841671;850.9531975741394;897.1925267259388;906.3791302957495;934.4440058165517;5446.2375267462585;5458.426865179099;5735.423743921471;5785.8553536161935;5799.043157878987;952.8999455057258;981.4111533080237;998.7253505286104;1031.1544533482622;1065.8934106589434;3728.2357116790995;3734.514513113332;3749.9250014999548;3853.5183102112237;3860.8841734748003;9722.902770972341;9940.701778946643;10023.749406264757;10042.748931276776;10078.949605251926;9940.502974851228;9998.750031249132;10002.34984726005;10042.447665378475;10044.296899216984;7610.429217808576;7679.232076792276;7764.22357764219;7841.176676449005;7905.525668459911;5265.78936842522;5428.755705993319;5434.945650543602;5495.862603434786;5544.445555444585;9348.766280843034;9374.625014999323;9388.061193880558;9405.341626086109;9418.717438476857;7413.221611730691;9457.668981585612;9464.337496375188;9481.09929556691;9481.66814161501;7095.184053833668;7329.340359367469;7546.207648196861;7741.032370953631;7966.561839098674;7807.336376408069;7916.525008499509;8001.799955001172;8033;8076.959615201887;6118.449339559461;7015.649217539205;7086.539374940612;7098.609576473344;7136.964315178391;6767.221769496571;6807.3873351398615;6956.2000369958105;6973.721051157897;7029.472789540702;5855.443732845373;6537.934620653923;6565.80302590923;6762.222344430454;6762.289959554176;3622.2393297406784;4116.279651060992;4275.636570891509;4339.370791235317;4475.552444755498;4126.649234815074;4665.20691482442;4797.160496913096;4890.584300334512;4962.826301079445 16:09:40 INFO - PROCESS | 1920 | |4;prototype.html;273.9904103356383;301.385174244541;309.45072496319017;311.7957737681819;314.62245305633246;5265.394479634843;5366.543843773275;5373.656585853537;5454.972725136378;5469.808556700516;5190.079670521903;5275.577953763699;5322.680639161644;5350.35795704516;5362.919556206651;4072.735272207312;4120.320147175712;4122.958770412288;4128.772917489537;4140.896477588054;3647.6503693633395;3990.9800450997705;3995.60043995601;4103.707332190365;4155.189738001091;3466.7746596471275;4051.5948405159543;4056.553779084298;4078.89802754932;4098.995746042224;3696.815159242027;3956.5054368203973;4039.9596004039877;4066.369712694537;4081.4898137732484;3298.6041674998914;3385.6118991213484;3576.535050443426;3579.713622910161;3610.981945090284;3966;5061.898762024739;5080.949190508121;5137.998090372379;5206.843794686166;4304.655627549789;4536.682432229745;4655.767211639439;4684.758538987171;4699.7415142167165;4884.365032545782;4911.852644420673;5600.887982240332;5622.465865742748;5649.096144616841;2829.589709492112;3316.6849149330774;3331.51693004513;3398.235397035672;3777.414500752388;5982.641041537521;5988.640681559077;6012.338642749294;6014.909776353358;6016.75095448453;4074.5925407459017;4089.284375234322;4090.9386359204636;4092.01791570027;4107.835686572504;3602.369585322558;3888.2199227552437;4148.502179738451;4152.418661387408;4178.26880295947;4215.494140703136;4521.299198624219;4748.738819364969;4764.618830493552;4783.5751929465105;5206.0368831766245;5217.739113044333;5764.337101233328;5770.451807078321;5837.854053648608;1115.453427820362;1289.426205338634;1294.7345794112184;1337.4315915735774;1346.8855147312588;912.8174365127002;924.912133347331;927.7958849053198;931.2131249094505;934.3879758758025;4351.69538132334;4913.31213630092;5087.720175390389;5111.846644600668;5123.615728820353;5089.720065396439;5116.360454943132;5357.303550538405;5589.311054703876;5643.633163844337;4410.779461027;4716.3868697069165;4867.780949857265;5019.415001274347;5119.923201151985;2425.5755242832433;2589.8964041438508;2901.695321991203;2922.5616157576533;2925.7711761060405;4615.838445654453;5164.8192313268855;5726.79956201531;5752.338481074647;5790.420957904176;4948.975255123702;5652.406497317805;5691.3170419549915;5699.686517241508;5725.914111288334;5362.544183744425;5713.657180569179;5775.971120144372;5811.651300921958;5828.213191219216;4818.445878723852;5208.0469823816065;5272.367315922113;5497;5565.944340556704;4844;5381.27352807366;5478.315210598676;5510.228568000563;5524.613277070566;5058.747062646779;5117.974410127926;5327.334083239595;5389.299391079135;5695.519165017043;3527.536072529974;3553.822308884494;3735.533058367704;3760.2103558253075;3797.962020379761;4616.676832621819;4815.627546149331;4904.485029071896;4938.43208031081;5051.570616497639;4677;4900.338454308623;4920.1389756791705;5073.771680274471;5201.349831271091;5309.946900531099;5318.760655770577;5551.362348107399;5693.629914055573;5714.742836572281;5013.523715247045;5329.934013197453;5526.585506086979;5665.6204394155375;5667.348254950569;4815.133276010211;5491.780328786804;5779.797707080232;5792.986227410271;5794.565407594363;5264.447233040476;5489.862753431036;5633.971830140987;5671.6597004179885;5696.344920334214 16:09:40 INFO - PROCESS | 1920 | |5;yui.html;689.9689513971871;710.6411262312531;712.1027505343266;726.7202127181034;737.1485933746525;13990.860091399083;14629.707405851877;15230;15416.460423885166;16040.438584649539;15546.378144874192;15754.921225393857;16001.519954401358;16051.117188554654;16064.357425702958;815.9347252219837;845.5772113943028;854.7734850264685;856.8646543330082;858.0861382627513;663.0419044480727;682.890737482003;826.9421140520166;850.6172222499869;859.9527026013567;893.0176805513947;896.7623579751371;897.407710910799;897.7037577599409;897.8877640294963;873.6898401067593;887.4586502233632;887.5074333744769;889.7108439757072;890.6437425029975;610.6763415389851;663.9568428052161;664.5016237821634;666.3702800853196;666.8766278238542;12336.321502317369;14291.928540357232;14807.851921480755;14858.702825943421;14863.479778207711;13754.587362379145;13845.861541384658;14383.568492945229;14482.855171448256;14511.854881451156;11466.31202127875;11757.706057348549;11818.881811181864;11828.467718952668;12161.391930403533;3376.18971446851;3445.2937147884622;3754.249150169977;3769.2461507698295;3856.5372155341256;85796.14203858006;100289.4985525075;102494.48752756188;115644;116906;10997;11062.944685276521;11151.21941464106;11161.609343672932;11545.653630391102;18729.157187926576;22551.88724056402;22644.886775566345;22731.977061032292;22930.445780903985;7633.2366763324335;7754.185810489817;7844;7948.682052717826;8043.597820108971;4388.648908087345;4395.27477966132;4408.189347458185;4428.13651337986;4454.643628509712;3406.489026646023;3533.681968622785;3568.107973006748;3569.196930690574;3570.357335679603;643.4466358931315;645.2321737132797;653.2422390027546;656.4846595422571;674.069783698493;461.25046798951706;604.2899592978233;635.7107516080173;668.7191379620571;681.2268075733979;8582.957085214533;9192.678256261133;9380.296477764196;9392.624295028123;9444.386114902509;545.1795048452134;580.5094694982732;594.925634295713;600;656.0127008851744;4077.7757223352405;4424.557544245614;4535.115652447807;4617.930731039037;4631.58315751584;66261;103206;104324.95675043305;104489;110067;89985;91345.08654913498;93449;96222;99696;81222;85233.14766852376;86644;88032;88398;7141.178764441948;8732.08313127138;8767.868481972648;8801.07588703177;8975.955120224357;44113.558864410945;45943.540564593925;49326.76683082952;52499.21251181159;54684.453155467934;52498.47501524936;60019.099713503456;60040.39959600523;69170;69191.65404172947;12922.353882306035;12938.676533086747;12972.870271297166;13342.866571334162;13599.59201223965;14251.144931304156;14314.212718300594;14481.565553033426;14639.707205856037;14686.265686715835;13355.866441335462;13452.730945381234;13615.931920340336;13895;14024.789628155382;11342.092632589209;13675.384607692478;13996.300184990914;13998.300084995913;14036.438542458187;11056.889431105586;12930.05483462792;13232.338383081;13511.86488135106;13562.321883905568;9463.668771592962;9990.600375984879;10920.945395272973;11756.58851940178;11794.997425218953;8736.126387361213;9611.663591774255;11212.327260364404;11449;11592.594259200887 16:09:40 INFO - PROCESS | 1920 | __end_tp_report 16:09:40 INFO - PROCESS | 1920 | __start_cc_report 16:09:40 INFO - PROCESS | 1920 | _x_x_mozilla_cycle_collect,360 16:09:40 INFO - PROCESS | 1920 | __end_cc_report 16:09:40 INFO - PROCESS | 1920 | __startTimestamp1481242180958__endTimestamp 16:09:40 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | ------- Summary: start ------- 16:09:40 INFO - PROCESS | 1920 | Number of tests: 6 16:09:40 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | [#0] dojo.html Cycles:165 Average:5747.07 Median:6043.21 stddev:656.17 (10.9%) stddev-sans-first:631.95 16:09:40 INFO - PROCESS | 1920 | Values: 3405.4 3407.6 3408.3 3409.7 3410.2 5835.2 5869.7 5878.0 5932.9 5950.3 5041.2 5495.9 5922.9 5957.8 5993.7 4941.3 5197.5 5329.8 5337.3 5366.9 5213.9 5296.5 5356.2 5444.8 5455.1 5295.8 5305.6 5321.2 5338.2 5339.4 4656.9 4854.6 5196.6 5215.7 5317.2 5245.8 5265.4 5288.2 5290.3 5358.9 5242.5 5889.6 5907.3 5924.3 5931.3 6173.3 6180.4 6183.5 6189.8 6228.6 4682.2 5091.8 6091.9 6155.3 6545.6 5001.9 5645.9 5866.9 6030.3 6057.7 6140.3 6205.7 6345.6 6350.4 6375.6 6080.7 6123.5 6136.1 6147.8 6195.8 5300.0 6199.9 6271.0 6272.2 6303.7 4777.7 5073.8 6121.5 6166.0 6194.5 6235.3 6236.6 6239.5 6260.4 6268.2 4971.2 4993.5 5015.8 5025.0 5035.3 4497.8 4502.1 4508.7 4517.4 4537.2 5843.0 6031.7 6073.1 6076.8 6087.1 6176.7 6184.6 6187.1 6228.8 6247.0 5089.4 5969.4 6031.7 6036.9 6039.6 5521.8 5525.7 5543.2 5543.3 5552.9 6235.1 6241.0 6330.5 6331.1 6428.8 6304.4 6310.5 6343.1 6349.5 6400.0 5804.4 6393.6 6402.0 6433.9 6480.3 5138.3 6013.6 6141.9 6259.5 6279.2 5134.7 5961.1 6166.4 6207.8 6273.7 5584.3 6078.1 6231.0 6232.0 6241.1 6129.2 6136.0 6145.2 6169.6 6172.8 6057.3 6090.1 6115.9 6137.8 6150.7 6136.6 6173.4 6177.5 6180.8 6181.2 6043.2 6119.0 6128.4 6130.4 6176.6 16:09:40 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | [#1] ext.html Cycles:180 Average:70669.57 Median:35793.91 stddev:156417.22 (437.0%) stddev-sans-first:156767.29 16:09:40 INFO - PROCESS | 1920 | Values: 501.4 518.8 524.9 527.8 542.9 37523.2 39416.8 39423.8 39604.2 39628.0 39979.8 40483.6 40496.4 40550.6 40692.8 8130.5 8350.4 9203.4 9383.2 9434.6 8556.2 11077.6 11334.6 11377.9 11510.0 11384.5 11444.0 11449.5 11457.1 11465.6 11235.3 11316.1 11327.0 11349.6 11359.7 11236.0 11291.3 11298.2 11323.5 11330.7 37981.0 38048.0 38256.8 38279.9 38331.9 3239.6 3250.6 3307.2 3716.9 4040.1 29523.7 29579.7 29786.5 30269.4 31003.7 3180.1 3206.9 3215.2 3250.6 3376.7 467901.0 469776.0 476876.0 481396.0 481676.0 61859.0 63352.7 64547.0 64578.7 64658.7 67069.7 67096.0 67474.0 67663.0 67672.6 15629.2 16209.8 16221.6 16265.3 16267.8 11155.8 11346.3 12155.9 12266.1 12426.1 19035.8 19347.4 19393.7 19400.9 19506.0 6872.6 7209.7 7973.0 8146.8 8177.0 812.8 863.9 879.7 985.6 1009.8 27571.9 29125.9 29179.8 30165.8 31877.7 971.8 1002.6 1027.3 1029.5 1034.3 14697.4 14876.4 14934.8 15000.8 15027.8 649045.0 658275.7 658710.0 662613.0 663051.0 51913.0 52377.5 52547.0 52645.0 52679.5 1310.7 1343.7 1349.9 1362.3 1367.2 24454.0 27658.3 28410.6 28667.3 29082.4 47365.8 47961.5 48002.8 48097.8 48272.0 603574.0 604012.0 604110.0 604584.0 606168.0 23236.8 23585.6 25692.8 26068.6 26841.3 35563.6 44009.0 44926.8 45658.5 45660.0 30723.5 36832.3 37178.4 37295.1 37909.6 32967.0 35570.8 36724.6 37634.8 38082.0 36017.0 36235.6 36412.8 36544.5 37024.0 33279.8 33296.5 33476.5 33756.0 34575.7 22425.3 24523.3 24709.9 25091.9 25416.6 16:09:40 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | [#2] jquery.html Cycles:180 Average:35288.03 Median:323.06 stddev:94628.81 (29291.2%) stddev-sans-first:94857.84 16:09:40 INFO - PROCESS | 1920 | Values: 320.8 325.4 342.9 380.6 390.4 18602.3 19306.6 19541.4 19645.3 19735.0 17677.7 20563.7 20942.4 21312.0 21486.9 6000.0 6416.9 6728.9 6754.7 6798.2 6562.6 6720.2 6731.1 6745.2 6785.4 6470.6 6712.2 6734.2 6794.0 6895.8 6151.6 6763.9 6797.7 6822.4 7137.3 7246.7 7399.4 7408.5 7419.0 7419.0 26297.9 26429.1 27136.6 27236.6 27511.3 2221.4 2263.0 2284.5 2287.8 2469.8 15578.1 16319.0 16536.1 16819.5 17119.7 1926.6 1928.3 1977.4 2016.9 2068.9 123029.8 125078.2 125392.0 133092.3 139325.3 20872.4 21366.6 21398.4 21489.2 21550.0 34704.1 34706.1 34722.8 34800.5 34830.8 8971.0 10122.2 10344.5 10481.2 10749.6 8514.2 8612.2 8642.5 8758.0 8803.9 8851.6 9088.7 9456.0 9638.5 9677.6 3193.6 3301.8 3695.6 3732.8 3812.5 795.4 835.2 871.8 902.1 927.0 19151.0 19680.4 19820.5 19827.9 20028.6 816.4 920.2 931.1 943.7 956.2 9248.9 10469.9 10497.1 10501.0 10519.4 499653.0 573097.1 575812.0 587077.0 587548.0 114151.0 119235.0 122701.0 126927.4 129455.1 963.4 977.1 1006.9 1007.2 1013.9 17064.3 17533.0 17587.3 17935.0 17957.1 41435.2 43812.0 47343.8 48206.8 53050.0 69541.0 77523.0 79906.0 80479.2 80799.0 7478.6 7605.3 7783.3 7806.3 8073.6 10152.9 11849.6 12220.6 12222.5 12349.2 10429.5 10456.4 10710.1 10722.0 10833.2 10155.4 10382.7 10684.6 10808.1 10876.3 10476.5 10535.5 10902.7 11030.2 11122.0 7688.8 7791.8 7821.4 8010.3 8024.3 9040.4 9960.0 10516.3 10819.2 10839.5 16:09:40 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | [#3] mootools.html Cycles:180 Average:5380.45 Median:5165.86 stddev:2954.19 (57.2%) stddev-sans-first:2947.87 16:09:40 INFO - PROCESS | 1920 | Values: 1471.1 1517.5 1689.6 1787.5 1818.8 7468.5 7667.4 8085.2 8470.4 8490.5 7980.9 8038.5 8261.3 8409.2 8431.7 2272.6 2321.7 2323.4 2329.3 2334.7 2101.9 2307.7 2328.6 2351.0 2355.4 1984.9 2044.8 2097.3 2121.0 2327.6 1908.3 2297.2 2297.7 2309.8 2311.0 2300.5 2320.6 2321.1 2323.6 2336.6 7239.7 8411.4 8414.4 8452.0 8485.8 5024.1 5134.9 5163.9 5167.8 5169.8 3084.4 3272.4 3487.6 3490.3 3497.0 1033.3 1036.4 1071.3 1073.1 1100.8 8894.0 10508.1 10825.4 10879.8 10905.6 4469.2 4536.7 4619.3 4627.1 4690.6 9339.1 9446.2 9446.9 9449.9 9461.4 5151.3 5169.5 5169.5 5184.9 5186.8 4228.1 4250.2 4664.2 4690.0 4749.0 3718.3 3772.1 3823.0 3829.8 3887.5 1273.7 1278.8 1278.8 1284.5 1290.0 702.6 851.0 897.2 906.4 934.4 5446.2 5458.4 5735.4 5785.9 5799.0 952.9 981.4 998.7 1031.2 1065.9 3728.2 3734.5 3749.9 3853.5 3860.9 9722.9 9940.7 10023.7 10042.7 10078.9 9940.5 9998.8 10002.3 10042.4 10044.3 7610.4 7679.2 7764.2 7841.2 7905.5 5265.8 5428.8 5434.9 5495.9 5544.4 9348.8 9374.6 9388.1 9405.3 9418.7 7413.2 9457.7 9464.3 9481.1 9481.7 7095.2 7329.3 7546.2 7741.0 7966.6 7807.3 7916.5 8001.8 8033.0 8077.0 6118.4 7015.6 7086.5 7098.6 7137.0 6767.2 6807.4 6956.2 6973.7 7029.5 5855.4 6537.9 6565.8 6762.2 6762.3 3622.2 4116.3 4275.6 4339.4 4475.6 4126.6 4665.2 4797.2 4890.6 4962.8 16:09:40 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | [#4] prototype.html Cycles:180 Average:4474.11 Median:5016.47 stddev:1364.26 (27.2%) stddev-sans-first:1331.16 16:09:40 INFO - PROCESS | 1920 | Values: 274.0 301.4 309.5 311.8 314.6 5265.4 5366.5 5373.7 5455.0 5469.8 5190.1 5275.6 5322.7 5350.4 5362.9 4072.7 4120.3 4123.0 4128.8 4140.9 3647.7 3991.0 3995.6 4103.7 4155.2 3466.8 4051.6 4056.6 4078.9 4099.0 3696.8 3956.5 4040.0 4066.4 4081.5 3298.6 3385.6 3576.5 3579.7 3611.0 3966.0 5061.9 5080.9 5138.0 5206.8 4304.7 4536.7 4655.8 4684.8 4699.7 4884.4 4911.9 5600.9 5622.5 5649.1 2829.6 3316.7 3331.5 3398.2 3777.4 5982.6 5988.6 6012.3 6014.9 6016.8 4074.6 4089.3 4090.9 4092.0 4107.8 3602.4 3888.2 4148.5 4152.4 4178.3 4215.5 4521.3 4748.7 4764.6 4783.6 5206.0 5217.7 5764.3 5770.5 5837.9 1115.5 1289.4 1294.7 1337.4 1346.9 912.8 924.9 927.8 931.2 934.4 4351.7 4913.3 5087.7 5111.8 5123.6 5089.7 5116.4 5357.3 5589.3 5643.6 4410.8 4716.4 4867.8 5019.4 5119.9 2425.6 2589.9 2901.7 2922.6 2925.8 4615.8 5164.8 5726.8 5752.3 5790.4 4949.0 5652.4 5691.3 5699.7 5725.9 5362.5 5713.7 5776.0 5811.7 5828.2 4818.4 5208.0 5272.4 5497.0 5565.9 4844.0 5381.3 5478.3 5510.2 5524.6 5058.7 5118.0 5327.3 5389.3 5695.5 3527.5 3553.8 3735.5 3760.2 3798.0 4616.7 4815.6 4904.5 4938.4 5051.6 4677.0 4900.3 4920.1 5073.8 5201.3 5309.9 5318.8 5551.4 5693.6 5714.7 5013.5 5329.9 5526.6 5665.6 5667.3 4815.1 5491.8 5779.8 5793.0 5794.6 5264.4 5489.9 5634.0 5671.7 5696.3 16:09:40 INFO - PROCESS | 1920 | 16:09:40 INFO - PROCESS | 1920 | [#5] yui.html Cycles:180 Average:20411.00 Median:4441.39 stddev:29648.30 (667.5%) stddev-sans-first:29694.49 16:09:40 INFO - PROCESS | 1920 | Values: 690.0 710.6 712.1 726.7 737.1 13990.9 14629.7 15230.0 15416.5 16040.4 15546.4 15754.9 16001.5 16051.1 16064.4 815.9 845.6 854.8 856.9 858.1 663.0 682.9 826.9 850.6 860.0 893.0 896.8 897.4 897.7 897.9 873.7 887.5 887.5 889.7 890.6 610.7 664.0 664.5 666.4 666.9 12336.3 14291.9 14807.9 14858.7 14863.5 13754.6 13845.9 14383.6 14482.9 14511.9 11466.3 11757.7 11818.9 11828.5 12161.4 3376.2 3445.3 3754.2 3769.2 3856.5 85796.1 100289.5 102494.5 115644.0 116906.0 10997.0 11062.9 11151.2 11161.6 11545.7 18729.2 22551.9 22644.9 22732.0 22930.4 7633.2 7754.2 7844.0 7948.7 8043.6 4388.6 4395.3 4408.2 4428.1 4454.6 3406.5 3533.7 3568.1 3569.2 3570.4 643.4 645.2 653.2 656.5 674.1 461.3 604.3 635.7 668.7 681.2 8583.0 9192.7 9380.3 9392.6 9444.4 545.2 580.5 594.9 600.0 656.0 4077.8 4424.6 4535.1 4617.9 4631.6 66261.0 103206.0 104325.0 104489.0 110067.0 89985.0 91345.1 93449.0 96222.0 99696.0 81222.0 85233.1 86644.0 88032.0 88398.0 7141.2 8732.1 8767.9 8801.1 8976.0 44113.6 45943.5 49326.8 52499.2 54684.5 52498.5 60019.1 60040.4 69170.0 69191.7 12922.4 12938.7 12972.9 13342.9 13599.6 14251.1 14314.2 14481.6 14639.7 14686.3 13355.9 13452.7 13615.9 13895.0 14024.8 11342.1 13675.4 13996.3 13998.3 14036.4 11056.9 12930.1 13232.3 13511.9 13562.3 9463.7 9990.6 10920.9 11756.6 11795.0 8736.1 9611.7 11212.3 11449.0 11592.6 16:09:40 INFO - PROCESS | 1920 | -------- Summary: end -------- 16:09:40 INFO - PROCESS | 1920 | 16:09:41 INFO - TEST-INFO | 1920: exit 0 16:09:41 INFO - TEST-OK | dromaeo_css | took 1116950ms 16:09:41 INFO - TEST-START | kraken 16:09:41 INFO - operating with platform_type : mac_ 16:09:41 INFO - Initialising browser for kraken test... 16:09:41 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmp50PGXJ/profile http://localhost:53675/getInfo.html) 16:09:44 INFO - PROCESS | 1927 | 2016-12-08 16:09:44.910 plugin-container[1928:21177] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 16:09:44 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:09:44 INFO - PROCESS | 1927 | 2016-12-08 16:09:44.912 plugin-container[1928:21177] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 16:09:44 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:09:44 INFO - PROCESS | 1927 | 2016-12-08 16:09:44.912 plugin-container[1928:21177] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:09:44 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 16:09:44 INFO - PROCESS | 1927 | colorDepth:24 16:09:44 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 16:09:44 INFO - PROCESS | 1927 | __metrics 16:09:45 ERROR - PROCESS | 1927 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 16:09:45 INFO - TEST-INFO | 1927: exit 0 16:09:45 INFO - Browser initialized. 16:09:45 INFO - Running cycle 1/1 for kraken test... 16:09:45 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmp50PGXJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:48 INFO - PROCESS | 1929 | 2016-12-08 16:09:48.355 plugin-container[1930:21299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 16:09:48 INFO - PROCESS | 1929 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:09:48 INFO - PROCESS | 1929 | 2016-12-08 16:09:48.355 plugin-container[1930:21299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8737, name = 'com.apple.CFPasteboardClient' 16:09:48 INFO - PROCESS | 1929 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:09:48 INFO - PROCESS | 1929 | 2016-12-08 16:09:48.355 plugin-container[1930:21299] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:09:58 INFO - PROCESS | 1929 | RSS: Main: 247611392 16:09:58 INFO - PROCESS | 1929 | 16:10:01 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53675/tests/kraken/driver.html?testName=audio-beat-detection) 16:10:02 INFO - PROCESS | 1929 | RSS: Main: 258207744 16:10:02 INFO - PROCESS | 1929 | 16:10:04 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53675/tests/kraken/driver.html?testName=audio-dft) 16:10:05 INFO - PROCESS | 1929 | RSS: Main: 265756672 16:10:05 INFO - PROCESS | 1929 | 16:10:08 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53675/tests/kraken/driver.html?testName=audio-fft) 16:10:09 INFO - PROCESS | 1929 | RSS: Main: 266964992 16:10:09 INFO - PROCESS | 1929 | 16:10:11 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53675/tests/kraken/driver.html?testName=audio-oscillator) 16:10:11 INFO - PROCESS | 1929 | RSS: Main: 267808768 16:10:11 INFO - PROCESS | 1929 | 16:10:13 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53675/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:10:14 INFO - PROCESS | 1929 | RSS: Main: 268845056 16:10:14 INFO - PROCESS | 1929 | 16:10:17 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53675/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:18 INFO - PROCESS | 1929 | RSS: Main: 274464768 16:10:18 INFO - PROCESS | 1929 | 16:10:21 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53675/tests/kraken/driver.html?testName=imaging-desaturate) 16:10:22 INFO - PROCESS | 1929 | RSS: Main: 275116032 16:10:22 INFO - PROCESS | 1929 | 16:10:25 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53675/tests/kraken/driver.html?testName=json-parse-financial) 16:10:25 INFO - PROCESS | 1929 | RSS: Main: 274915328 16:10:25 INFO - PROCESS | 1929 | 16:10:27 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53675/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:28 INFO - PROCESS | 1929 | RSS: Main: 274173952 16:10:28 INFO - PROCESS | 1929 | 16:10:30 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53675/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:30 INFO - PROCESS | 1929 | RSS: Main: 274190336 16:10:30 INFO - PROCESS | 1929 | 16:10:33 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53675/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:33 INFO - PROCESS | 1929 | RSS: Main: 274214912 16:10:33 INFO - PROCESS | 1929 | 16:10:36 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:37 INFO - PROCESS | 1929 | RSS: Main: 274550784 16:10:37 INFO - PROCESS | 1929 | 16:10:40 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:41 INFO - PROCESS | 1929 | RSS: Main: 272306176 16:10:41 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:53675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53675/tests/kraken/driver.html?testName=ai-astar) 16:10:43 INFO - PROCESS | 1929 | RSS: Main: 275030016 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | __start_tp_report 16:10:43 INFO - PROCESS | 1929 | _x_x_mozilla_page_load 16:10:43 INFO - PROCESS | 1929 | _x_x_mozilla_page_load_details 16:10:43 INFO - PROCESS | 1929 | |i|pagename|runs| 16:10:43 INFO - PROCESS | 1929 | |0;ai-astar;109;121;118;119;117;131;119;117;117;120 16:10:43 INFO - PROCESS | 1929 | |1;audio-beat-detection;155;142;106;175;147;130;117;118;136;116 16:10:43 INFO - PROCESS | 1929 | |2;audio-dft;202;213;262;275;199;199;196;199;194;195 16:10:43 INFO - PROCESS | 1929 | |3;audio-fft;81;60;60;85;59;60;59;63;85;81 16:10:43 INFO - PROCESS | 1929 | |4;audio-oscillator;88;71;84;72;68;70;82;70;71;81 16:10:43 INFO - PROCESS | 1929 | |5;imaging-gaussian-blur;137;132;89;87;100;111;117;131;94;91 16:10:43 INFO - PROCESS | 1929 | |6;imaging-darkroom;99;99;125;175;102;146;108;167;166;99 16:10:43 INFO - PROCESS | 1929 | |7;imaging-desaturate;93;97;141;121;92;93;101;121;78;106 16:10:43 INFO - PROCESS | 1929 | |8;json-parse-financial;48;48;79;80;86;75;57;60;65;79 16:10:43 INFO - PROCESS | 1929 | |9;json-stringify-tinderbox;68;77;70;75;74;76;73;70;71;68 16:10:43 INFO - PROCESS | 1929 | |10;stanford-crypto-aes;87;91;91;93;108;98;89;88;88;96 16:10:43 INFO - PROCESS | 1929 | |11;stanford-crypto-ccm;155;150;146;142;166;141;161;166;194;157 16:10:43 INFO - PROCESS | 1929 | |12;stanford-crypto-pbkdf2;237;190;199;235;239;224;234;194;181;176 16:10:43 INFO - PROCESS | 1929 | |13;stanford-crypto-sha256-iterative;71;72;72;71;70;70;70;70;70;72 16:10:43 INFO - PROCESS | 1929 | __end_tp_report 16:10:43 INFO - PROCESS | 1929 | __start_cc_report 16:10:43 INFO - PROCESS | 1929 | _x_x_mozilla_cycle_collect,958 16:10:43 INFO - PROCESS | 1929 | __end_cc_report 16:10:43 INFO - PROCESS | 1929 | __startTimestamp1481242243403__endTimestamp 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | ------- Summary: start ------- 16:10:43 INFO - PROCESS | 1929 | Number of tests: 14 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#0] ai-astar Cycles:10 Average:118.80 Median:119.00 stddev:5.39 (4.5%) stddev-sans-first:4.40 16:10:43 INFO - PROCESS | 1929 | Values: 109.0 121.0 118.0 119.0 117.0 131.0 119.0 117.0 117.0 120.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:139.00 stddev:21.15 (15.2%) stddev-sans-first:21.06 16:10:43 INFO - PROCESS | 1929 | Values: 155.0 142.0 106.0 175.0 147.0 130.0 117.0 118.0 136.0 116.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#2] audio-dft Cycles:10 Average:213.40 Median:200.50 stddev:29.68 (14.8%) stddev-sans-first:31.19 16:10:43 INFO - PROCESS | 1929 | Values: 202.0 213.0 262.0 275.0 199.0 199.0 196.0 199.0 194.0 195.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#3] audio-fft Cycles:10 Average:69.30 Median:72.00 stddev:11.92 (16.6%) stddev-sans-first:11.86 16:10:43 INFO - PROCESS | 1929 | Values: 81.0 60.0 60.0 85.0 59.0 60.0 59.0 63.0 85.0 81.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:7.23 (9.4%) stddev-sans-first:6.14 16:10:43 INFO - PROCESS | 1929 | Values: 88.0 71.0 84.0 72.0 68.0 70.0 82.0 70.0 71.0 81.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:112.00 stddev:19.38 (17.3%) stddev-sans-first:17.68 16:10:43 INFO - PROCESS | 1929 | Values: 137.0 132.0 89.0 87.0 100.0 111.0 117.0 131.0 94.0 91.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#6] imaging-darkroom Cycles:10 Average:128.60 Median:171.00 stddev:31.80 (18.6%) stddev-sans-first:31.88 16:10:43 INFO - PROCESS | 1929 | Values: 99.0 99.0 125.0 175.0 102.0 146.0 108.0 167.0 166.0 99.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#7] imaging-desaturate Cycles:10 Average:104.30 Median:85.00 stddev:18.47 (21.7%) stddev-sans-first:19.13 16:10:43 INFO - PROCESS | 1929 | Values: 93.0 97.0 141.0 121.0 92.0 93.0 101.0 121.0 78.0 106.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:77.00 stddev:13.95 (18.1%) stddev-sans-first:12.85 16:10:43 INFO - PROCESS | 1929 | Values: 48.0 48.0 79.0 80.0 86.0 75.0 57.0 60.0 65.0 79.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.50 stddev:3.26 (4.4%) stddev-sans-first:3.08 16:10:43 INFO - PROCESS | 1929 | Values: 68.0 77.0 70.0 75.0 74.0 76.0 73.0 70.0 71.0 68.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:91.00 stddev:6.40 (7.0%) stddev-sans-first:6.42 16:10:43 INFO - PROCESS | 1929 | Values: 87.0 91.0 91.0 93.0 108.0 98.0 89.0 88.0 88.0 96.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#11] stanford-crypto-ccm Cycles:10 Average:157.80 Median:159.00 stddev:15.62 (9.8%) stddev-sans-first:16.53 16:10:43 INFO - PROCESS | 1929 | Values: 155.0 150.0 146.0 142.0 166.0 141.0 161.0 166.0 194.0 157.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:210.90 Median:229.00 stddev:25.24 (11.0%) stddev-sans-first:24.94 16:10:43 INFO - PROCESS | 1929 | Values: 237.0 190.0 199.0 235.0 239.0 224.0 234.0 194.0 181.0 176.0 16:10:43 INFO - PROCESS | 1929 | 16:10:43 INFO - PROCESS | 1929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:71.00 stddev:0.92 (1.3%) stddev-sans-first:0.97 16:10:43 INFO - PROCESS | 1929 | Values: 71.0 72.0 72.0 71.0 70.0 70.0 70.0 70.0 70.0 72.0 16:10:43 INFO - PROCESS | 1929 | -------- Summary: end -------- 16:10:43 INFO - PROCESS | 1929 | 16:10:44 INFO - TEST-INFO | 1929: exit 0 16:10:44 INFO - TEST-OK | kraken | took 62820ms 16:10:44 INFO - SUITE-END | took 1179s 16:10:44 INFO - Completed test suite (00:19:39) 16:10:44 INFO - javascript benchmark 16:10:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7337.930395494327, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3405.387030334539, 3407.6251612322644, 3408.2842603053364, 3409.6931276185137, 3410.164509695125, 5835.183074369586, 5869.706514674265, 5878.0301250293705, 5932.911006334898, 5950.285965684113, 5041.243813427987, 5495.945040549594, 5922.851928701773, 5957.821265362035, 5993.670348130862, 4941.308216849644, 5197.532222100011, 5329.786808527654, 5337.3061502004875, 5366.9463305366835, 5213.921791173136, 5296.523312901838, 5356.196570514415, 5444.809431669893, 5455.1271796512565, 5295.761690723926, 5305.575553955674, 5321.228421878825, 5338.225957236198, 5339.359276886759, 4656.905627177611, 4854.6359023073055, 5196.61025423091, 5215.687058776485, 5317.229001794718, 5245.79016839326, 5265.394479634852, 5288.206768984644, 5290.259363689087, 5358.892822143574, 5242.528172464458, 5889.646621202742, 5907.320658124328, 5924.348321684612, 5931.347551769302, 6173.2900716417735, 6180.412860778264, 6183.536234782365, 6189.783357582463, 6228.626282423023, 4682.204025315705, 5091.821786237501, 6091.90389342639, 6155.292141403751, 6545.574537655085, 5001.899582091934, 5645.887082258332, 5866.882662346772, 6030.30651475086, 6057.697115144225, 6140.263168419729, 6205.689715514206, 6345.555811093179, 6350.428461438492, 6375.553711240169, 6080.665563394055, 6123.479504242189, 6136.0902143038675, 6147.754089836446, 6195.81412557624, 5300.0, 6199.938000620026, 6270.968645156836, 6272.18461599989, 6303.684815759194, 4777.737224452619, 5073.7716802743225, 6121.5139442231075, 6166.0, 6194.473469755031, 6235.282942461675, 6236.6258024518675, 6239.500839932794, 6260.436560709558, 6268.216472940882, 4971.1797553404, 4993.525615066563, 5015.84952451427, 5025.0, 5035.320231768737, 4497.8425755098415, 4502.099580083996, 4508.729476231437, 4517.41273634432, 4537.160625284333, 5842.970785146132, 6031.668258245751, 6073.058675905242, 6076.75692972285, 6087.086936959403, 6176.66028368435, 6184.567080304336, 6187.102870083902, 6228.750849965951, 6247.031710084944, 5089.440161582223, 5969.403059693996, 6031.698415079229, 6036.939630603725, 6039.63762174271, 5521.806736764274, 5525.668459892339, 5543.223948647193, 5543.307086614173, 5552.888942221133, 6235.064740288903, 6241.001439769797, 6330.493560515056, 6331.145295385064, 6428.839279018062, 6304.432601065926, 6310.463610593151, 6343.080253363329, 6349.523785716182, 6400.039994000937, 5804.3905389935135, 6393.6483493408305, 6402.0, 6433.935660643334, 6480.319566445644, 5138.306328645585, 6013.609115407659, 6141.884937092248, 6259.499240060875, 6279.183706118175, 5134.6662466941025, 5961.101288204017, 6166.383361663798, 6207.844803879939, 6273.65494897785, 5584.274044374368, 6078.149059131813, 6230.968845155927, 6232.034034724625, 6241.095041218907, 6129.233845769279, 6135.98756205239, 6145.248604147797, 6169.56813023084, 6172.845678858064, 6057.273127224761, 6090.147379366804, 6115.938840611537, 6137.835406613001, 6150.661713605795, 6136.6011209271255, 6173.382661733791, 6177.4749146323065, 6180.783672571439, 6181.196444462191, 6043.21404456456, 6119.020956646915, 6128.356522565066, 6130.417610327012, 6176.629402235881], "value": 5709.242229841741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.42336313239775, 518.75099952023, 524.897644959233, 527.7783331000979, 542.869711269295, 37523.249535009316, 39416.80291598545, 39423.80288098559, 39604.20791584167, 39628.0, 39979.80010099953, 40483.59516404835, 40496.39255411171, 40550.59449405505, 40692.796536017355, 8130.471519351223, 8350.415470917038, 9203.401778884385, 9383.202427793645, 9434.622615095388, 8556.18716221961, 11077.612283570077, 11334.603288884891, 11377.94311028448, 11510.021648159916, 11384.544618215261, 11443.970042696155, 11449.484773185184, 11457.140714446408, 11465.59870404536, 11235.325880447159, 11316.094712422986, 11327.0, 11349.602763903224, 11359.716007099889, 11236.0, 11291.322520648788, 11298.152638552141, 11323.490442930088, 11330.660080197607, 37981.0, 38048.0, 38256.80871595652, 38279.85160445191, 38331.85004449871, 3239.6274428440797, 3250.561174241471, 3307.2228026413773, 3716.888493345177, 4040.1313717550574, 29523.70476295231, 29579.70420295791, 29786.451577634285, 30269.394612107855, 31003.689963100307, 3180.093673303121, 3206.9417092359467, 3215.1801290670965, 3250.6424293327714, 3376.74674399421, 467901.0, 469776.0, 476876.0, 481396.0, 481676.0, 61859.0, 63352.68323658352, 64547.0, 64578.677106615105, 64658.67670661616, 67069.6646516774, 67096.0, 67474.0, 67662.98505522421, 67672.58619310542, 15629.218539073001, 16209.837901620833, 16221.59446013859, 16265.268062937197, 16267.755983660254, 11155.832662510069, 11346.262492937933, 12155.939220303842, 12266.141370104007, 12426.130170888131, 19035.809641903405, 19347.4195774127, 19393.709094363312, 19400.902995485216, 19506.02469876529, 6872.553284036521, 7209.675564599605, 7972.960135199287, 8146.796330091676, 8177.0, 812.8374325134997, 863.8920134983127, 879.7360791762574, 985.5811280206027, 1009.8182327181178, 27571.86214068957, 29125.85437072801, 29179.832806687496, 30165.849170754005, 31877.681223187472, 971.8104969530874, 1002.644061358222, 1027.342500799489, 1029.4801125431609, 1034.2760067952463, 14697.41210351574, 14876.404943802343, 14934.775978360334, 15000.849991500163, 15027.849721502645, 649045.0, 658275.6689732453, 658710.0, 662613.0, 663051.0, 51913.0, 52377.476225238024, 52547.0, 52645.0, 52679.47320526822, 1310.7181955879341, 1343.7245364700213, 1349.8920086393066, 1362.2848004797363, 1367.172860419432, 24454.021839126235, 27658.308542286602, 28410.57947102678, 28667.27660872214, 29082.41835163327, 47365.76317118392, 47961.5203847957, 48002.79993000203, 48097.75951120222, 48272.0, 603574.0, 604012.0, 604110.0, 604584.0, 606168.0, 23236.76763232346, 23585.646215306442, 25692.816110630058, 26068.608970865833, 26841.328966775207, 35563.57745690143, 44009.0, 44926.77536612296, 45658.54341456543, 45660.0, 30723.539146913263, 36832.26335473329, 37178.442323365714, 37295.06762330963, 37909.620903791714, 32967.01098967035, 35570.822145889106, 36724.63275367212, 37634.8118259407, 38082.0, 36017.0, 36235.637643623224, 36412.81793591015, 36544.45183322199, 37024.0, 33279.833600831844, 33296.500552492216, 33476.49785253272, 33756.0, 34575.65424345825, 22425.32724018282, 24523.264302070966, 24709.87645061763, 25091.87454062718, 25416.618750718386], "value": 19893.572773799646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.773854432625, 325.3509249048149, 342.92798512312413, 380.6421963354445, 390.4143784323515, 18602.25590976361, 19306.613867722637, 19541.413757587277, 19645.31241406551, 19735.013249337513, 17677.7348339775, 20563.691544626843, 20942.37172884812, 21312.040958156882, 21486.89256553719, 6000.009998350267, 6416.871662566746, 6728.899066514006, 6754.66226688666, 6798.150231221098, 6562.639054851981, 6720.193576770795, 6731.09130267415, 6745.224299205581, 6785.355391237825, 6470.611763294218, 6712.160979877515, 6734.225564060147, 6793.966030169842, 6895.793126206222, 6151.630902145886, 6763.898541521881, 6797.728090876359, 6822.4200942919915, 7137.321954414322, 7246.7463638772915, 7399.4450416219, 7408.481406301561, 7418.962905185494, 7419.0, 26297.868510657514, 26429.074982375525, 27136.592951105747, 27236.59145112825, 27511.31221719453, 2221.366910430536, 2262.988685056564, 2284.5430913817304, 2287.759785222561, 2469.76537228963, 15578.118048161126, 16319.0, 16536.090515021668, 16819.495415137564, 17119.747248022835, 1926.5568919148534, 1928.2672584417971, 1977.377126205255, 2016.878987260769, 2068.90689918954, 123029.76970230273, 125078.24765257057, 125392.0, 133092.33453832765, 139325.3033734835, 20872.37382878516, 21366.57266854654, 21398.358049258546, 21489.247876324254, 21550.0, 34704.132396690286, 34706.13234669103, 34722.78470253529, 34800.47799283013, 34830.82584587111, 8971.013188549254, 10122.15557228547, 10344.5344959477, 10481.213908956859, 10749.62376316825, 8514.191151840565, 8612.18184272493, 8642.524661143572, 8758.0, 8803.867941980876, 8851.64593416269, 9088.681896133603, 9456.0, 9638.518074096268, 9677.564509597085, 3193.6327322358225, 3301.8443544759143, 3695.5934847167327, 3732.8133593320226, 3812.4853144825647, 795.3716563914559, 835.1815221083251, 871.7864123289835, 902.1429641840278, 926.9896604999395, 19151.007934645422, 19680.40958771239, 19820.504487387643, 19827.909464979275, 20028.59942801165, 816.3550794871985, 920.2224120618067, 931.1014870649856, 943.7027336389016, 956.2015716876347, 9248.907510924804, 10469.895301047045, 10497.107745841537, 10501.002404771534, 10519.368837869752, 499653.0, 573097.1345143331, 575812.0, 587077.0, 587548.0, 114151.0, 119235.0, 122701.0, 126927.36536317444, 129455.05817412747, 963.3834346018554, 977.1449981266392, 1006.9194464442827, 1007.2495990487134, 1013.9442330671884, 17064.317427303264, 17533.035683037557, 17587.29650813953, 17935.013574253542, 17957.10214489244, 41435.1712965745, 43812.0, 47343.81640459016, 48206.794830129526, 53050.0, 69541.0, 77523.0, 79906.0, 80479.19520804717, 80799.0, 7478.551286922802, 7605.315521603082, 7783.338416234684, 7806.258405451473, 8073.636686349246, 10152.898471015491, 11849.585264515701, 12220.572279970158, 12222.511099555919, 12349.221425179263, 10429.478526073817, 10456.372617642966, 10710.143188544742, 10722.0, 10833.187510936868, 10155.441450720282, 10382.688519344432, 10684.626038088629, 10808.135349172204, 10876.293040952312, 10476.528556214836, 10535.4732263385, 10902.727431814268, 11030.172737044913, 11122.0, 7688.8077798053255, 7791.766247012599, 7821.413393995359, 8010.319122874388, 8024.334297397168, 9040.367174297999, 9960.0, 10516.316439431412, 10819.242653014213, 10839.458027098772], "value": 10566.424008661654, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1471.124680814915, 1517.5447365790264, 1689.6029433083231, 1787.5084351803243, 1818.7726534183219, 7468.51454655447, 7667.3866090712745, 8085.151059138794, 8470.407071504997, 8490.490570565762, 7980.880286795688, 8038.517688938653, 8261.297789687884, 8409.159084091587, 8431.747047588568, 2272.556851413971, 2321.7330007049236, 2323.4191452136965, 2329.312852708447, 2334.673145759595, 2101.949025487256, 2307.7461479237354, 2328.639060945556, 2351.0243249051623, 2355.3993731598416, 1984.9281388050435, 2044.8140078754286, 2097.286922446368, 2120.978790212101, 2327.592671282527, 1908.3225454963497, 2297.173017713622, 2297.7357603875603, 2309.84985975911, 2310.9768902311016, 2300.5168914527967, 2320.640300753386, 2321.1063740460045, 2323.581755284048, 2336.5677349690363, 7239.674214660353, 8411.369147313915, 8414.410991230616, 8452.0, 8485.787855303604, 5024.095662780698, 5134.948650513485, 5163.896722065575, 5167.819126330598, 5169.767360468788, 3084.4293805646025, 3272.443684573628, 3487.5640544931885, 3490.336836001177, 3496.965030349689, 1033.2508931025022, 1036.43514284715, 1071.276888100529, 1073.0717928991444, 1100.8403781451725, 8894.02165761772, 10508.0542751152, 10825.350478971286, 10879.83680244797, 10905.618303359424, 4469.217886869785, 4536.659750518725, 4619.313950408077, 4627.120847039119, 4690.624750019991, 9339.066093390607, 9446.244300455946, 9446.85829712555, 9449.90550094504, 9461.38500997433, 5151.2530683050745, 5169.534741873174, 5169.53474187325, 5184.870378240499, 5186.844394668091, 4228.112096459717, 4250.176050183064, 4664.160451118763, 4689.976550117296, 4749.002709431058, 3718.3307004738867, 3772.0946972726347, 3822.980885095557, 3829.7702137871815, 3887.455756194135, 1273.6879464531064, 1278.7953927371761, 1278.833751612303, 1284.4926254129753, 1289.9871001290055, 702.6205848841671, 850.9531975741394, 897.1925267259388, 906.3791302957495, 934.4440058165517, 5446.2375267462585, 5458.426865179099, 5735.423743921471, 5785.8553536161935, 5799.043157878987, 952.8999455057258, 981.4111533080237, 998.7253505286104, 1031.1544533482622, 1065.8934106589434, 3728.2357116790995, 3734.514513113332, 3749.9250014999548, 3853.5183102112237, 3860.8841734748003, 9722.902770972341, 9940.701778946643, 10023.749406264757, 10042.748931276776, 10078.949605251926, 9940.502974851228, 9998.750031249132, 10002.34984726005, 10042.447665378475, 10044.296899216984, 7610.429217808576, 7679.232076792276, 7764.22357764219, 7841.176676449005, 7905.525668459911, 5265.78936842522, 5428.755705993319, 5434.945650543602, 5495.862603434786, 5544.445555444585, 9348.766280843034, 9374.625014999323, 9388.061193880558, 9405.341626086109, 9418.717438476857, 7413.221611730691, 9457.668981585612, 9464.337496375188, 9481.09929556691, 9481.66814161501, 7095.184053833668, 7329.340359367469, 7546.207648196861, 7741.032370953631, 7966.561839098674, 7807.336376408069, 7916.525008499509, 8001.799955001172, 8033.0, 8076.959615201887, 6118.449339559461, 7015.649217539205, 7086.539374940612, 7098.609576473344, 7136.964315178391, 6767.221769496571, 6807.3873351398615, 6956.2000369958105, 6973.721051157897, 7029.472789540702, 5855.443732845373, 6537.934620653923, 6565.80302590923, 6762.222344430454, 6762.289959554176, 3622.2393297406784, 4116.279651060992, 4275.636570891509, 4339.370791235317, 4475.552444755498, 4126.649234815074, 4665.20691482442, 4797.160496913096, 4890.584300334512, 4962.826301079445], "value": 4373.437783261591, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.9904103356383, 301.385174244541, 309.45072496319017, 311.7957737681819, 314.62245305633246, 5265.394479634843, 5366.543843773275, 5373.656585853537, 5454.972725136378, 5469.808556700516, 5190.079670521903, 5275.577953763699, 5322.680639161644, 5350.35795704516, 5362.919556206651, 4072.735272207312, 4120.320147175712, 4122.958770412288, 4128.772917489537, 4140.896477588054, 3647.6503693633395, 3990.9800450997705, 3995.60043995601, 4103.707332190365, 4155.189738001091, 3466.7746596471275, 4051.5948405159543, 4056.553779084298, 4078.89802754932, 4098.995746042224, 3696.815159242027, 3956.5054368203973, 4039.9596004039877, 4066.369712694537, 4081.4898137732484, 3298.6041674998914, 3385.6118991213484, 3576.535050443426, 3579.713622910161, 3610.981945090284, 3966.0, 5061.898762024739, 5080.949190508121, 5137.998090372379, 5206.843794686166, 4304.655627549789, 4536.682432229745, 4655.767211639439, 4684.758538987171, 4699.7415142167165, 4884.365032545782, 4911.852644420673, 5600.887982240332, 5622.465865742748, 5649.096144616841, 2829.589709492112, 3316.6849149330774, 3331.51693004513, 3398.235397035672, 3777.414500752388, 5982.641041537521, 5988.640681559077, 6012.338642749294, 6014.909776353358, 6016.75095448453, 4074.5925407459017, 4089.284375234322, 4090.9386359204636, 4092.01791570027, 4107.835686572504, 3602.369585322558, 3888.2199227552437, 4148.502179738451, 4152.418661387408, 4178.26880295947, 4215.494140703136, 4521.299198624219, 4748.738819364969, 4764.618830493552, 4783.5751929465105, 5206.0368831766245, 5217.739113044333, 5764.337101233328, 5770.451807078321, 5837.854053648608, 1115.453427820362, 1289.426205338634, 1294.7345794112184, 1337.4315915735774, 1346.8855147312588, 912.8174365127002, 924.912133347331, 927.7958849053198, 931.2131249094505, 934.3879758758025, 4351.69538132334, 4913.31213630092, 5087.720175390389, 5111.846644600668, 5123.615728820353, 5089.720065396439, 5116.360454943132, 5357.303550538405, 5589.311054703876, 5643.633163844337, 4410.779461027, 4716.3868697069165, 4867.780949857265, 5019.415001274347, 5119.923201151985, 2425.5755242832433, 2589.8964041438508, 2901.695321991203, 2922.5616157576533, 2925.7711761060405, 4615.838445654453, 5164.8192313268855, 5726.79956201531, 5752.338481074647, 5790.420957904176, 4948.975255123702, 5652.406497317805, 5691.3170419549915, 5699.686517241508, 5725.914111288334, 5362.544183744425, 5713.657180569179, 5775.971120144372, 5811.651300921958, 5828.213191219216, 4818.445878723852, 5208.0469823816065, 5272.367315922113, 5497.0, 5565.944340556704, 4844.0, 5381.27352807366, 5478.315210598676, 5510.228568000563, 5524.613277070566, 5058.747062646779, 5117.974410127926, 5327.334083239595, 5389.299391079135, 5695.519165017043, 3527.536072529974, 3553.822308884494, 3735.533058367704, 3760.2103558253075, 3797.962020379761, 4616.676832621819, 4815.627546149331, 4904.485029071896, 4938.43208031081, 5051.570616497639, 4677.0, 4900.338454308623, 4920.1389756791705, 5073.771680274471, 5201.349831271091, 5309.946900531099, 5318.760655770577, 5551.362348107399, 5693.629914055573, 5714.742836572281, 5013.523715247045, 5329.934013197453, 5526.585506086979, 5665.6204394155375, 5667.348254950569, 4815.133276010211, 5491.780328786804, 5779.797707080232, 5792.986227410271, 5794.565407594363, 5264.447233040476, 5489.862753431036, 5633.971830140987, 5671.6597004179885, 5696.344920334214], "value": 4039.4034788795943, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.9689513971871, 710.6411262312531, 712.1027505343266, 726.7202127181034, 737.1485933746525, 13990.860091399083, 14629.707405851877, 15230.0, 15416.460423885166, 16040.438584649539, 15546.378144874192, 15754.921225393857, 16001.519954401358, 16051.117188554654, 16064.357425702958, 815.9347252219837, 845.5772113943028, 854.7734850264685, 856.8646543330082, 858.0861382627513, 663.0419044480727, 682.890737482003, 826.9421140520166, 850.6172222499869, 859.9527026013567, 893.0176805513947, 896.7623579751371, 897.407710910799, 897.7037577599409, 897.8877640294963, 873.6898401067593, 887.4586502233632, 887.5074333744769, 889.7108439757072, 890.6437425029975, 610.6763415389851, 663.9568428052161, 664.5016237821634, 666.3702800853196, 666.8766278238542, 12336.321502317369, 14291.928540357232, 14807.851921480755, 14858.702825943421, 14863.479778207711, 13754.587362379145, 13845.861541384658, 14383.568492945229, 14482.855171448256, 14511.854881451156, 11466.31202127875, 11757.706057348549, 11818.881811181864, 11828.467718952668, 12161.391930403533, 3376.18971446851, 3445.2937147884622, 3754.249150169977, 3769.2461507698295, 3856.5372155341256, 85796.14203858006, 100289.4985525075, 102494.48752756188, 115644.0, 116906.0, 10997.0, 11062.944685276521, 11151.21941464106, 11161.609343672932, 11545.653630391102, 18729.157187926576, 22551.88724056402, 22644.886775566345, 22731.977061032292, 22930.445780903985, 7633.2366763324335, 7754.185810489817, 7844.0, 7948.682052717826, 8043.597820108971, 4388.648908087345, 4395.27477966132, 4408.189347458185, 4428.13651337986, 4454.643628509712, 3406.489026646023, 3533.681968622785, 3568.107973006748, 3569.196930690574, 3570.357335679603, 643.4466358931315, 645.2321737132797, 653.2422390027546, 656.4846595422571, 674.069783698493, 461.25046798951706, 604.2899592978233, 635.7107516080173, 668.7191379620571, 681.2268075733979, 8582.957085214533, 9192.678256261133, 9380.296477764196, 9392.624295028123, 9444.386114902509, 545.1795048452134, 580.5094694982732, 594.925634295713, 600.0, 656.0127008851744, 4077.7757223352405, 4424.557544245614, 4535.115652447807, 4617.930731039037, 4631.58315751584, 66261.0, 103206.0, 104324.95675043305, 104489.0, 110067.0, 89985.0, 91345.08654913498, 93449.0, 96222.0, 99696.0, 81222.0, 85233.14766852376, 86644.0, 88032.0, 88398.0, 7141.178764441948, 8732.08313127138, 8767.868481972648, 8801.07588703177, 8975.955120224357, 44113.558864410945, 45943.540564593925, 49326.76683082952, 52499.21251181159, 54684.453155467934, 52498.47501524936, 60019.099713503456, 60040.39959600523, 69170.0, 69191.65404172947, 12922.353882306035, 12938.676533086747, 12972.870271297166, 13342.866571334162, 13599.59201223965, 14251.144931304156, 14314.212718300594, 14481.565553033426, 14639.707205856037, 14686.265686715835, 13355.866441335462, 13452.730945381234, 13615.931920340336, 13895.0, 14024.789628155382, 11342.092632589209, 13675.384607692478, 13996.300184990914, 13998.300084995913, 14036.438542458187, 11056.889431105586, 12930.05483462792, 13232.338383081, 13511.86488135106, 13562.321883905568, 9463.668771592962, 9990.600375984879, 10920.945395272973, 11756.58851940178, 11794.997425218953, 8736.126387361213, 9611.663591774255, 11212.327260364404, 11449.0, 11592.594259200887], "value": 7362.6196806201015, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1625.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 118.0, 119.0, 117.0, 131.0, 119.0, 117.0, 117.0, 120.0], "value": 118.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 142.0, 106.0, 175.0, 147.0, 130.0, 117.0, 118.0, 136.0, 116.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 213.0, 262.0, 275.0, 199.0, 199.0, 196.0, 199.0, 194.0, 195.0], "value": 213.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 85.0, 59.0, 60.0, 59.0, 63.0, 85.0, 81.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 84.0, 72.0, 68.0, 70.0, 82.0, 70.0, 71.0, 81.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 132.0, 89.0, 87.0, 100.0, 111.0, 117.0, 131.0, 94.0, 91.0], "value": 108.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 125.0, 175.0, 102.0, 146.0, 108.0, 167.0, 166.0, 99.0], "value": 128.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 141.0, 121.0, 92.0, 93.0, 101.0, 121.0, 78.0, 106.0], "value": 104.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 79.0, 80.0, 86.0, 75.0, 57.0, 60.0, 65.0, 79.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 70.0, 75.0, 74.0, 76.0, 73.0, 70.0, 71.0, 68.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 91.0, 93.0, 108.0, 98.0, 89.0, 88.0, 88.0, 96.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 150.0, 146.0, 142.0, 166.0, 141.0, 161.0, 166.0, 194.0, 157.0], "value": 157.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [237.0, 190.0, 199.0, 235.0, 239.0, 224.0, 234.0, 194.0, 181.0, 176.0], "value": 210.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 72.0, 71.0, 70.0, 70.0, 70.0, 70.0, 70.0, 72.0], "value": 70.8, "unit": "score"}]}]} 16:10:44 INFO - Return code: 0 16:10:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:44 ERROR - # TBPL SUCCESS # 16:10:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:10:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:10:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:10:44 INFO - 2016-12-08 16:10:44.726 screenresolution[1934:21672] starting screenresolution argv=screenresolution get 16:10:44 INFO - 2016-12-08 16:10:44.730 screenresolution[1934:21672] Display 0: 1600x1200x32@60 16:10:44 INFO - 2016-12-08 16:10:44.736 screenresolution[1935:21675] starting screenresolution argv=screenresolution list 16:10:44 INFO - Available Modes on Display 0 16:10:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:10:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:10:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:10:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:10:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:10:44 INFO - Intel Iris: 16:10:44 INFO - Chipset Model: Intel Iris 16:10:44 INFO - Type: GPU 16:10:44 INFO - Bus: Built-In 16:10:44 INFO - VRAM (Dynamic, Max): 1536 MB 16:10:44 INFO - Vendor: Intel (0x8086) 16:10:44 INFO - Device ID: 0x0a2e 16:10:44 INFO - Revision ID: 0x0009 16:10:44 INFO - Displays: 16:10:44 INFO - Display: 16:10:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:10:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:10:44 INFO - Display Serial Number: 1600x1200 60 16:10:44 INFO - Main Display: Yes 16:10:44 INFO - Mirror: Off 16:10:44 INFO - Online: Yes 16:10:44 INFO - Rotation: Supported 16:10:44 INFO - Return code: 0 16:10:44 INFO - Running post-action listener: _resource_record_post_action 16:10:44 INFO - [mozharness: 2016-12-09 00:10:44.977033Z] Finished run-tests step (success) 16:10:44 INFO - Running post-run listener: _resource_record_post_run 16:10:45 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 929792; Write bytes: 695788032; Read time: 13; Write time: 2028 16:10:45 INFO - TinderboxPrint: CPU usage
54.8% 16:10:45 INFO - TinderboxPrint: I/O read bytes / time
929,792 / 13 16:10:45 INFO - TinderboxPrint: I/O write bytes / time
695,788,032 / 2,028 16:10:45 INFO - TinderboxPrint: CPU idle
2,196.2 (45.2%) 16:10:45 INFO - TinderboxPrint: CPU system
985.9 (20.3%) 16:10:45 INFO - TinderboxPrint: CPU user
1,671.7 (34.4%) 16:10:45 INFO - TinderboxPrint: Swap in / out
385,261,568 / 0 16:10:45 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 383109632; Write bytes: 375539200; Read time: 29473; Write time: 801 16:10:45 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 872448; Write bytes: 307796992; Read time: 11; Write time: 1183 16:10:45 INFO - Running post-run listener: _upload_blobber_files 16:10:45 INFO - Blob upload gear active. 16:10:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:45 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 16:10:45 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:10:45 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:10:45 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:10:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:10:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:46 INFO - (blobuploader) - INFO - Done attempting. 16:10:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:10:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:10:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:47 INFO - (blobuploader) - INFO - Done attempting. 16:10:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:10:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:10:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:50 INFO - (blobuploader) - INFO - Done attempting. 16:10:50 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:50 INFO - Return code: 0 16:10:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:10:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:10:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ba350f3a7bc59f8057ff892c20c153fc1b566a04654688f09b887d2478459a44b29ab00db3596866d963845f01bd4884c286256a10f0e0836da92a279d9fb505", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8d897e39947d3218706008106aed296b9700932c1b34ff8fa1bc0e6075ba564ce5d4b8e3415183774e26b8936b5f9d7ad88646e9ccc91f99f9b51f898b8300f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2749ae59f5e732b290198dd85f10303809e680aef772934f26ba8c2a77115389eb75a8bb5c639448b18a366d6814e076b89fdd3b7c8089e6a0b248875c8c665b"} 16:10:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:10:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:10:50 INFO - Contents: 16:10:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ba350f3a7bc59f8057ff892c20c153fc1b566a04654688f09b887d2478459a44b29ab00db3596866d963845f01bd4884c286256a10f0e0836da92a279d9fb505", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8d897e39947d3218706008106aed296b9700932c1b34ff8fa1bc0e6075ba564ce5d4b8e3415183774e26b8936b5f9d7ad88646e9ccc91f99f9b51f898b8300f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2749ae59f5e732b290198dd85f10303809e680aef772934f26ba8c2a77115389eb75a8bb5c639448b18a366d6814e076b89fdd3b7c8089e6a0b248875c8c665b"} 16:10:50 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:50 INFO - Copying logs to upload dir... 16:10:50 INFO - mkdir: /builds/slave/test/build/upload/logs 16:10:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.098045 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-08 16:10:50.218665) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 16:10:50.222512) ========= 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.laEXQja3Dx/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.K3ohBisZzP/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/Larch/sha512/ba350f3a7bc59f8057ff892c20c153fc1b566a04654688f09b887d2478459a44b29ab00db3596866d963845f01bd4884c286256a10f0e0836da92a279d9fb505", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8d897e39947d3218706008106aed296b9700932c1b34ff8fa1bc0e6075ba564ce5d4b8e3415183774e26b8936b5f9d7ad88646e9ccc91f99f9b51f898b8300f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2749ae59f5e732b290198dd85f10303809e680aef772934f26ba8c2a77115389eb75a8bb5c639448b18a366d6814e076b89fdd3b7c8089e6a0b248875c8c665b"} build_url:https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011442 build_url: 'https://queue.taskcluster.net/v1/task/Y78rdpKDR_eI9PbqTXJTBg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ba350f3a7bc59f8057ff892c20c153fc1b566a04654688f09b887d2478459a44b29ab00db3596866d963845f01bd4884c286256a10f0e0836da92a279d9fb505", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8d897e39947d3218706008106aed296b9700932c1b34ff8fa1bc0e6075ba564ce5d4b8e3415183774e26b8936b5f9d7ad88646e9ccc91f99f9b51f898b8300f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2749ae59f5e732b290198dd85f10303809e680aef772934f26ba8c2a77115389eb75a8bb5c639448b18a366d6814e076b89fdd3b7c8089e6a0b248875c8c665b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 16:10:50.257394) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 16:10:50.257735) ========= 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.laEXQja3Dx/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.K3ohBisZzP/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.005241 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 16:10:50.307495) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 16:10:50.307999) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 16:10:50.308531) ========= ========= Total master_lag: 0.19 =========