builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0385 starttime: 1484582964.66 results: success (0) buildid: 20170116055525 builduid: 4297465955b84599959df4d9ad5944c7 revision: 100c34ac81052ad03e9ea0bb68c66df175202280 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:24.662299) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:24.662709) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:24.663002) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:24.825540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-16 08:09:24.825798) ========= 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.bNGqItR6Vh/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.EPmSZ45z11/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-16 08:09:25-- 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% 164M=0s 2017-01-16 08:09:25 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.161761 ========= master_lag: 0.98 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-16 08:09:25.964984) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:25.965300) ========= 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.bNGqItR6Vh/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.EPmSZ45z11/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.023472 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:26.023760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-16 08:09:26.024113) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 100c34ac81052ad03e9ea0bb68c66df175202280 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 100c34ac81052ad03e9ea0bb68c66df175202280 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bNGqItR6Vh/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.EPmSZ45z11/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-16 08:09:26,111 truncating revision to first 12 chars 2017-01-16 08:09:26,111 Setting DEBUG logging. 2017-01-16 08:09:26,111 attempt 1/10 2017-01-16 08:09:26,111 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/100c34ac8105?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-16 08:09:27,083 unpacking tar archive at: graphics-100c34ac8105/testing/mozharness/ program finished with exit code 0 elapsedTime=1.239516 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-16 08:09:27.288253) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:27.288556) ========= script_repo_revision: 100c34ac81052ad03e9ea0bb68c66df175202280 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:27.289434) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:27.289704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-16 08:09:27.310075) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-16 08:09:27.310396) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bNGqItR6Vh/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.EPmSZ45z11/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:09:27 INFO - MultiFileLogger online at 20170116 08:09:27 in /builds/slave/test 08:09:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 08:09:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:09:27 INFO - {'append_to_log': False, 08:09:27 INFO - 'base_work_dir': '/builds/slave/test', 08:09:27 INFO - 'blob_upload_branch': 'Graphics', 08:09:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:09:27 INFO - 'branch': 'Graphics', 08:09:27 INFO - 'buildbot_json_path': 'buildprops.json', 08:09:27 INFO - 'config_files': ('talos/mac_config.py',), 08:09:27 INFO - 'default_actions': ('clobber', 08:09:27 INFO - 'read-buildbot-config', 08:09:27 INFO - 'download-and-extract', 08:09:27 INFO - 'populate-webroot', 08:09:27 INFO - 'create-virtualenv', 08:09:27 INFO - 'install', 08:09:27 INFO - 'run-tests'), 08:09:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:09:27 INFO - 'download_minidump_stackwalk': True, 08:09:27 INFO - 'download_symbols': 'ondemand', 08:09:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:09:27 INFO - 'tooltool.py': '/tools/tooltool.py', 08:09:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:09:27 INFO - '/tools/misc-python/virtualenv.py')}, 08:09:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:09:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:09:27 INFO - 'installer_path': 'installer.exe', 08:09:27 INFO - 'log_level': 'info', 08:09:27 INFO - 'log_name': 'talos', 08:09:27 INFO - 'log_to_console': True, 08:09:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:09:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:09:27 INFO - 'opt_config_files': (), 08:09:27 INFO - 'pip_index': False, 08:09:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:27 INFO - 'cmd': ('bash', 08:09:27 INFO - '-c', 08:09:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:27 INFO - 'enabled': True, 08:09:27 INFO - 'halt_on_failure': False, 08:09:27 INFO - 'name': 'check_screen_resolution'},), 08:09:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:27 INFO - 'cmd': ('bash', 08:09:27 INFO - '-c', 08:09:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:27 INFO - 'enabled': True, 08:09:27 INFO - 'halt_on_failure': False, 08:09:27 INFO - 'name': 'check_screen_resolution'},), 08:09:27 INFO - 'run_cmd_checks_enabled': True, 08:09:27 INFO - 'sps_profile': False, 08:09:27 INFO - 'sps_profile_interval': 0, 08:09:27 INFO - 'suite': 'dromaeojs-e10s', 08:09:27 INFO - 'system_bits': '32', 08:09:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:09:27 INFO - 'title': 't-yosemite-r7-0385', 08:09:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:09:27 INFO - 'use_talos_json': True, 08:09:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:09:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:09:27 INFO - 'work_dir': 'build'} 08:09:27 INFO - [mozharness: 2017-01-16 16:09:27.513907Z] Running clobber step. 08:09:27 INFO - Running pre-action listener: _resource_record_pre_action 08:09:27 INFO - Running main action method: clobber 08:09:27 INFO - rmtree: /builds/slave/test/build 08:09:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:09:29 INFO - Running post-action listener: _resource_record_post_action 08:09:29 INFO - [mozharness: 2017-01-16 16:09:29.356903Z] Finished clobber step (success) 08:09:29 INFO - [mozharness: 2017-01-16 16:09:29.357067Z] Running read-buildbot-config step. 08:09:29 INFO - Running pre-action listener: _resource_record_pre_action 08:09:29 INFO - Running main action method: read_buildbot_config 08:09:29 INFO - Using buildbot properties: 08:09:29 INFO - { 08:09:29 INFO - "project": "", 08:09:29 INFO - "product": "firefox", 08:09:29 INFO - "script_repo_revision": "default", 08:09:29 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 08:09:29 INFO - "repository": "", 08:09:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 08:09:29 INFO - "buildid": "20170116055525", 08:09:29 INFO - "pgo_build": "False", 08:09:29 INFO - "basedir": "/builds/slave/test", 08:09:29 INFO - "buildnumber": 3, 08:09:29 INFO - "slavename": "t-yosemite-r7-0385", 08:09:29 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:09:29 INFO - "platform": "yosemite_r7", 08:09:29 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 08:09:29 INFO - "branch": "graphics", 08:09:29 INFO - "repo_path": "projects/graphics", 08:09:29 INFO - "revision": "100c34ac81052ad03e9ea0bb68c66df175202280", 08:09:29 INFO - "stage_platform": "macosx64", 08:09:29 INFO - "builduid": "4297465955b84599959df4d9ad5944c7", 08:09:29 INFO - "slavebuilddir": "test" 08:09:29 INFO - } 08:09:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:09:29 INFO - Running post-action listener: _resource_record_post_action 08:09:29 INFO - [mozharness: 2017-01-16 16:09:29.360305Z] Finished read-buildbot-config step (success) 08:09:29 INFO - [mozharness: 2017-01-16 16:09:29.360413Z] Running download-and-extract step. 08:09:29 INFO - Running pre-action listener: _resource_record_pre_action 08:09:29 INFO - Running main action method: download_and_extract 08:09:29 INFO - mkdir: /builds/slave/test/build/tests 08:09:29 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')]} 08:09:29 INFO - trying https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:09:29 INFO - Downloading https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/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 08:09:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/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 08:09:30 INFO - Downloaded 1199 bytes. 08:09:30 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:09:30 INFO - Using the following test package requirements: 08:09:30 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:09:30 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:30 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:09:30 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:30 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:09:30 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:30 INFO - u'jsshell-mac.zip'], 08:09:30 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:30 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:09:30 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:09:30 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:30 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:09:30 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:30 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:09:30 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:30 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:09:30 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:30 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:09:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:09:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:09:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:09:30 INFO - Fetch https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:09:31 INFO - Expected file size: 16324398 08:09:31 INFO - Obtained file size: 16324398 08:09:38 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 08:09:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:09:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:09:38 INFO - Fetch https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:09:38 INFO - Expected file size: 16324398 08:09:38 INFO - Obtained file size: 16324398 08:09:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:09:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:09:45 INFO - Fetch https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:09:46 INFO - Expected file size: 14403463 08:09:46 INFO - Obtained file size: 14403463 08:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:46 INFO - trying https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:09:46 INFO - Downloading https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:09:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/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 08:09:49 INFO - Downloaded 70261478 bytes. 08:09:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:09:49 INFO - mkdir: /builds/slave/test/properties 08:09:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:09:49 INFO - Writing to file /builds/slave/test/properties/build_url 08:09:49 INFO - Contents: 08:09:49 INFO - build_url:https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:09:50 INFO - Running post-action listener: _resource_record_post_action 08:09:50 INFO - Running post-action listener: set_extra_try_arguments 08:09:50 INFO - [mozharness: 2017-01-16 16:09:50.512082Z] Finished download-and-extract step (success) 08:09:50 INFO - [mozharness: 2017-01-16 16:09:50.512235Z] Running populate-webroot step. 08:09:50 INFO - Running pre-action listener: _resource_record_pre_action 08:09:50 INFO - Running main action method: populate_webroot 08:09:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:09:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:09:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:09:50 INFO - '--tppagecycles', 08:09:50 INFO - '7'], 08:09:50 INFO - 'tests': ['tsvgm']}}, 08:09:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:09:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:09:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:09:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:09:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:09:50 INFO - 'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:09:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:09:50 INFO - 'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['damp', 'tps']}, 08:09:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:09:50 INFO - 'tests': ['damp', 'tps']}, 08:09:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['dromaeo_dom']}, 08:09:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:09:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['basic_compositor_video']}, 08:09:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:09:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['a11yr', 08:09:50 INFO - 'ts_paint', 08:09:50 INFO - 'tpaint', 08:09:50 INFO - 'sessionrestore', 08:09:50 INFO - 'sessionrestore_no_auto_restore', 08:09:50 INFO - 'tabpaint']}, 08:09:50 INFO - 'other-e10s': {'tests': ['a11yr', 08:09:50 INFO - 'ts_paint', 08:09:50 INFO - 'tpaint', 08:09:50 INFO - 'sessionrestore', 08:09:50 INFO - 'sessionrestore_no_auto_restore', 08:09:50 INFO - 'tabpaint']}, 08:09:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:09:50 INFO - 'ts_paint', 08:09:50 INFO - 'tpaint', 08:09:50 INFO - 'sessionrestore', 08:09:50 INFO - 'sessionrestore_no_auto_restore', 08:09:50 INFO - 'tabpaint']}, 08:09:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:09:50 INFO - 'ts_paint', 08:09:50 INFO - 'tpaint', 08:09:50 INFO - 'sessionrestore', 08:09:50 INFO - 'sessionrestore_no_auto_restore', 08:09:50 INFO - 'tabpaint']}, 08:09:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['a11yr', 08:09:50 INFO - 'ts_paint', 08:09:50 INFO - 'tpaint', 08:09:50 INFO - 'sessionrestore', 08:09:50 INFO - 'sessionrestore_no_auto_restore', 08:09:50 INFO - 'tabpaint']}, 08:09:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['a11yr', 08:09:50 INFO - 'ts_paint', 08:09:50 INFO - 'tpaint', 08:09:50 INFO - 'sessionrestore', 08:09:50 INFO - 'sessionrestore_no_auto_restore', 08:09:50 INFO - 'tabpaint']}, 08:09:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['tsvgx', 08:09:50 INFO - 'tsvgr_opacity', 08:09:50 INFO - 'tart', 08:09:50 INFO - 'tscrollx', 08:09:50 INFO - 'cart', 08:09:50 INFO - 'tsvg_static']}, 08:09:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:09:50 INFO - 'tsvgr_opacity', 08:09:50 INFO - 'tart', 08:09:50 INFO - 'tscrollx', 08:09:50 INFO - 'cart', 08:09:50 INFO - 'tsvg_static']}, 08:09:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:09:50 INFO - 'talos_options': ['--disable-e10s'], 08:09:50 INFO - 'tests': ['tp5o']}, 08:09:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:09:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:09:50 INFO - 'talos_options': ['--disable-e10s', 08:09:50 INFO - '--xperf_path', 08:09:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:50 INFO - 'tests': ['tp5n']}, 08:09:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:09:50 INFO - 'talos_options': ['--xperf_path', 08:09:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:50 INFO - 'tests': ['tp5n']}}, 08:09:50 INFO - 'talos.zip': {'path': '', 08:09:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:09:50 INFO - Running post-action listener: _resource_record_post_action 08:09:50 INFO - [mozharness: 2017-01-16 16:09:50.530753Z] Finished populate-webroot step (success) 08:09:50 INFO - [mozharness: 2017-01-16 16:09:50.530886Z] Running create-virtualenv step. 08:09:50 INFO - Running pre-action listener: _resource_record_pre_action 08:09:50 INFO - Running main action method: create_virtualenv 08:09:50 INFO - Creating virtualenv /builds/slave/test/build/venv 08:09:50 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 08:09:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:09:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:09:50 INFO - Using real prefix '/tools/python27' 08:09:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:09:51 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:54 INFO - Installing pip.................done. 08:09:54 INFO - Return code: 0 08:09:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:09:54 INFO - Reading from file tmpfile_stdout 08:09:54 INFO - Output received: 08:09:54 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) 08:09:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:54 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 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:09:54 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 08:09:54 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 08:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:54 INFO - 'HOME': '/Users/cltbld', 08:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:54 INFO - 'LOGNAME': 'cltbld', 08:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:54 INFO - 'MOZ_NO_REMOTE': '1', 08:09:54 INFO - 'NO_EM_RESTART': '1', 08:09:54 INFO - 'PAGER': '/bin/cat', 08:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:54 INFO - 'PWD': '/builds/slave/test', 08:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:54 INFO - 'SHELL': '/bin/bash', 08:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:09:54 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:54 INFO - 'USER': 'cltbld', 08:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:54 INFO - 'XPC_FLAGS': '0x0', 08:09:54 INFO - 'XPC_SERVICE_NAME': '0', 08:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:54 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 08:09:54 INFO - Cleaning up... 08:09:54 INFO - Return code: 0 08:09:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:54 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 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:09:54 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 08:09:54 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 08:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:54 INFO - 'HOME': '/Users/cltbld', 08:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:54 INFO - 'LOGNAME': 'cltbld', 08:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:54 INFO - 'MOZ_NO_REMOTE': '1', 08:09:54 INFO - 'NO_EM_RESTART': '1', 08:09:54 INFO - 'PAGER': '/bin/cat', 08:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:54 INFO - 'PWD': '/builds/slave/test', 08:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:54 INFO - 'SHELL': '/bin/bash', 08:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:09:54 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:54 INFO - 'USER': 'cltbld', 08:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:54 INFO - 'XPC_FLAGS': '0x0', 08:09:54 INFO - 'XPC_SERVICE_NAME': '0', 08:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:55 INFO - Downloading/unpacking psutil>=3.1.1 08:09:55 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 08:09:55 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 08:09:55 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 08:09:55 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 08:09:55 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 08:09:55 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 08:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:59 INFO - Installing collected packages: psutil 08:09:59 INFO - Running setup.py install for psutil 08:09:59 INFO - building 'psutil._psutil_osx' extension 08:09:59 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 08:09:59 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 08:09:59 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 08:09:59 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 08:09:59 INFO - building 'psutil._psutil_posix' extension 08:09:59 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 08:09:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:09:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:09:59 INFO - ^ 08:09:59 INFO - 1 warning generated. 08:09:59 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 08:09:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:59 INFO - Successfully installed psutil 08:09:59 INFO - Cleaning up... 08:10:00 INFO - Return code: 0 08:10:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:10:00 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')]} 08:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:00 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')]} 08:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:00 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 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:10:00 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 08:10:00 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 08:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:00 INFO - 'HOME': '/Users/cltbld', 08:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:00 INFO - 'LOGNAME': 'cltbld', 08:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:00 INFO - 'MOZ_NO_REMOTE': '1', 08:10:00 INFO - 'NO_EM_RESTART': '1', 08:10:00 INFO - 'PAGER': '/bin/cat', 08:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:00 INFO - 'PWD': '/builds/slave/test', 08:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:00 INFO - 'SHELL': '/bin/bash', 08:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:00 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:00 INFO - 'USER': 'cltbld', 08:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:00 INFO - 'XPC_FLAGS': '0x0', 08:10:00 INFO - 'XPC_SERVICE_NAME': '0', 08:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:10:00 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 08:10:00 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 08:10:00 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 08:10:00 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 08:10:00 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 08:10:00 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 08:10:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:10:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:10:02 INFO - Installing collected packages: mozsystemmonitor 08:10:02 INFO - Running setup.py install for mozsystemmonitor 08:10:03 INFO - Successfully installed mozsystemmonitor 08:10:03 INFO - Cleaning up... 08:10:03 INFO - Return code: 0 08:10:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:03 INFO - 'HOME': '/Users/cltbld', 08:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:03 INFO - 'LOGNAME': 'cltbld', 08:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:03 INFO - 'MOZ_NO_REMOTE': '1', 08:10:03 INFO - 'NO_EM_RESTART': '1', 08:10:03 INFO - 'PAGER': '/bin/cat', 08:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:03 INFO - 'PWD': '/builds/slave/test', 08:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:03 INFO - 'SHELL': '/bin/bash', 08:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:03 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:03 INFO - 'USER': 'cltbld', 08:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:03 INFO - 'XPC_FLAGS': '0x0', 08:10:03 INFO - 'XPC_SERVICE_NAME': '0', 08:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:03 INFO - Downloading/unpacking jsonschema==2.5.1 08:10:03 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 08:10:03 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 08:10:03 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 08:10:03 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 08:10:03 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 08:10:03 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 08:10:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:06 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:06 INFO - warning: no files found matching '*.txt' 08:10:06 INFO - no previously-included directories found matching 'build' 08:10:06 INFO - no previously-included directories found matching 'dist' 08:10:06 INFO - no previously-included directories found matching '.git*' 08:10:06 INFO - Installing collected packages: jsonschema, functools32 08:10:06 INFO - Running setup.py install for functools32 08:10:06 INFO - warning: no files found matching '*.txt' 08:10:06 INFO - no previously-included directories found matching 'build' 08:10:06 INFO - no previously-included directories found matching 'dist' 08:10:06 INFO - no previously-included directories found matching '.git*' 08:10:06 INFO - Successfully installed jsonschema functools32 08:10:06 INFO - Cleaning up... 08:10:07 INFO - Return code: 0 08:10:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:10:07 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')]} 08:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:07 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')]} 08:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:07 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 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:10:07 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 08:10:07 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 08:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:07 INFO - 'HOME': '/Users/cltbld', 08:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:07 INFO - 'LOGNAME': 'cltbld', 08:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:07 INFO - 'MOZ_NO_REMOTE': '1', 08:10:07 INFO - 'NO_EM_RESTART': '1', 08:10:07 INFO - 'PAGER': '/bin/cat', 08:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:07 INFO - 'PWD': '/builds/slave/test', 08:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:07 INFO - 'SHELL': '/bin/bash', 08:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:07 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:07 INFO - 'USER': 'cltbld', 08:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:07 INFO - 'XPC_FLAGS': '0x0', 08:10:07 INFO - 'XPC_SERVICE_NAME': '0', 08:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:10:07 INFO - Cleaning up... 08:10:07 INFO - Return code: 0 08:10:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:10:07 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')]} 08:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:07 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')]} 08:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:07 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 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:10:07 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 08:10:07 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 08:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:07 INFO - 'HOME': '/Users/cltbld', 08:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:07 INFO - 'LOGNAME': 'cltbld', 08:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:07 INFO - 'MOZ_NO_REMOTE': '1', 08:10:07 INFO - 'NO_EM_RESTART': '1', 08:10:07 INFO - 'PAGER': '/bin/cat', 08:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:07 INFO - 'PWD': '/builds/slave/test', 08:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:07 INFO - 'SHELL': '/bin/bash', 08:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:07 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:07 INFO - 'USER': 'cltbld', 08:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:07 INFO - 'XPC_FLAGS': '0x0', 08:10:07 INFO - 'XPC_SERVICE_NAME': '0', 08:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:07 INFO - Downloading/unpacking blobuploader==1.2.4 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:10:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:10:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:11 INFO - Downloading docopt-0.6.1.tar.gz 08:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:10:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:10:11 INFO - Running setup.py install for blobuploader 08:10:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:10:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:10:11 INFO - Running setup.py install for requests 08:10:12 INFO - Running setup.py install for docopt 08:10:12 INFO - Successfully installed blobuploader requests docopt 08:10:12 INFO - Cleaning up... 08:10:12 INFO - Return code: 0 08:10:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:12 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 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:10:12 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 08:10:12 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 08:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:12 INFO - 'HOME': '/Users/cltbld', 08:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:12 INFO - 'LOGNAME': 'cltbld', 08:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:12 INFO - 'MOZ_NO_REMOTE': '1', 08:10:12 INFO - 'NO_EM_RESTART': '1', 08:10:12 INFO - 'PAGER': '/bin/cat', 08:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:12 INFO - 'PWD': '/builds/slave/test', 08:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:12 INFO - 'SHELL': '/bin/bash', 08:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:12 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:12 INFO - 'USER': 'cltbld', 08:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:12 INFO - 'XPC_FLAGS': '0x0', 08:10:12 INFO - 'XPC_SERVICE_NAME': '0', 08:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:12 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-yMWfBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:12 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-zT80Y8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:12 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-z_h8ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-hdnBVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-LDCq19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-gk_2NS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-hgy9kY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-kFngti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-t95rKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-eiFH5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-OGgWHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-QtElQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:13 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-58AEoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:14 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-E2JlyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:14 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-7aRCGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:14 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-OCEeVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:14 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-kQM2Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:10:14 INFO - Running setup.py install for manifestparser 08:10:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Running setup.py install for mozcrash 08:10:14 INFO - Running setup.py install for mozdebug 08:10:14 INFO - Running setup.py install for mozdevice 08:10:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Running setup.py install for mozfile 08:10:15 INFO - Running setup.py install for mozhttpd 08:10:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Running setup.py install for mozinfo 08:10:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Running setup.py install for mozInstall 08:10:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Running setup.py install for mozleak 08:10:15 INFO - Running setup.py install for mozlog 08:10:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Running setup.py install for moznetwork 08:10:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:10:16 INFO - Running setup.py install for mozprocess 08:10:16 INFO - Running setup.py install for mozprofile 08:10:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:10:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:10:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:10:16 INFO - Running setup.py install for mozrunner 08:10:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:10:16 INFO - Running setup.py install for mozscreenshot 08:10:16 INFO - Running setup.py install for moztest 08:10:16 INFO - Running setup.py install for mozversion 08:10:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:10:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:10:17 INFO - Cleaning up... 08:10:17 INFO - Return code: 0 08:10:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:17 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')]} 08:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:17 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')]} 08:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:17 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 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:10:17 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 08:10:17 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 08:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:17 INFO - 'HOME': '/Users/cltbld', 08:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:17 INFO - 'LOGNAME': 'cltbld', 08:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:17 INFO - 'MOZ_NO_REMOTE': '1', 08:10:17 INFO - 'NO_EM_RESTART': '1', 08:10:17 INFO - 'PAGER': '/bin/cat', 08:10:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:17 INFO - 'PWD': '/builds/slave/test', 08:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:17 INFO - 'SHELL': '/bin/bash', 08:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:17 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:17 INFO - 'USER': 'cltbld', 08:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:17 INFO - 'XPC_FLAGS': '0x0', 08:10:17 INFO - 'XPC_SERVICE_NAME': '0', 08:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:17 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-psh77E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:17 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)) 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:17 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-eKemVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:17 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)) 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:17 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Ei99Be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:17 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)) 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:17 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2PWCvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:17 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)) 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:17 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-KOwTeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:17 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)) 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:17 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-tBfUq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:18 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)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-7tl7aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:18 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)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-DEgRZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:18 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)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-DkoF2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:18 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)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-eFR_Nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:18 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)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ftPULY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:18 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)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-gtAVfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-V2bIA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:18 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)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-38ZB1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:18 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)) 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:18 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-0ajBLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:19 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)) 08:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:19 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-zN00Aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:19 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-lNLXLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:19 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)) 08:10:19 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)) 08:10:19 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)) 08:10:19 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)) 08:10:19 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)) 08:10:19 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)) 08:10:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:21 INFO - Downloading blessings-1.6.tar.gz 08:10:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:10:21 INFO - Installing collected packages: blessings 08:10:21 INFO - Running setup.py install for blessings 08:10:22 INFO - Successfully installed blessings 08:10:22 INFO - Cleaning up... 08:10:22 INFO - Return code: 0 08:10:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:22 INFO - Reading from file tmpfile_stdout 08:10:22 INFO - Current package versions: 08:10:22 INFO - blessings == 1.6 08:10:22 INFO - blobuploader == 1.2.4 08:10:22 INFO - docopt == 0.6.1 08:10:22 INFO - functools32 == 3.2.3-2 08:10:22 INFO - jsonschema == 2.5.1 08:10:22 INFO - manifestparser == 1.1 08:10:22 INFO - mozInstall == 1.12 08:10:22 INFO - mozcrash == 1.0 08:10:22 INFO - mozdebug == 0.1 08:10:22 INFO - mozdevice == 0.49 08:10:22 INFO - mozfile == 1.2 08:10:22 INFO - mozhttpd == 0.7 08:10:22 INFO - mozinfo == 0.9 08:10:22 INFO - mozleak == 0.1 08:10:22 INFO - mozlog == 3.4 08:10:22 INFO - moznetwork == 0.27 08:10:22 INFO - mozprocess == 0.24 08:10:22 INFO - mozprofile == 0.28 08:10:22 INFO - mozrunner == 6.13 08:10:22 INFO - mozscreenshot == 0.1 08:10:22 INFO - mozsystemmonitor == 0.3 08:10:22 INFO - moztest == 0.8 08:10:22 INFO - mozversion == 1.4 08:10:22 INFO - psutil == 3.1.1 08:10:22 INFO - requests == 1.2.3 08:10:22 INFO - wsgiref == 0.1.2 08:10:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:22 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 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:10:22 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 08:10:22 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 08:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:22 INFO - 'HOME': '/Users/cltbld', 08:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:22 INFO - 'LOGNAME': 'cltbld', 08:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:22 INFO - 'MOZ_NO_REMOTE': '1', 08:10:22 INFO - 'NO_EM_RESTART': '1', 08:10:22 INFO - 'PAGER': '/bin/cat', 08:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:22 INFO - 'PWD': '/builds/slave/test', 08:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:22 INFO - 'SHELL': '/bin/bash', 08:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:22 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:22 INFO - 'USER': 'cltbld', 08:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:22 INFO - 'XPC_FLAGS': '0x0', 08:10:22 INFO - 'XPC_SERVICE_NAME': '0', 08:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 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)) 08:10:22 INFO - Cleaning up... 08:10:22 INFO - Return code: 0 08:10:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f8d8ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, '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.bNGqItR6Vh/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.EPmSZ45z11/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 08:10:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:22 INFO - 'HOME': '/Users/cltbld', 08:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:22 INFO - 'LOGNAME': 'cltbld', 08:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:22 INFO - 'MOZ_NO_REMOTE': '1', 08:10:22 INFO - 'NO_EM_RESTART': '1', 08:10:22 INFO - 'PAGER': '/bin/cat', 08:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:22 INFO - 'PWD': '/builds/slave/test', 08:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:22 INFO - 'SHELL': '/bin/bash', 08:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:22 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:22 INFO - 'USER': 'cltbld', 08:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:22 INFO - 'XPC_FLAGS': '0x0', 08:10:22 INFO - 'XPC_SERVICE_NAME': '0', 08:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:10:22 INFO - Cleaning up... 08:10:23 INFO - Return code: 0 08:10:23 INFO - Running post-action listener: _resource_record_post_action 08:10:23 INFO - Running post-action listener: _start_resource_monitoring 08:10:23 INFO - Starting resource monitoring. 08:10:23 INFO - [mozharness: 2017-01-16 16:10:23.044450Z] Finished create-virtualenv step (success) 08:10:23 INFO - [mozharness: 2017-01-16 16:10:23.045508Z] Running install step. 08:10:23 INFO - Running pre-action listener: _resource_record_pre_action 08:10:23 INFO - Running main action method: install 08:10:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:23 INFO - Reading from file tmpfile_stdout 08:10:23 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:23 INFO - Reading from file tmpfile_stdout 08:10:23 INFO - Output received: 08:10:23 INFO - Usage: mozinstall [options] installer 08:10:23 INFO - Options: 08:10:23 INFO - -h, --help show this help message and exit 08:10:23 INFO - -d DEST, --destination=DEST 08:10:23 INFO - Directory to install application into. [default: 08:10:23 INFO - "/builds/slave/test"] 08:10:23 INFO - --app=APP Application being installed. [default: firefox] 08:10:23 INFO - mkdir: /builds/slave/test/build/application 08:10:23 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'] 08:10:23 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 08:10:45 INFO - Reading from file tmpfile_stdout 08:10:45 INFO - Output received: 08:10:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:10:45 INFO - Running post-action listener: _resource_record_post_action 08:10:45 INFO - [mozharness: 2017-01-16 16:10:45.191999Z] Finished install step (success) 08:10:45 INFO - [mozharness: 2017-01-16 16:10:45.192165Z] Running run-tests step. 08:10:45 INFO - Running pre-action listener: _resource_record_pre_action 08:10:45 INFO - Running main action method: run_tests 08:10:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:45 INFO - 2017-01-16 08:10:45.206 screenresolution[2453:83165] starting screenresolution argv=screenresolution get 08:10:45 INFO - 2017-01-16 08:10:45.209 screenresolution[2453:83165] Display 0: 1600x1200x32@60 08:10:45 INFO - 2017-01-16 08:10:45.215 screenresolution[2454:83167] starting screenresolution argv=screenresolution list 08:10:45 INFO - Available Modes on Display 0 08:10:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:45 INFO - Intel Iris: 08:10:45 INFO - Chipset Model: Intel Iris 08:10:45 INFO - Type: GPU 08:10:45 INFO - Bus: Built-In 08:10:45 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:45 INFO - Vendor: Intel (0x8086) 08:10:45 INFO - Device ID: 0x0a2e 08:10:45 INFO - Revision ID: 0x0009 08:10:45 INFO - Displays: 08:10:45 INFO - Display: 08:10:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:45 INFO - Display Serial Number: 1600x1200 60 08:10:45 INFO - Main Display: Yes 08:10:45 INFO - Mirror: Off 08:10:45 INFO - Online: Yes 08:10:45 INFO - Rotation: Supported 08:10:45 INFO - Return code: 0 08:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:45 INFO - Python 2.7.3 08:10:45 INFO - Return code: 0 08:10:45 INFO - grabbing minidump binary from tooltool 08:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:45 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 0x104873d30>, '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 0x10479adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104868be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:45 INFO - Return code: 0 08:10:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:10:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:10:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 08:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0385 --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 08:10:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNGqItR6Vh/Render', 08:10:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:45 INFO - 'HOME': '/Users/cltbld', 08:10:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:45 INFO - 'LOGNAME': 'cltbld', 08:10:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:45 INFO - 'MOZ_NO_REMOTE': '1', 08:10:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:45 INFO - 'NO_EM_RESTART': '1', 08:10:45 INFO - 'PAGER': '/bin/cat', 08:10:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:45 INFO - 'PWD': '/builds/slave/test', 08:10:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:45 INFO - 'SHELL': '/bin/bash', 08:10:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EPmSZ45z11/Listeners', 08:10:45 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 08:10:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:45 INFO - 'USER': 'cltbld', 08:10:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:45 INFO - 'XPC_FLAGS': '0x0', 08:10:45 INFO - 'XPC_SERVICE_NAME': '0', 08:10:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 08:10:45 INFO - mozversion application_buildid: 20170116055525 08:10:45 INFO - mozversion application_changeset: 100c34ac81052ad03e9ea0bb68c66df175202280 08:10:45 INFO - mozversion application_display_name: Nightly 08:10:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:45 INFO - mozversion application_name: Firefox 08:10:45 INFO - mozversion application_remotingname: firefox 08:10:45 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 08:10:45 INFO - mozversion application_vendor: Mozilla 08:10:45 INFO - mozversion application_version: 53.0a1 08:10:45 INFO - mozversion platform_buildid: 20170116055525 08:10:45 INFO - mozversion platform_changeset: 100c34ac81052ad03e9ea0bb68c66df175202280 08:10:45 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 08:10:45 INFO - mozversion platform_version: 53.0a1 08:10:45 INFO - using testdate: 1484583045 08:10:45 INFO - actual date: 1484583045 08:10:45 INFO - starting webserver on 'localhost:60500' 08:10:45 INFO - SUITE-START | Running 2 tests 08:10:45 INFO - TEST-START | dromaeo_css 08:10:45 INFO - operating with platform_type : mac_ 08:10:45 INFO - Initialising browser for dromaeo_css test... 08:10:45 INFO - TEST-INFO | started process 2464 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpR5j_vB/profile http://localhost:60500/getInfo.html) 08:10:48 INFO - PROCESS | 2464 | 2017-01-16 08:10:48.614 plugin-container[2466:83322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 08:10:48 INFO - PROCESS | 2464 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:48 INFO - PROCESS | 2464 | 2017-01-16 08:10:48.617 plugin-container[2466:83322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x911b, name = 'com.apple.CFPasteboardClient' 08:10:48 INFO - PROCESS | 2464 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:48 INFO - PROCESS | 2464 | 2017-01-16 08:10:48.617 plugin-container[2466:83322] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:10:48 INFO - PROCESS | 2464 | __metrics Screen width/height:1600/1200 08:10:48 INFO - PROCESS | 2464 | colorDepth:24 08:10:48 INFO - PROCESS | 2464 | Browser inner width/height: 1024/689 08:10:48 INFO - PROCESS | 2464 | __metrics 08:10:48 ERROR - PROCESS | 2464 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3249: TypeError: this.tabs is undefined 08:10:49 INFO - TEST-INFO | 2464: exit 0 08:10:49 INFO - Browser initialized. 08:10:49 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:49 INFO - TEST-INFO | started process 2467 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpR5j_vB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:51 INFO - PROCESS | 2467 | 2017-01-16 08:10:51.867 plugin-container[2468:83445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 08:10:51 INFO - PROCESS | 2467 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:51 INFO - PROCESS | 2467 | 2017-01-16 08:10:51.868 plugin-container[2468:83445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 08:10:51 INFO - PROCESS | 2467 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:51 INFO - PROCESS | 2467 | 2017-01-16 08:10:51.868 plugin-container[2468:83445] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:11:01 INFO - PROCESS | 2467 | RSS: Main: 233754624 08:11:01 INFO - PROCESS | 2467 | 08:14:04 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:60500/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60500/tests/dromaeo/cssquery-ext.html) 08:14:05 INFO - PROCESS | 2467 | RSS: Main: 250773504 08:14:05 INFO - PROCESS | 2467 | 08:15:49 INFO - PROCESS | 2467 | ************************* 08:15:49 INFO - PROCESS | 2467 | A coding exception was thrown and uncaught in a Task. 08:15:49 INFO - PROCESS | 2467 | 08:15:49 ERROR - PROCESS | 2467 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:15:49 INFO - PROCESS | 2467 | Full stack: 08:15:49 INFO - PROCESS | 2467 | ************************* 08:17:07 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:60500/tests/dromaeo/cssquery-ext.html (next: http://localhost:60500/tests/dromaeo/cssquery-jquery.html) 08:17:08 INFO - PROCESS | 2467 | RSS: Main: 261881856 08:17:08 INFO - PROCESS | 2467 | 08:20:10 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:60500/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60500/tests/dromaeo/cssquery-mootools.html) 08:20:11 INFO - PROCESS | 2467 | RSS: Main: 270360576 08:20:11 INFO - PROCESS | 2467 | 08:23:13 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:60500/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60500/tests/dromaeo/cssquery-prototype.html) 08:23:13 INFO - PROCESS | 2467 | RSS: Main: 266477568 08:23:13 INFO - PROCESS | 2467 | 08:26:16 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:60500/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60500/tests/dromaeo/cssquery-yui.html) 08:26:17 INFO - PROCESS | 2467 | RSS: Main: 271257600 08:26:17 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:60500/tests/dromaeo/cssquery-yui.html (next: http://localhost:60500/tests/dromaeo/cssquery-dojo.html) 08:29:20 INFO - PROCESS | 2467 | RSS: Main: 273547264 08:29:20 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | __start_tp_report 08:29:20 INFO - PROCESS | 2467 | _x_x_mozilla_page_load 08:29:20 INFO - PROCESS | 2467 | _x_x_mozilla_page_load_details 08:29:20 INFO - PROCESS | 2467 | |i|pagename|runs| 08:29:20 INFO - PROCESS | 2467 | |0;dojo.html;3215.067630387188;3233.8059716417015;3293.6047674279075;3302.8018318900854;3328.3010567780757;5366.785328586857;5397.163439666848;5415.91876121858;5446.074167391548;5458.0721277382845;5117.385913690353;5426.565874730022;5439.891202175964;5460.34475862897;5571.470710282517;4979.377577802775;5017.623678224129;5028.069807085682;5105.081085404626;5110.437851836295;4923.55687988081;5132.435432102485;5137.922931156017;5138.997895410396;5174.146265866147;4934.851954441372;5112.718800465973;5133.3326667533165;5144.228365745131;5152.484751524855;5010.348654674869;5032.119379108642;5083.110455670243;5098.592112631018;5112.821051263225;4116.814743336557;5041.823536176216;5066.544011039025;5103.948960510422;5148.794048238066;5356.0894647909945;5506.174073888908;5536.058869992111;5591.329040515124;5642.210090587321;5816.534677225812;5888.205092312569;6021.729022194012;6022.759089636409;6049.879002419927;6059.0608455689435;6086.513078953717;6113.510919126459;6131.743695906989;6139.185504090456;5569.916451253234;5650.3219613646215;5656.236408084938;5675.489205971482;5678.063119585305;6041.426064523863;6159.29168145669;6211.409916057967;6215.782447614402;6223.844403889849;5798.855028624318;5839.941600584025;5875.882482350329;5907.7046147692445;5912.46787789101;4941.1847045237855;5247.4490178530705;5809.796657116981;6067.817965461043;6094.451499365078;5150.381954165524;5583.609147359646;5931.436513531207;5986.970065149733;6015.6691381974415;5946.732397042144;6086.695665216722;6088.4215999479975;6098.054801505774;6109.358517355614;4726.172919739032;4768.284757286366;4777.140114779304;4800.231962885921;4853.563179313879;4286.485621725413;4295.806688699016;4299.97850010748;4335.501417336984;4345.109252603209;5588.329400471888;5612.214289999403;5616.9157462638095;5640.802571909963;5671.716414179275;6096.634201947897;6116.3883611638485;6129;6148.323684394713;6154.5076393888385;5450;5461.044317244467;5489.58828087887;5515.255440515479;5588.469095435927;5257.684538927677;5262.736863156827;5269.920951185659;5275.920861187085;5325.600579956519;6102.267727872683;6193.977993631091;6197.938020619827;6199.876002479835;6224.221972253468;5907.852303692442;5940.405959404025;6002.609830361012;6100.145979562776;6101.969490152521;6131.601445906002;6137.478314343331;6138.725610151984;6147.754089836357;6149.877002459836;6030.5778595497895;6034.24571928509;6037.63774173551;6048.092786082123;6079.574429789872;6026.487748541423;6035.39646035393;6067.908981365194;6092.329843717276;6118.724657390339;5575.944240557543;5690.630109042899;5825.3592104867585;5899.705014749159;5939;5907.527397808251;5910.615809972338;5920.970395147997;5937.5249980000635;5972.850678732892;5573.3869274380595;5622.5783066271015;5685.118806584986;5695.401982791913;5767.307923049261;5902.380250073852;5919.23050003514;5927.496162826035;5929.9110513341475;5959.553033522417;5577.749001295033;5621.735109600308;5638.5489160867855;5695.515881150149;5755.107958266475 08:29:20 INFO - PROCESS | 2467 | |1;ext.html;473.29849192154194;474.8860273534353;484.91271571117187;485.9673194461769;491.673037430109;39003.21993560127;41228.79385603075;41366;43267;43333.349999750026;42277.36583951243;42776.57223427765;42809.57190428095;42887.785561072225;42927.78536107323;7148.606826624534;7905.960470197641;9197.310201734863;9433.198178154866;9445.95277023614;11123.610673626425;11418.771624567504;11438.942805285962;11624.82562761555;11646.417679116053;11407.372594507298;11463.426828658576;11478;11487.023602993755;11505.884941150607;11283.774324513546;11295.096392288639;11354.772904541864;11362.977332040156;11424.82862757051;11330.093592512661;11411.885881141248;11411.94294028533;11422.828657570142;11432.542698292058;40746;40864.38703419451;41026.38460423096;41069.58930410688;41184;3780.4140358244517;3993.6805055595482;4030.2141082488633;4044.6359827615656;4169.124483858393;27767.722322776917;30881.69118308833;30882.382352353052;31960;32057;3127.1400364899832;3319.5684561007156;3358.3619112368574;3367.7979321240805;3371.4437117875523;400448;433476.1545268656;434355;437838;439998;70719;71583.56832863313;71686.283137169;71691.2830871695;71697.64151179211;61340;72909.27090729025;73080.26919730841;73837.63081184559;76068;16747;16853.915730421268;16906.661866762843;16972.74540881888;16993;10452.477376131163;11470.254433461798;11733.530658773725;12814.615561533168;12954.093213475155;17564.736528952075;17648;19051.231182168303;21682.24112156067;21794.782052179275;9066.320025998077;9094.954525227464;9125.726228213165;9191.724248272563;9198.95400522993;805.1505661527028;874.5889431967092;874.9168828961366;880.8810810540494;891.6299735609779;30007.099787006424;30471.542926856117;30644.38711225763;31066.84466577698;31203.375932481224;962.9744322296732;994.7612572982432;1008.294194064158;1023.9897601023895;1054.8048611006989;13643.86356136426;14391.496297629743;14392.39916295587;14554.563363099123;14630.26848657563;640836;642700;644085;644138;645776;43386.78306608447;51825.48174518282;51846.74076629593;52449;52528;1143.239745569187;1361.1016728959037;1423.6085076604018;1428.4643258777958;1431.7852322151552;26334.341641459116;27340.316492087855;27413.903443862022;27472.03847865315;27497.725022749517;45093.774531127136;45894.31158532691;46097.769511152226;46125;46236.30645540387;554571;579080;581892;583297;583993;25078.62382064307;25402.237932862045;25452.74547254578;26201.475970480864;26205.868970655025;42279.365809513496;42790.432574045655;43231.13537729291;43562;43627.781861090494;29097.272568185967;34051.4892276621;35654.108647284025;35833.82083089568;37049.259014819014;35607.465888012215;35681.28637427185;36408;36546.26907461889;36623.63376366202;34836.65163348334;34940.65059349374;34975.650243498254;35391;35594.46608300826;24047.51904961856;31066.84466577743;32233.67766322307;32290.515642265855;32299.838500808284;23442.296731098093;23955.76044239605;23972.160974365812;24295.757042429348;24364.87817560901 08:29:20 INFO - PROCESS | 2467 | |2;jquery.html;359.58288385472855;386.32200488143303;399.9100202454448;403.25196760010164;412.22707423580783;19491.317803876867;21325;21847.344579662597;21925.342239732832;22087.57642565401;20536.486587835312;21299.787002129975;21671.916404179767;21803.345899622997;22075.66886496704;5131.666441681288;6004.849878753044;6076.240469941257;6087.05650624154;6092;3195.6484786673445;4035.1727895781296;5963.194968679216;6074.605150665193;7392.630368481581;7841.019872515935;7890.526568405914;7975.042994840598;7979.321757650607;7998.760037198865;7437.81405464865;7761.107472640634;7846.764597062097;7929;7949.403794715419;7480.666789813437;7623.352015078725;7750.689972401097;7801.531908085533;7826.843463130763;29643.55534666982;30315.545266821016;30707.9252226171;30829.845850770602;30913.072607821578;2868.7991840571167;2919.226405002676;2975.181824998121;2979.7318241358166;3047.05541282201;16338;17270;17635;17779.555511112325;18028.918264904016;1676.2959556985973;1934.6904495280828;1958.8442818736871;1995.391405621285;2000.4098790856733;119121.40439297748;120359;120872.99999999913;162175.18912405364;164652.3534764649;23398.64902026471;23980.80335941203;23988.28035158948;24027.759722402552;24286.392840179138;40030.799846000584;42058;42284.57715422806;42426.575734242266;42428.78785606114;5826.533877289891;8576;8794.648214071365;9024.909750902407;9352.812943741086;8555.230029297392;8627.396082274307;8635;8773.868391974125;8803.867941980876;4866.78099485524;4913.631477639191;4962.478939711351;4975.353204083446;5037.672551284155;1689.9831001689827;1773.122304459301;1774.086345532052;2074.377686694016;2104.3792081335737;827.6192951242375;985.8225519406433;1007.1338648762058;1008.9103767930617;1018.0582960761266;21436.571268574855;21667;21705.67441488379;21784.89107554452;21824.45438864041;1019.8572199892021;1032.7779527401644;1053.647028245531;1055.5566662001977;1058.444316733721;10569.154467642571;11101.1674124441;11163.218574699855;11182.993530582287;11198.720031999103;731664;742700;750876;764606;765747;108789;134143;146261;149815;152045;1014.1582486536157;1042.6142327338932;1061.1987949098382;1064.2656566968767;1067.0983019348637;19568.412947611596;20396.286129985972;20463.795362046785;20533.383998479472;20534.69197962002;59843.803123938145;61055.084173738316;61280;61493.69253153705;61663.53345866115;90927.54536227277;92695.29218831171;92700;93039;93651;8397.790055248668;8537.402381833212;8670.739877803677;8705.521196334263;8779.20987111163;8815.823683526422;12415.317157556421;13357.065005449525;13489.055766096279;13811.033227673966;11445.484953177294;11834.289942603471;11987.280763154238;12031.4585843639;12052;11058.336499810037;11099.500522476346;11115.777684446428;11289.887101128883;11490.54037838477;11698.239614425034;11878.96115320795;11881.465334060162;12028.218165819193;12061.698457538632;7225.458090643117;8383.538905360263;8588.78528036779;8785.472871627722;8870.645174193218;9696.5151742414;11640.301581904774;11870.643880683594;11980.28118312904;12037 08:29:20 INFO - PROCESS | 2467 | |3;mootools.html;1201.3752848518775;1336.4520546575902;1458.7155504676587;1471.0291207802845;1489.8659120679138;7507.512011719235;7732.420068494871;8086.23180797824;8516.020657624375;8544.06015338313;7975.361971042317;8154.3884208684285;8261.008678958533;8378.874316885252;8403.327733781298;2301;2338.450464140934;2343.4024323797494;2347.976520234793;2348.1077190667525;2089.51941053557;2161.7838216178334;2317.1310758465575;2357.929262122139;2365.2312998275543;2236.496788222645;2294.942626434344;2297.7012988311494;2307.0541078157958;2325.3256555598855;2236.2955668964305;2249.044156233594;2261.4911644879935;2264.4112530742045;2267.580497607948;2297.310806757983;2312.838101332907;2348.941276468085;2363.61000434928;2374.263978166774;8418.873716894252;8488.53313067781;8499.702510412168;8500.022497412752;8517.914820851836;5065.696058236481;5188.7146206958605;5195.9480405195845;5196.532312091892;5203.9219411708855;3449.1549570355173;3452.7052355366736;3477.3219222251673;3538.3454060998797;3543.326767914088;1193.7493126443458;1201.519392243101;1208.6857417071537;1233.4881024374874;1263.3620021888978;10810.945945270223;10839.024487796058;10891.183161262938;10899.618513352074;10900.34597924127;4361.12777444506;4655.208614535537;4697.224957881911;4708.364370809897;4732.952670473252;7957.721479748297;8111.229433203836;9503.714888553355;9514.952425237829;9521.28590355726;5171.5862730981435;5201;5234.685918844882;5247.658902171346;5266.157414813688;4595.862124136282;4726.676530571445;4799.352087468217;4807.302941073594;4850.466448690641;3574.267275208576;3651.926961460756;3841.615838416136;3896.6103389660807;3957.584453632385;1165.8600967883908;1203.6449247472017;1271.6757226907264;1274.1272228523492;1281.263273617655;685.9573448358467;768.3891306411416;782.3701919954491;830.0661755525034;847.2163248994656;4929.580985616263;5458.945410545924;5602.579806514527;5742.885142297214;5774.88450230993;971.8639390485338;982.6855406270068;987.3878195518824;996.9900300997042;1030.407515678488;3331.3503866746237;3772;3805.257974694963;3834.271488417192;3862.8841134766003;9661.13049825505;9889.109980101824;9932.851007234898;9965.800683986425;9972.152367048882;9971.551280192409;9972.950135249423;9973.052560006789;9975.900240997642;10002.199824014062;7676.654550545127;7799.49303295284;7921.603919803987;7934.444588878839;7952.562609056557;5435.565154787529;5443.074677304947;5595.132754423071;5599.832005039855;5646.3788983212635;9184.448933064037;9263.53682315868;9310.813783724423;9314;9334.113259240361;8315.708950186714;8540.786480338042;9162.496062716615;9373.1564159226;9383;6715.630640314829;6719.428848547928;6739.764108256383;6783.660816959034;6844.8973265402055;6520.804375868732;7779.183185765413;7858.60706964638;7894.171112033384;7901;6950.6177160256675;7059.788206353817;7091.716331346895;7113.715451381887;7178.497505174588;6931.44548436134;6991.755288564876;6995.895061574182;7003.719851205895;7059.71761129549;6285.214348206474;6349.936500635119;6418.550701450854;6421.036844473179;6443.291237963726;5011.649184557046;5048.823291184791;5061.4179369373;5096.643234973516;5130.923036154386;4934.7285899275885;4969.511589568384;5049.419316778617;5145.331106956071;5213.061648903234 08:29:20 INFO - PROCESS | 2467 | |4;prototype.html;359.823686393667;365.2603477957137;374.61227629403567;377.91874746929403;381.0587848015403;4591.816327346906;5153.2270159476;5243.84268471946;5348.732563371836;5502.422245664203;4664.766761661912;5063.784691673999;5323.380101530957;5393.249442784179;5418.241446197536;3877.0695033191973;4169.3954376615375;4240.151969606076;4252.298370768819;4279;3791.545014598238;3907.9023024424473;3942.1327307992196;4072.75563466193;4266.722663026893;3671.375866102769;4035.778032208227;4200.243956087903;4258.9148217035645;4272.551382104881;3804.144067584799;3979.98010009951;4241.597048280424;4279.893002674927;4284.614384705376;3304.9173770655684;3755.34281500739;3776.2825063237897;3786.2427514496935;3796.791176485292;4032.3951407288846;5263.236830659533;5308.018016666929;5313.7608807603365;5324.41431442541;4333.851529344726;4725.432948046222;4950.979902259619;4978.726170060645;4979.211641480746;5144.871378215537;5356.517913387775;5569.220309156722;5814.563907706939;5841.386654401312;3554.5556805399324;3651.8174091295327;3994.3609022556543;4116.941529235382;4188.225178341986;5080.542751152377;5108.948910510921;5970.402959704038;6056.303525094627;6067.666278354689;3627.891163265106;3969.4045893115685;4244.957550424518;4276.72201306914;4278.336857787048;3766.9434958475645;4213.0116208986965;4227.492700875853;4288.764117973541;4345.152695224401;4289.463817022872;4311.8706438806885;4321.67587430944;4687.1958189613915;4898.510148985073;5070.112730272262;5213.869653258699;5486.102439877069;5590.832275031756;5740.6268592541355;1269.9873001270055;1331.0682522234474;1378.6902442679295;1381.979270310946;1408.5703860322494;906.9274458043341;924.4822899176482;931.0177762460669;968.0174622757906;1007.7883644434751;4270;4347.130573885235;4557.541586692293;4702.694324868872;4836.283119492529;4992.430412255182;5248.553872920845;5621.7751289948765;5640.8871822563315;5656.48286512498;4002.9599704002585;4125.50493940729;4613.976930115328;4913.901721965541;4956.909479914413;2429.344077099209;2674.796341646267;2886.422715456917;2914.489964256289;2948.9410211795644;4673.345731597579;4918.532739389752;4959.876003099951;5654.802081927195;5720.1991721159075;5260.079486089995;5602.543338731959;5616.382197958222;5647.604667673306;6043.728032238472;6081.604695694766;6105.297890742621;6117.602355846856;6133.381305030054;6143.447089761943;5905.616134951214;5914.2311499504785;5920.270229755227;5980.491658209101;5992.910106348496;5279.594661443424;5474.671519708831;5591.099026331067;5863.472287494146;5928.79844883758;5046.772895219798;5292.867678308073;5668.433156684298;6162.414570615784;6235.469985051322;3681.3005528949416;3952.960470395259;3959.9406008909314;3960.346542820402;3966.880993570082;4905.558499735041;5702.45826646468;5735.627184233012;5870.679097203095;5917.615355001911;5071.340725705634;5258.710770907637;5567.860803479945;5757.783253447369;5775.566832487496;4796.640251981045;4967.627427942991;5640.266765320482;5652.010898092735;5705.37240903492;5052.837847295069;5317.521423071942;5370.03339398912;5610.76563156105;5676.17695434168;4727.669063165683;5171.991461664939;5741.559837069145;5770.538356931352;5838.766449341979;5541;5578.230612825975;5665.705389317333;5672.347680016851;5712.685802280915 08:29:20 INFO - PROCESS | 2467 | |5;yui.html;628.7610707930985;679.1510611735334;764.5221736414741;794.4438892775057;833.9958300208498;14512.201828899406;15427.228638568084;15439.305231264592;15480.458183963563;15515.146666933315;14336;14462.493812716557;14467;14471.927640361784;14503.854961450383;684.0389253099403;695.2595485807624;849.2781136034383;870.2863651805537;874.0909454167657;871.6382701179003;874.0167311774254;875.5709702245917;878.0648609231148;881.2157180109707;930.1767935377206;930.5393830054132;932.5057719408725;942.1002942190192;946.1484663802598;929.4376901974309;930.042056681619;931.6366617019368;933.8926023507315;934.9065093490631;685.7565564224678;705.2242533213475;706.3254591864757;706.4560288577818;707.1761397971358;14863.925680371636;14965.401383944629;14971.251437428195;14988.100713957198;15676.29456674441;14016.859831401658;14194.006419550635;14268;14449.49426770058;14506.057106288057;11583.420828958519;11681.941590291994;11744.647660570197;11765.352905590189;11822.704432389173;3531.1525233943926;3617.981910090433;3724.404095344732;3776.339140650375;3800.885973420774;103235.96764032339;105282.99999999923;105374.47312763464;105433.47283263535;106740.4662976688;9813.656522021694;10500.317479363817;10548.841767373495;10602.469876506299;10703.625373111903;20799.480012999495;20950;20976.685349719766;21062.473438163863;21086.367408977756;7507.1742108368035;7525.397968162643;7550.471466997367;7674.270944260286;7714.537127772352;4120.361343991686;4126.174765047002;4145.072546258879;4176.415301857743;4206.200821843901;3570.6607872252093;3639.6906262967414;3651.8904432867057;3653.287608916289;3655.373358855337;667.9599224046573;668.6422763821332;675.8817206988757;685.2530741491798;693.9236683964759;664.8304682306077;678.3487851662361;678.7420780103529;684.2404930527111;690.9619970901649;8482.745517634481;10105.444200568892;10330.845037324447;10444;10455.111315538112;554.4400155842548;636.5353292096703;658.5752189837528;692.1590267824516;703.7431337561753;4682.9531704683195;4800.927986080212;4893.486183950634;5120.513551212629;5658.405867383948;103045;103344.48327758463;103720.48139759254;105221;106706;88404.5579772097;88728;89624;89896.550517247;90183.54908225418;76611;79851.60074199592;80040.19959800444;82702.58648706718;82712.75930861042;10792.244542882238;10970.561177553127;11097.556097756;11180.496877640688;11260.493277802354;40960;47398.81502962454;49282.507174929226;49612;49924;65583.34416655773;66374.668126661;68582.77102004227;69746.65126674334;70602.29397705957;13152.473901043852;13251.277333946526;13345.399457024263;13358.666033349244;13531.684737239439;14793.92603036978;14825.555233343017;14947.374686281602;15266.542003739905;15277.847221528087;13802;13847.169169849842;13964.371603277674;14237.715245695235;14548.054376465496;10541.631042913461;13823.585292441243;13944.860551394762;14109.647258818612;14365.353559090076;13638.386272617558;13875.791863122264;13887.513937012156;14147;14312.33247974831;9304.441733496013;11044.226904116635;11288.604898828502;11424.428778560872;11684.896718590735;10551.366917984946;10758.73103172427;10982.341059536453;11004.889951100387;11050.778984420427 08:29:20 INFO - PROCESS | 2467 | __end_tp_report 08:29:20 INFO - PROCESS | 2467 | __start_cc_report 08:29:20 INFO - PROCESS | 2467 | _x_x_mozilla_cycle_collect,374 08:29:20 INFO - PROCESS | 2467 | __end_cc_report 08:29:20 INFO - PROCESS | 2467 | __startTimestamp1484584160533__endTimestamp 08:29:20 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | ------- Summary: start ------- 08:29:20 INFO - PROCESS | 2467 | Number of tests: 6 08:29:20 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | [#0] dojo.html Cycles:165 Average:5547.32 Median:5671.72 stddev:624.40 (11.0%) stddev-sans-first:598.91 08:29:20 INFO - PROCESS | 2467 | Values: 3215.1 3233.8 3293.6 3302.8 3328.3 5366.8 5397.2 5415.9 5446.1 5458.1 5117.4 5426.6 5439.9 5460.3 5571.5 4979.4 5017.6 5028.1 5105.1 5110.4 4923.6 5132.4 5137.9 5139.0 5174.1 4934.9 5112.7 5133.3 5144.2 5152.5 5010.3 5032.1 5083.1 5098.6 5112.8 4116.8 5041.8 5066.5 5103.9 5148.8 5356.1 5506.2 5536.1 5591.3 5642.2 5816.5 5888.2 6021.7 6022.8 6049.9 6059.1 6086.5 6113.5 6131.7 6139.2 5569.9 5650.3 5656.2 5675.5 5678.1 6041.4 6159.3 6211.4 6215.8 6223.8 5798.9 5839.9 5875.9 5907.7 5912.5 4941.2 5247.4 5809.8 6067.8 6094.5 5150.4 5583.6 5931.4 5987.0 6015.7 5946.7 6086.7 6088.4 6098.1 6109.4 4726.2 4768.3 4777.1 4800.2 4853.6 4286.5 4295.8 4300.0 4335.5 4345.1 5588.3 5612.2 5616.9 5640.8 5671.7 6096.6 6116.4 6129.0 6148.3 6154.5 5450.0 5461.0 5489.6 5515.3 5588.5 5257.7 5262.7 5269.9 5275.9 5325.6 6102.3 6194.0 6197.9 6199.9 6224.2 5907.9 5940.4 6002.6 6100.1 6102.0 6131.6 6137.5 6138.7 6147.8 6149.9 6030.6 6034.2 6037.6 6048.1 6079.6 6026.5 6035.4 6067.9 6092.3 6118.7 5575.9 5690.6 5825.4 5899.7 5939.0 5907.5 5910.6 5921.0 5937.5 5972.9 5573.4 5622.6 5685.1 5695.4 5767.3 5902.4 5919.2 5927.5 5929.9 5959.6 5577.7 5621.7 5638.5 5695.5 5755.1 08:29:20 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | [#1] ext.html Cycles:180 Average:68566.44 Median:35630.79 stddev:148935.65 (418.0%) stddev-sans-first:149265.70 08:29:20 INFO - PROCESS | 2467 | Values: 473.3 474.9 484.9 486.0 491.7 39003.2 41228.8 41366.0 43267.0 43333.3 42277.4 42776.6 42809.6 42887.8 42927.8 7148.6 7906.0 9197.3 9433.2 9446.0 11123.6 11418.8 11438.9 11624.8 11646.4 11407.4 11463.4 11478.0 11487.0 11505.9 11283.8 11295.1 11354.8 11363.0 11424.8 11330.1 11411.9 11411.9 11422.8 11432.5 40746.0 40864.4 41026.4 41069.6 41184.0 3780.4 3993.7 4030.2 4044.6 4169.1 27767.7 30881.7 30882.4 31960.0 32057.0 3127.1 3319.6 3358.4 3367.8 3371.4 400448.0 433476.2 434355.0 437838.0 439998.0 70719.0 71583.6 71686.3 71691.3 71697.6 61340.0 72909.3 73080.3 73837.6 76068.0 16747.0 16853.9 16906.7 16972.7 16993.0 10452.5 11470.3 11733.5 12814.6 12954.1 17564.7 17648.0 19051.2 21682.2 21794.8 9066.3 9095.0 9125.7 9191.7 9199.0 805.2 874.6 874.9 880.9 891.6 30007.1 30471.5 30644.4 31066.8 31203.4 963.0 994.8 1008.3 1024.0 1054.8 13643.9 14391.5 14392.4 14554.6 14630.3 640836.0 642700.0 644085.0 644138.0 645776.0 43386.8 51825.5 51846.7 52449.0 52528.0 1143.2 1361.1 1423.6 1428.5 1431.8 26334.3 27340.3 27413.9 27472.0 27497.7 45093.8 45894.3 46097.8 46125.0 46236.3 554571.0 579080.0 581892.0 583297.0 583993.0 25078.6 25402.2 25452.7 26201.5 26205.9 42279.4 42790.4 43231.1 43562.0 43627.8 29097.3 34051.5 35654.1 35833.8 37049.3 35607.5 35681.3 36408.0 36546.3 36623.6 34836.7 34940.7 34975.7 35391.0 35594.5 24047.5 31066.8 32233.7 32290.5 32299.8 23442.3 23955.8 23972.2 24295.8 24364.9 08:29:20 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | [#2] jquery.html Cycles:180 Average:42570.41 Median:13577.60 stddev:124719.71 (918.6%) stddev-sans-first:125029.31 08:29:20 INFO - PROCESS | 2467 | Values: 359.6 386.3 399.9 403.3 412.2 19491.3 21325.0 21847.3 21925.3 22087.6 20536.5 21299.8 21671.9 21803.3 22075.7 5131.7 6004.8 6076.2 6087.1 6092.0 3195.6 4035.2 5963.2 6074.6 7392.6 7841.0 7890.5 7975.0 7979.3 7998.8 7437.8 7761.1 7846.8 7929.0 7949.4 7480.7 7623.4 7750.7 7801.5 7826.8 29643.6 30315.5 30707.9 30829.8 30913.1 2868.8 2919.2 2975.2 2979.7 3047.1 16338.0 17270.0 17635.0 17779.6 18028.9 1676.3 1934.7 1958.8 1995.4 2000.4 119121.4 120359.0 120873.0 162175.2 164652.4 23398.6 23980.8 23988.3 24027.8 24286.4 40030.8 42058.0 42284.6 42426.6 42428.8 5826.5 8576.0 8794.6 9024.9 9352.8 8555.2 8627.4 8635.0 8773.9 8803.9 4866.8 4913.6 4962.5 4975.4 5037.7 1690.0 1773.1 1774.1 2074.4 2104.4 827.6 985.8 1007.1 1008.9 1018.1 21436.6 21667.0 21705.7 21784.9 21824.5 1019.9 1032.8 1053.6 1055.6 1058.4 10569.2 11101.2 11163.2 11183.0 11198.7 731664.0 742700.0 750876.0 764606.0 765747.0 108789.0 134143.0 146261.0 149815.0 152045.0 1014.2 1042.6 1061.2 1064.3 1067.1 19568.4 20396.3 20463.8 20533.4 20534.7 59843.8 61055.1 61280.0 61493.7 61663.5 90927.5 92695.3 92700.0 93039.0 93651.0 8397.8 8537.4 8670.7 8705.5 8779.2 8815.8 12415.3 13357.1 13489.1 13811.0 11445.5 11834.3 11987.3 12031.5 12052.0 11058.3 11099.5 11115.8 11289.9 11490.5 11698.2 11879.0 11881.5 12028.2 12061.7 7225.5 8383.5 8588.8 8785.5 8870.6 9696.5 11640.3 11870.6 11980.3 12037.0 08:29:20 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | [#3] mootools.html Cycles:180 Average:5400.56 Median:5241.17 stddev:2925.92 (55.8%) stddev-sans-first:2917.10 08:29:20 INFO - PROCESS | 2467 | Values: 1201.4 1336.5 1458.7 1471.0 1489.9 7507.5 7732.4 8086.2 8516.0 8544.1 7975.4 8154.4 8261.0 8378.9 8403.3 2301.0 2338.5 2343.4 2348.0 2348.1 2089.5 2161.8 2317.1 2357.9 2365.2 2236.5 2294.9 2297.7 2307.1 2325.3 2236.3 2249.0 2261.5 2264.4 2267.6 2297.3 2312.8 2348.9 2363.6 2374.3 8418.9 8488.5 8499.7 8500.0 8517.9 5065.7 5188.7 5195.9 5196.5 5203.9 3449.2 3452.7 3477.3 3538.3 3543.3 1193.7 1201.5 1208.7 1233.5 1263.4 10810.9 10839.0 10891.2 10899.6 10900.3 4361.1 4655.2 4697.2 4708.4 4733.0 7957.7 8111.2 9503.7 9515.0 9521.3 5171.6 5201.0 5234.7 5247.7 5266.2 4595.9 4726.7 4799.4 4807.3 4850.5 3574.3 3651.9 3841.6 3896.6 3957.6 1165.9 1203.6 1271.7 1274.1 1281.3 686.0 768.4 782.4 830.1 847.2 4929.6 5458.9 5602.6 5742.9 5774.9 971.9 982.7 987.4 997.0 1030.4 3331.4 3772.0 3805.3 3834.3 3862.9 9661.1 9889.1 9932.9 9965.8 9972.2 9971.6 9973.0 9973.1 9975.9 10002.2 7676.7 7799.5 7921.6 7934.4 7952.6 5435.6 5443.1 5595.1 5599.8 5646.4 9184.4 9263.5 9310.8 9314.0 9334.1 8315.7 8540.8 9162.5 9373.2 9383.0 6715.6 6719.4 6739.8 6783.7 6844.9 6520.8 7779.2 7858.6 7894.2 7901.0 6950.6 7059.8 7091.7 7113.7 7178.5 6931.4 6991.8 6995.9 7003.7 7059.7 6285.2 6349.9 6418.6 6421.0 6443.3 5011.6 5048.8 5061.4 5096.6 5130.9 4934.7 4969.5 5049.4 5145.3 5213.1 08:29:20 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | [#4] prototype.html Cycles:180 Average:4569.25 Median:4985.82 stddev:1377.68 (27.6%) stddev-sans-first:1344.83 08:29:20 INFO - PROCESS | 2467 | Values: 359.8 365.3 374.6 377.9 381.1 4591.8 5153.2 5243.8 5348.7 5502.4 4664.8 5063.8 5323.4 5393.2 5418.2 3877.1 4169.4 4240.2 4252.3 4279.0 3791.5 3907.9 3942.1 4072.8 4266.7 3671.4 4035.8 4200.2 4258.9 4272.6 3804.1 3980.0 4241.6 4279.9 4284.6 3304.9 3755.3 3776.3 3786.2 3796.8 4032.4 5263.2 5308.0 5313.8 5324.4 4333.9 4725.4 4951.0 4978.7 4979.2 5144.9 5356.5 5569.2 5814.6 5841.4 3554.6 3651.8 3994.4 4116.9 4188.2 5080.5 5108.9 5970.4 6056.3 6067.7 3627.9 3969.4 4245.0 4276.7 4278.3 3766.9 4213.0 4227.5 4288.8 4345.2 4289.5 4311.9 4321.7 4687.2 4898.5 5070.1 5213.9 5486.1 5590.8 5740.6 1270.0 1331.1 1378.7 1382.0 1408.6 906.9 924.5 931.0 968.0 1007.8 4270.0 4347.1 4557.5 4702.7 4836.3 4992.4 5248.6 5621.8 5640.9 5656.5 4003.0 4125.5 4614.0 4913.9 4956.9 2429.3 2674.8 2886.4 2914.5 2948.9 4673.3 4918.5 4959.9 5654.8 5720.2 5260.1 5602.5 5616.4 5647.6 6043.7 6081.6 6105.3 6117.6 6133.4 6143.4 5905.6 5914.2 5920.3 5980.5 5992.9 5279.6 5474.7 5591.1 5863.5 5928.8 5046.8 5292.9 5668.4 6162.4 6235.5 3681.3 3953.0 3959.9 3960.3 3966.9 4905.6 5702.5 5735.6 5870.7 5917.6 5071.3 5258.7 5567.9 5757.8 5775.6 4796.6 4967.6 5640.3 5652.0 5705.4 5052.8 5317.5 5370.0 5610.8 5676.2 4727.7 5172.0 5741.6 5770.5 5838.8 5541.0 5578.2 5665.7 5672.3 5712.7 08:29:20 INFO - PROCESS | 2467 | 08:29:20 INFO - PROCESS | 2467 | [#5] yui.html Cycles:180 Average:20580.88 Median:3645.79 stddev:29532.62 (810.0%) stddev-sans-first:29577.47 08:29:20 INFO - PROCESS | 2467 | Values: 628.8 679.2 764.5 794.4 834.0 14512.2 15427.2 15439.3 15480.5 15515.1 14336.0 14462.5 14467.0 14471.9 14503.9 684.0 695.3 849.3 870.3 874.1 871.6 874.0 875.6 878.1 881.2 930.2 930.5 932.5 942.1 946.1 929.4 930.0 931.6 933.9 934.9 685.8 705.2 706.3 706.5 707.2 14863.9 14965.4 14971.3 14988.1 15676.3 14016.9 14194.0 14268.0 14449.5 14506.1 11583.4 11681.9 11744.6 11765.4 11822.7 3531.2 3618.0 3724.4 3776.3 3800.9 103236.0 105283.0 105374.5 105433.5 106740.5 9813.7 10500.3 10548.8 10602.5 10703.6 20799.5 20950.0 20976.7 21062.5 21086.4 7507.2 7525.4 7550.5 7674.3 7714.5 4120.4 4126.2 4145.1 4176.4 4206.2 3570.7 3639.7 3651.9 3653.3 3655.4 668.0 668.6 675.9 685.3 693.9 664.8 678.3 678.7 684.2 691.0 8482.7 10105.4 10330.8 10444.0 10455.1 554.4 636.5 658.6 692.2 703.7 4683.0 4800.9 4893.5 5120.5 5658.4 103045.0 103344.5 103720.5 105221.0 106706.0 88404.6 88728.0 89624.0 89896.6 90183.5 76611.0 79851.6 80040.2 82702.6 82712.8 10792.2 10970.6 11097.6 11180.5 11260.5 40960.0 47398.8 49282.5 49612.0 49924.0 65583.3 66374.7 68582.8 69746.7 70602.3 13152.5 13251.3 13345.4 13358.7 13531.7 14793.9 14825.6 14947.4 15266.5 15277.8 13802.0 13847.2 13964.4 14237.7 14548.1 10541.6 13823.6 13944.9 14109.6 14365.4 13638.4 13875.8 13887.5 14147.0 14312.3 9304.4 11044.2 11288.6 11424.4 11684.9 10551.4 10758.7 10982.3 11004.9 11050.8 08:29:20 INFO - PROCESS | 2467 | -------- Summary: end -------- 08:29:20 INFO - PROCESS | 2467 | 08:29:21 INFO - TEST-INFO | 2467: exit 0 08:29:21 INFO - TEST-OK | dromaeo_css | took 1115485ms 08:29:21 INFO - TEST-START | kraken 08:29:21 INFO - operating with platform_type : mac_ 08:29:21 INFO - Initialising browser for kraken test... 08:29:21 INFO - TEST-INFO | started process 2472 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpWXBh7h/profile http://localhost:60500/getInfo.html) 08:29:24 INFO - PROCESS | 2472 | 2017-01-16 08:29:24.207 plugin-container[2473:85696] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 08:29:24 INFO - PROCESS | 2472 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:29:24 INFO - PROCESS | 2472 | 2017-01-16 08:29:24.210 plugin-container[2473:85696] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 08:29:24 INFO - PROCESS | 2472 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:29:24 INFO - PROCESS | 2472 | 2017-01-16 08:29:24.210 plugin-container[2473:85696] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:29:24 INFO - PROCESS | 2472 | __metrics Screen width/height:1600/1200 08:29:24 INFO - PROCESS | 2472 | colorDepth:24 08:29:24 INFO - PROCESS | 2472 | Browser inner width/height: 1024/689 08:29:24 INFO - PROCESS | 2472 | __metrics 08:29:24 ERROR - PROCESS | 2472 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3249: TypeError: this.tabs is undefined 08:29:25 INFO - TEST-INFO | 2472: exit 0 08:29:25 INFO - Browser initialized. 08:29:25 INFO - Running cycle 1/1 for kraken test... 08:29:25 INFO - TEST-INFO | started process 2474 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpWXBh7h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:27 INFO - PROCESS | 2474 | 2017-01-16 08:29:27.698 plugin-container[2475:85818] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 08:29:27 INFO - PROCESS | 2474 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:29:27 INFO - PROCESS | 2474 | 2017-01-16 08:29:27.699 plugin-container[2475:85818] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:29:27 INFO - PROCESS | 2474 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:29:27 INFO - PROCESS | 2474 | 2017-01-16 08:29:27.699 plugin-container[2475:85818] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:29:37 INFO - PROCESS | 2474 | RSS: Main: 233652224 08:29:37 INFO - PROCESS | 2474 | 08:29:40 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60500/tests/kraken/driver.html?testName=audio-beat-detection) 08:29:41 INFO - PROCESS | 2474 | RSS: Main: 246464512 08:29:41 INFO - PROCESS | 2474 | 08:29:44 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60500/tests/kraken/driver.html?testName=audio-dft) 08:29:44 INFO - PROCESS | 2474 | RSS: Main: 254337024 08:29:44 INFO - PROCESS | 2474 | 08:29:48 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60500/tests/kraken/driver.html?testName=audio-fft) 08:29:48 INFO - PROCESS | 2474 | RSS: Main: 256974848 08:29:48 INFO - PROCESS | 2474 | 08:29:50 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60500/tests/kraken/driver.html?testName=audio-oscillator) 08:29:51 INFO - PROCESS | 2474 | RSS: Main: 258146304 08:29:51 INFO - PROCESS | 2474 | 08:29:53 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60500/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:29:54 INFO - PROCESS | 2474 | RSS: Main: 259751936 08:29:54 INFO - PROCESS | 2474 | 08:29:57 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60500/tests/kraken/driver.html?testName=imaging-darkroom) 08:29:57 INFO - PROCESS | 2474 | RSS: Main: 265650176 08:29:57 INFO - PROCESS | 2474 | 08:30:00 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60500/tests/kraken/driver.html?testName=imaging-desaturate) 08:30:01 INFO - PROCESS | 2474 | RSS: Main: 264519680 08:30:01 INFO - PROCESS | 2474 | 08:30:04 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60500/tests/kraken/driver.html?testName=json-parse-financial) 08:30:05 INFO - PROCESS | 2474 | RSS: Main: 264491008 08:30:05 INFO - PROCESS | 2474 | 08:30:07 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60500/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:30:07 INFO - PROCESS | 2474 | RSS: Main: 263835648 08:30:07 INFO - PROCESS | 2474 | 08:30:09 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60500/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:30:10 INFO - PROCESS | 2474 | RSS: Main: 263880704 08:30:10 INFO - PROCESS | 2474 | 08:30:12 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60500/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:30:13 INFO - PROCESS | 2474 | RSS: Main: 264065024 08:30:13 INFO - PROCESS | 2474 | 08:30:16 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60500/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:17 INFO - PROCESS | 2474 | RSS: Main: 264413184 08:30:17 INFO - PROCESS | 2474 | 08:30:19 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60500/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:30:20 INFO - PROCESS | 2474 | RSS: Main: 264204288 08:30:20 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:60500/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60500/tests/kraken/driver.html?testName=ai-astar) 08:30:22 INFO - PROCESS | 2474 | RSS: Main: 266174464 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | __start_tp_report 08:30:22 INFO - PROCESS | 2474 | _x_x_mozilla_page_load 08:30:22 INFO - PROCESS | 2474 | _x_x_mozilla_page_load_details 08:30:22 INFO - PROCESS | 2474 | |i|pagename|runs| 08:30:22 INFO - PROCESS | 2474 | |0;ai-astar;110;177;162;118;119;142;177;118;121;119 08:30:22 INFO - PROCESS | 2474 | |1;audio-beat-detection;146;119;115;154;115;116;114;119;128;153 08:30:22 INFO - PROCESS | 2474 | |2;audio-dft;209;236;233;201;194;197;197;199;200;202 08:30:22 INFO - PROCESS | 2474 | |3;audio-fft;84;61;90;76;85;83;83;83;72;61 08:30:22 INFO - PROCESS | 2474 | |4;audio-oscillator;90;70;86;72;75;108;115;105;71;86 08:30:22 INFO - PROCESS | 2474 | |5;imaging-gaussian-blur;98;87;98;84;109;86;90;87;89;85 08:30:22 INFO - PROCESS | 2474 | |6;imaging-darkroom;95;95;131;109;94;94;115;124;95;149 08:30:22 INFO - PROCESS | 2474 | |7;imaging-desaturate;133;140;147;148;92;150;143;103;146;114 08:30:22 INFO - PROCESS | 2474 | |8;json-parse-financial;62;63;89;62;63;62;63;64;61;60 08:30:22 INFO - PROCESS | 2474 | |9;json-stringify-tinderbox;68;77;80;77;87;68;62;103;64;75 08:30:22 INFO - PROCESS | 2474 | |10;stanford-crypto-aes;92;88;98;90;87;89;91;90;92;88 08:30:22 INFO - PROCESS | 2474 | |11;stanford-crypto-ccm;139;148;144;148;164;142;142;168;181;161 08:30:22 INFO - PROCESS | 2474 | |12;stanford-crypto-pbkdf2;181;173;177;176;175;173;176;175;174;173 08:30:22 INFO - PROCESS | 2474 | |13;stanford-crypto-sha256-iterative;70;69;71;71;68;69;73;69;67;62 08:30:22 INFO - PROCESS | 2474 | __end_tp_report 08:30:22 INFO - PROCESS | 2474 | __start_cc_report 08:30:22 INFO - PROCESS | 2474 | _x_x_mozilla_cycle_collect,921 08:30:22 INFO - PROCESS | 2474 | __end_cc_report 08:30:22 INFO - PROCESS | 2474 | __startTimestamp1484584222590__endTimestamp 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | ------- Summary: start ------- 08:30:22 INFO - PROCESS | 2474 | Number of tests: 14 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#0] ai-astar Cycles:10 Average:136.30 Median:131.50 stddev:26.25 (20.0%) stddev-sans-first:26.06 08:30:22 INFO - PROCESS | 2474 | Values: 110.0 177.0 162.0 118.0 119.0 142.0 177.0 118.0 121.0 119.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:123.50 stddev:16.55 (13.4%) stddev-sans-first:16.21 08:30:22 INFO - PROCESS | 2474 | Values: 146.0 119.0 115.0 154.0 115.0 116.0 114.0 119.0 128.0 153.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#2] audio-dft Cycles:10 Average:206.80 Median:201.50 stddev:15.14 (7.5%) stddev-sans-first:16.04 08:30:22 INFO - PROCESS | 2474 | Values: 209.0 236.0 233.0 201.0 194.0 197.0 197.0 199.0 200.0 202.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#3] audio-fft Cycles:10 Average:77.80 Median:83.00 stddev:10.12 (12.2%) stddev-sans-first:10.48 08:30:22 INFO - PROCESS | 2474 | Values: 84.0 61.0 90.0 76.0 85.0 83.0 83.0 83.0 72.0 61.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:73.50 stddev:16.56 (22.5%) stddev-sans-first:17.54 08:30:22 INFO - PROCESS | 2474 | Values: 90.0 70.0 86.0 72.0 75.0 108.0 115.0 105.0 71.0 86.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:88.00 stddev:7.94 (9.0%) stddev-sans-first:8.05 08:30:22 INFO - PROCESS | 2474 | Values: 98.0 87.0 98.0 84.0 109.0 86.0 90.0 87.0 89.0 85.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#6] imaging-darkroom Cycles:10 Average:110.10 Median:94.00 stddev:19.35 (20.6%) stddev-sans-first:19.74 08:30:22 INFO - PROCESS | 2474 | Values: 95.0 95.0 131.0 109.0 94.0 94.0 115.0 124.0 95.0 149.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#7] imaging-desaturate Cycles:10 Average:131.60 Median:146.50 stddev:20.95 (14.3%) stddev-sans-first:22.22 08:30:22 INFO - PROCESS | 2474 | Values: 133.0 140.0 147.0 148.0 92.0 150.0 143.0 103.0 146.0 114.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:63.00 stddev:8.54 (13.6%) stddev-sans-first:9.00 08:30:22 INFO - PROCESS | 2474 | Values: 62.0 63.0 89.0 62.0 63.0 62.0 63.0 64.0 61.0 60.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#9] json-stringify-tinderbox Cycles:10 Average:76.10 Median:76.00 stddev:12.19 (16.0%) stddev-sans-first:12.57 08:30:22 INFO - PROCESS | 2474 | Values: 68.0 77.0 80.0 77.0 87.0 68.0 62.0 103.0 64.0 75.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:90.50 stddev:3.14 (3.5%) stddev-sans-first:3.28 08:30:22 INFO - PROCESS | 2474 | Values: 92.0 88.0 98.0 90.0 87.0 89.0 91.0 90.0 92.0 88.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:154.50 stddev:13.98 (9.0%) stddev-sans-first:13.77 08:30:22 INFO - PROCESS | 2474 | Values: 139.0 148.0 144.0 148.0 164.0 142.0 142.0 168.0 181.0 161.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:175.50 stddev:2.45 (1.4%) stddev-sans-first:1.50 08:30:22 INFO - PROCESS | 2474 | Values: 181.0 173.0 177.0 176.0 175.0 173.0 176.0 175.0 174.0 173.0 08:30:22 INFO - PROCESS | 2474 | 08:30:22 INFO - PROCESS | 2474 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.50 stddev:2.96 (4.3%) stddev-sans-first:3.11 08:30:22 INFO - PROCESS | 2474 | Values: 70.0 69.0 71.0 71.0 68.0 69.0 73.0 69.0 67.0 62.0 08:30:22 INFO - PROCESS | 2474 | -------- Summary: end -------- 08:30:22 INFO - PROCESS | 2474 | 08:30:23 INFO - TEST-INFO | 2474: exit 0 08:30:23 INFO - TEST-OK | kraken | took 62239ms 08:30:23 INFO - SUITE-END | took 1177s 08:30:23 INFO - Completed test suite (00:19:37) 08:30:23 INFO - javascript benchmark 08:30:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7429.894344049502, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3215.067630387188, 3233.8059716417015, 3293.6047674279075, 3302.8018318900854, 3328.3010567780757, 5366.785328586857, 5397.163439666848, 5415.91876121858, 5446.074167391548, 5458.0721277382845, 5117.385913690353, 5426.565874730022, 5439.891202175964, 5460.34475862897, 5571.470710282517, 4979.377577802775, 5017.623678224129, 5028.069807085682, 5105.081085404626, 5110.437851836295, 4923.55687988081, 5132.435432102485, 5137.922931156017, 5138.997895410396, 5174.146265866147, 4934.851954441372, 5112.718800465973, 5133.3326667533165, 5144.228365745131, 5152.484751524855, 5010.348654674869, 5032.119379108642, 5083.110455670243, 5098.592112631018, 5112.821051263225, 4116.814743336557, 5041.823536176216, 5066.544011039025, 5103.948960510422, 5148.794048238066, 5356.0894647909945, 5506.174073888908, 5536.058869992111, 5591.329040515124, 5642.210090587321, 5816.534677225812, 5888.205092312569, 6021.729022194012, 6022.759089636409, 6049.879002419927, 6059.0608455689435, 6086.513078953717, 6113.510919126459, 6131.743695906989, 6139.185504090456, 5569.916451253234, 5650.3219613646215, 5656.236408084938, 5675.489205971482, 5678.063119585305, 6041.426064523863, 6159.29168145669, 6211.409916057967, 6215.782447614402, 6223.844403889849, 5798.855028624318, 5839.941600584025, 5875.882482350329, 5907.7046147692445, 5912.46787789101, 4941.1847045237855, 5247.4490178530705, 5809.796657116981, 6067.817965461043, 6094.451499365078, 5150.381954165524, 5583.609147359646, 5931.436513531207, 5986.970065149733, 6015.6691381974415, 5946.732397042144, 6086.695665216722, 6088.4215999479975, 6098.054801505774, 6109.358517355614, 4726.172919739032, 4768.284757286366, 4777.140114779304, 4800.231962885921, 4853.563179313879, 4286.485621725413, 4295.806688699016, 4299.97850010748, 4335.501417336984, 4345.109252603209, 5588.329400471888, 5612.214289999403, 5616.9157462638095, 5640.802571909963, 5671.716414179275, 6096.634201947897, 6116.3883611638485, 6129.0, 6148.323684394713, 6154.5076393888385, 5450.0, 5461.044317244467, 5489.58828087887, 5515.255440515479, 5588.469095435927, 5257.684538927677, 5262.736863156827, 5269.920951185659, 5275.920861187085, 5325.600579956519, 6102.267727872683, 6193.977993631091, 6197.938020619827, 6199.876002479835, 6224.221972253468, 5907.852303692442, 5940.405959404025, 6002.609830361012, 6100.145979562776, 6101.969490152521, 6131.601445906002, 6137.478314343331, 6138.725610151984, 6147.754089836357, 6149.877002459836, 6030.5778595497895, 6034.24571928509, 6037.63774173551, 6048.092786082123, 6079.574429789872, 6026.487748541423, 6035.39646035393, 6067.908981365194, 6092.329843717276, 6118.724657390339, 5575.944240557543, 5690.630109042899, 5825.3592104867585, 5899.705014749159, 5939.0, 5907.527397808251, 5910.615809972338, 5920.970395147997, 5937.5249980000635, 5972.850678732892, 5573.3869274380595, 5622.5783066271015, 5685.118806584986, 5695.401982791913, 5767.307923049261, 5902.380250073852, 5919.23050003514, 5927.496162826035, 5929.9110513341475, 5959.553033522417, 5577.749001295033, 5621.735109600308, 5638.5489160867855, 5695.515881150149, 5755.107958266475], "value": 5507.925293100126, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.29849192154194, 474.8860273534353, 484.91271571117187, 485.9673194461769, 491.673037430109, 39003.21993560127, 41228.79385603075, 41366.0, 43267.0, 43333.349999750026, 42277.36583951243, 42776.57223427765, 42809.57190428095, 42887.785561072225, 42927.78536107323, 7148.606826624534, 7905.960470197641, 9197.310201734863, 9433.198178154866, 9445.95277023614, 11123.610673626425, 11418.771624567504, 11438.942805285962, 11624.82562761555, 11646.417679116053, 11407.372594507298, 11463.426828658576, 11478.0, 11487.023602993755, 11505.884941150607, 11283.774324513546, 11295.096392288639, 11354.772904541864, 11362.977332040156, 11424.82862757051, 11330.093592512661, 11411.885881141248, 11411.94294028533, 11422.828657570142, 11432.542698292058, 40746.0, 40864.38703419451, 41026.38460423096, 41069.58930410688, 41184.0, 3780.4140358244517, 3993.6805055595482, 4030.2141082488633, 4044.6359827615656, 4169.124483858393, 27767.722322776917, 30881.69118308833, 30882.382352353052, 31960.0, 32057.0, 3127.1400364899832, 3319.5684561007156, 3358.3619112368574, 3367.7979321240805, 3371.4437117875523, 400448.0, 433476.1545268656, 434355.0, 437838.0, 439998.0, 70719.0, 71583.56832863313, 71686.283137169, 71691.2830871695, 71697.64151179211, 61340.0, 72909.27090729025, 73080.26919730841, 73837.63081184559, 76068.0, 16747.0, 16853.915730421268, 16906.661866762843, 16972.74540881888, 16993.0, 10452.477376131163, 11470.254433461798, 11733.530658773725, 12814.615561533168, 12954.093213475155, 17564.736528952075, 17648.0, 19051.231182168303, 21682.24112156067, 21794.782052179275, 9066.320025998077, 9094.954525227464, 9125.726228213165, 9191.724248272563, 9198.95400522993, 805.1505661527028, 874.5889431967092, 874.9168828961366, 880.8810810540494, 891.6299735609779, 30007.099787006424, 30471.542926856117, 30644.38711225763, 31066.84466577698, 31203.375932481224, 962.9744322296732, 994.7612572982432, 1008.294194064158, 1023.9897601023895, 1054.8048611006989, 13643.86356136426, 14391.496297629743, 14392.39916295587, 14554.563363099123, 14630.26848657563, 640836.0, 642700.0, 644085.0, 644138.0, 645776.0, 43386.78306608447, 51825.48174518282, 51846.74076629593, 52449.0, 52528.0, 1143.239745569187, 1361.1016728959037, 1423.6085076604018, 1428.4643258777958, 1431.7852322151552, 26334.341641459116, 27340.316492087855, 27413.903443862022, 27472.03847865315, 27497.725022749517, 45093.774531127136, 45894.31158532691, 46097.769511152226, 46125.0, 46236.30645540387, 554571.0, 579080.0, 581892.0, 583297.0, 583993.0, 25078.62382064307, 25402.237932862045, 25452.74547254578, 26201.475970480864, 26205.868970655025, 42279.365809513496, 42790.432574045655, 43231.13537729291, 43562.0, 43627.781861090494, 29097.272568185967, 34051.4892276621, 35654.108647284025, 35833.82083089568, 37049.259014819014, 35607.465888012215, 35681.28637427185, 36408.0, 36546.26907461889, 36623.63376366202, 34836.65163348334, 34940.65059349374, 34975.650243498254, 35391.0, 35594.46608300826, 24047.51904961856, 31066.84466577743, 32233.67766322307, 32290.515642265855, 32299.838500808284, 23442.296731098093, 23955.76044239605, 23972.160974365812, 24295.757042429348, 24364.87817560901], "value": 20017.772767575865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.58288385472855, 386.32200488143303, 399.9100202454448, 403.25196760010164, 412.22707423580783, 19491.317803876867, 21325.0, 21847.344579662597, 21925.342239732832, 22087.57642565401, 20536.486587835312, 21299.787002129975, 21671.916404179767, 21803.345899622997, 22075.66886496704, 5131.666441681288, 6004.849878753044, 6076.240469941257, 6087.05650624154, 6092.0, 3195.6484786673445, 4035.1727895781296, 5963.194968679216, 6074.605150665193, 7392.630368481581, 7841.019872515935, 7890.526568405914, 7975.042994840598, 7979.321757650607, 7998.760037198865, 7437.81405464865, 7761.107472640634, 7846.764597062097, 7929.0, 7949.403794715419, 7480.666789813437, 7623.352015078725, 7750.689972401097, 7801.531908085533, 7826.843463130763, 29643.55534666982, 30315.545266821016, 30707.9252226171, 30829.845850770602, 30913.072607821578, 2868.7991840571167, 2919.226405002676, 2975.181824998121, 2979.7318241358166, 3047.05541282201, 16338.0, 17270.0, 17635.0, 17779.555511112325, 18028.918264904016, 1676.2959556985973, 1934.6904495280828, 1958.8442818736871, 1995.391405621285, 2000.4098790856733, 119121.40439297748, 120359.0, 120872.99999999913, 162175.18912405364, 164652.3534764649, 23398.64902026471, 23980.80335941203, 23988.28035158948, 24027.759722402552, 24286.392840179138, 40030.799846000584, 42058.0, 42284.57715422806, 42426.575734242266, 42428.78785606114, 5826.533877289891, 8576.0, 8794.648214071365, 9024.909750902407, 9352.812943741086, 8555.230029297392, 8627.396082274307, 8635.0, 8773.868391974125, 8803.867941980876, 4866.78099485524, 4913.631477639191, 4962.478939711351, 4975.353204083446, 5037.672551284155, 1689.9831001689827, 1773.122304459301, 1774.086345532052, 2074.377686694016, 2104.3792081335737, 827.6192951242375, 985.8225519406433, 1007.1338648762058, 1008.9103767930617, 1018.0582960761266, 21436.571268574855, 21667.0, 21705.67441488379, 21784.89107554452, 21824.45438864041, 1019.8572199892021, 1032.7779527401644, 1053.647028245531, 1055.5566662001977, 1058.444316733721, 10569.154467642571, 11101.1674124441, 11163.218574699855, 11182.993530582287, 11198.720031999103, 731664.0, 742700.0, 750876.0, 764606.0, 765747.0, 108789.0, 134143.0, 146261.0, 149815.0, 152045.0, 1014.1582486536157, 1042.6142327338932, 1061.1987949098382, 1064.2656566968767, 1067.0983019348637, 19568.412947611596, 20396.286129985972, 20463.795362046785, 20533.383998479472, 20534.69197962002, 59843.803123938145, 61055.084173738316, 61280.0, 61493.69253153705, 61663.53345866115, 90927.54536227277, 92695.29218831171, 92700.0, 93039.0, 93651.0, 8397.790055248668, 8537.402381833212, 8670.739877803677, 8705.521196334263, 8779.20987111163, 8815.823683526422, 12415.317157556421, 13357.065005449525, 13489.055766096279, 13811.033227673966, 11445.484953177294, 11834.289942603471, 11987.280763154238, 12031.4585843639, 12052.0, 11058.336499810037, 11099.500522476346, 11115.777684446428, 11289.887101128883, 11490.54037838477, 11698.239614425034, 11878.96115320795, 11881.465334060162, 12028.218165819193, 12061.698457538632, 7225.458090643117, 8383.538905360263, 8588.78528036779, 8785.472871627722, 8870.645174193218, 9696.5151742414, 11640.301581904774, 11870.643880683594, 11980.28118312904, 12037.0], "value": 11053.618736265315, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1201.3752848518775, 1336.4520546575902, 1458.7155504676587, 1471.0291207802845, 1489.8659120679138, 7507.512011719235, 7732.420068494871, 8086.23180797824, 8516.020657624375, 8544.06015338313, 7975.361971042317, 8154.3884208684285, 8261.008678958533, 8378.874316885252, 8403.327733781298, 2301.0, 2338.450464140934, 2343.4024323797494, 2347.976520234793, 2348.1077190667525, 2089.51941053557, 2161.7838216178334, 2317.1310758465575, 2357.929262122139, 2365.2312998275543, 2236.496788222645, 2294.942626434344, 2297.7012988311494, 2307.0541078157958, 2325.3256555598855, 2236.2955668964305, 2249.044156233594, 2261.4911644879935, 2264.4112530742045, 2267.580497607948, 2297.310806757983, 2312.838101332907, 2348.941276468085, 2363.61000434928, 2374.263978166774, 8418.873716894252, 8488.53313067781, 8499.702510412168, 8500.022497412752, 8517.914820851836, 5065.696058236481, 5188.7146206958605, 5195.9480405195845, 5196.532312091892, 5203.9219411708855, 3449.1549570355173, 3452.7052355366736, 3477.3219222251673, 3538.3454060998797, 3543.326767914088, 1193.7493126443458, 1201.519392243101, 1208.6857417071537, 1233.4881024374874, 1263.3620021888978, 10810.945945270223, 10839.024487796058, 10891.183161262938, 10899.618513352074, 10900.34597924127, 4361.12777444506, 4655.208614535537, 4697.224957881911, 4708.364370809897, 4732.952670473252, 7957.721479748297, 8111.229433203836, 9503.714888553355, 9514.952425237829, 9521.28590355726, 5171.5862730981435, 5201.0, 5234.685918844882, 5247.658902171346, 5266.157414813688, 4595.862124136282, 4726.676530571445, 4799.352087468217, 4807.302941073594, 4850.466448690641, 3574.267275208576, 3651.926961460756, 3841.615838416136, 3896.6103389660807, 3957.584453632385, 1165.8600967883908, 1203.6449247472017, 1271.6757226907264, 1274.1272228523492, 1281.263273617655, 685.9573448358467, 768.3891306411416, 782.3701919954491, 830.0661755525034, 847.2163248994656, 4929.580985616263, 5458.945410545924, 5602.579806514527, 5742.885142297214, 5774.88450230993, 971.8639390485338, 982.6855406270068, 987.3878195518824, 996.9900300997042, 1030.407515678488, 3331.3503866746237, 3772.0, 3805.257974694963, 3834.271488417192, 3862.8841134766003, 9661.13049825505, 9889.109980101824, 9932.851007234898, 9965.800683986425, 9972.152367048882, 9971.551280192409, 9972.950135249423, 9973.052560006789, 9975.900240997642, 10002.199824014062, 7676.654550545127, 7799.49303295284, 7921.603919803987, 7934.444588878839, 7952.562609056557, 5435.565154787529, 5443.074677304947, 5595.132754423071, 5599.832005039855, 5646.3788983212635, 9184.448933064037, 9263.53682315868, 9310.813783724423, 9314.0, 9334.113259240361, 8315.708950186714, 8540.786480338042, 9162.496062716615, 9373.1564159226, 9383.0, 6715.630640314829, 6719.428848547928, 6739.764108256383, 6783.660816959034, 6844.8973265402055, 6520.804375868732, 7779.183185765413, 7858.60706964638, 7894.171112033384, 7901.0, 6950.6177160256675, 7059.788206353817, 7091.716331346895, 7113.715451381887, 7178.497505174588, 6931.44548436134, 6991.755288564876, 6995.895061574182, 7003.719851205895, 7059.71761129549, 6285.214348206474, 6349.936500635119, 6418.550701450854, 6421.036844473179, 6443.291237963726, 5011.649184557046, 5048.823291184791, 5061.4179369373, 5096.643234973516, 5130.923036154386, 4934.7285899275885, 4969.511589568384, 5049.419316778617, 5145.331106956071, 5213.061648903234], "value": 4392.432213753427, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.823686393667, 365.2603477957137, 374.61227629403567, 377.91874746929403, 381.0587848015403, 4591.816327346906, 5153.2270159476, 5243.84268471946, 5348.732563371836, 5502.422245664203, 4664.766761661912, 5063.784691673999, 5323.380101530957, 5393.249442784179, 5418.241446197536, 3877.0695033191973, 4169.3954376615375, 4240.151969606076, 4252.298370768819, 4279.0, 3791.545014598238, 3907.9023024424473, 3942.1327307992196, 4072.75563466193, 4266.722663026893, 3671.375866102769, 4035.778032208227, 4200.243956087903, 4258.9148217035645, 4272.551382104881, 3804.144067584799, 3979.98010009951, 4241.597048280424, 4279.893002674927, 4284.614384705376, 3304.9173770655684, 3755.34281500739, 3776.2825063237897, 3786.2427514496935, 3796.791176485292, 4032.3951407288846, 5263.236830659533, 5308.018016666929, 5313.7608807603365, 5324.41431442541, 4333.851529344726, 4725.432948046222, 4950.979902259619, 4978.726170060645, 4979.211641480746, 5144.871378215537, 5356.517913387775, 5569.220309156722, 5814.563907706939, 5841.386654401312, 3554.5556805399324, 3651.8174091295327, 3994.3609022556543, 4116.941529235382, 4188.225178341986, 5080.542751152377, 5108.948910510921, 5970.402959704038, 6056.303525094627, 6067.666278354689, 3627.891163265106, 3969.4045893115685, 4244.957550424518, 4276.72201306914, 4278.336857787048, 3766.9434958475645, 4213.0116208986965, 4227.492700875853, 4288.764117973541, 4345.152695224401, 4289.463817022872, 4311.8706438806885, 4321.67587430944, 4687.1958189613915, 4898.510148985073, 5070.112730272262, 5213.869653258699, 5486.102439877069, 5590.832275031756, 5740.6268592541355, 1269.9873001270055, 1331.0682522234474, 1378.6902442679295, 1381.979270310946, 1408.5703860322494, 906.9274458043341, 924.4822899176482, 931.0177762460669, 968.0174622757906, 1007.7883644434751, 4270.0, 4347.130573885235, 4557.541586692293, 4702.694324868872, 4836.283119492529, 4992.430412255182, 5248.553872920845, 5621.7751289948765, 5640.8871822563315, 5656.48286512498, 4002.9599704002585, 4125.50493940729, 4613.976930115328, 4913.901721965541, 4956.909479914413, 2429.344077099209, 2674.796341646267, 2886.422715456917, 2914.489964256289, 2948.9410211795644, 4673.345731597579, 4918.532739389752, 4959.876003099951, 5654.802081927195, 5720.1991721159075, 5260.079486089995, 5602.543338731959, 5616.382197958222, 5647.604667673306, 6043.728032238472, 6081.604695694766, 6105.297890742621, 6117.602355846856, 6133.381305030054, 6143.447089761943, 5905.616134951214, 5914.2311499504785, 5920.270229755227, 5980.491658209101, 5992.910106348496, 5279.594661443424, 5474.671519708831, 5591.099026331067, 5863.472287494146, 5928.79844883758, 5046.772895219798, 5292.867678308073, 5668.433156684298, 6162.414570615784, 6235.469985051322, 3681.3005528949416, 3952.960470395259, 3959.9406008909314, 3960.346542820402, 3966.880993570082, 4905.558499735041, 5702.45826646468, 5735.627184233012, 5870.679097203095, 5917.615355001911, 5071.340725705634, 5258.710770907637, 5567.860803479945, 5757.783253447369, 5775.566832487496, 4796.640251981045, 4967.627427942991, 5640.266765320482, 5652.010898092735, 5705.37240903492, 5052.837847295069, 5317.521423071942, 5370.03339398912, 5610.76563156105, 5676.17695434168, 4727.669063165683, 5171.991461664939, 5741.559837069145, 5770.538356931352, 5838.766449341979, 5541.0, 5578.230612825975, 5665.705389317333, 5672.347680016851, 5712.685802280915], "value": 4152.880082588553, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.7610707930985, 679.1510611735334, 764.5221736414741, 794.4438892775057, 833.9958300208498, 14512.201828899406, 15427.228638568084, 15439.305231264592, 15480.458183963563, 15515.146666933315, 14336.0, 14462.493812716557, 14467.0, 14471.927640361784, 14503.854961450383, 684.0389253099403, 695.2595485807624, 849.2781136034383, 870.2863651805537, 874.0909454167657, 871.6382701179003, 874.0167311774254, 875.5709702245917, 878.0648609231148, 881.2157180109707, 930.1767935377206, 930.5393830054132, 932.5057719408725, 942.1002942190192, 946.1484663802598, 929.4376901974309, 930.042056681619, 931.6366617019368, 933.8926023507315, 934.9065093490631, 685.7565564224678, 705.2242533213475, 706.3254591864757, 706.4560288577818, 707.1761397971358, 14863.925680371636, 14965.401383944629, 14971.251437428195, 14988.100713957198, 15676.29456674441, 14016.859831401658, 14194.006419550635, 14268.0, 14449.49426770058, 14506.057106288057, 11583.420828958519, 11681.941590291994, 11744.647660570197, 11765.352905590189, 11822.704432389173, 3531.1525233943926, 3617.981910090433, 3724.404095344732, 3776.339140650375, 3800.885973420774, 103235.96764032339, 105282.99999999923, 105374.47312763464, 105433.47283263535, 106740.4662976688, 9813.656522021694, 10500.317479363817, 10548.841767373495, 10602.469876506299, 10703.625373111903, 20799.480012999495, 20950.0, 20976.685349719766, 21062.473438163863, 21086.367408977756, 7507.1742108368035, 7525.397968162643, 7550.471466997367, 7674.270944260286, 7714.537127772352, 4120.361343991686, 4126.174765047002, 4145.072546258879, 4176.415301857743, 4206.200821843901, 3570.6607872252093, 3639.6906262967414, 3651.8904432867057, 3653.287608916289, 3655.373358855337, 667.9599224046573, 668.6422763821332, 675.8817206988757, 685.2530741491798, 693.9236683964759, 664.8304682306077, 678.3487851662361, 678.7420780103529, 684.2404930527111, 690.9619970901649, 8482.745517634481, 10105.444200568892, 10330.845037324447, 10444.0, 10455.111315538112, 554.4400155842548, 636.5353292096703, 658.5752189837528, 692.1590267824516, 703.7431337561753, 4682.9531704683195, 4800.927986080212, 4893.486183950634, 5120.513551212629, 5658.405867383948, 103045.0, 103344.48327758463, 103720.48139759254, 105221.0, 106706.0, 88404.5579772097, 88728.0, 89624.0, 89896.550517247, 90183.54908225418, 76611.0, 79851.60074199592, 80040.19959800444, 82702.58648706718, 82712.75930861042, 10792.244542882238, 10970.561177553127, 11097.556097756, 11180.496877640688, 11260.493277802354, 40960.0, 47398.81502962454, 49282.507174929226, 49612.0, 49924.0, 65583.34416655773, 66374.668126661, 68582.77102004227, 69746.65126674334, 70602.29397705957, 13152.473901043852, 13251.277333946526, 13345.399457024263, 13358.666033349244, 13531.684737239439, 14793.92603036978, 14825.555233343017, 14947.374686281602, 15266.542003739905, 15277.847221528087, 13802.0, 13847.169169849842, 13964.371603277674, 14237.715245695235, 14548.054376465496, 10541.631042913461, 13823.585292441243, 13944.860551394762, 14109.647258818612, 14365.353559090076, 13638.386272617558, 13875.791863122264, 13887.513937012156, 14147.0, 14312.33247974831, 9304.441733496013, 11044.226904116635, 11288.604898828502, 11424.428778560872, 11684.896718590735, 10551.366917984946, 10758.73103172427, 10982.341059536453, 11004.889951100387, 11050.778984420427], "value": 7566.292681547903, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1599.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 177.0, 162.0, 118.0, 119.0, 142.0, 177.0, 118.0, 121.0, 119.0], "value": 136.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 119.0, 115.0, 154.0, 115.0, 116.0, 114.0, 119.0, 128.0, 153.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 236.0, 233.0, 201.0, 194.0, 197.0, 197.0, 199.0, 200.0, 202.0], "value": 206.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 90.0, 76.0, 85.0, 83.0, 83.0, 83.0, 72.0, 61.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 86.0, 72.0, 75.0, 108.0, 115.0, 105.0, 71.0, 86.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 87.0, 98.0, 84.0, 109.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 131.0, 109.0, 94.0, 94.0, 115.0, 124.0, 95.0, 149.0], "value": 110.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 140.0, 147.0, 148.0, 92.0, 150.0, 143.0, 103.0, 146.0, 114.0], "value": 131.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 89.0, 62.0, 63.0, 62.0, 63.0, 64.0, 61.0, 60.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 80.0, 77.0, 87.0, 68.0, 62.0, 103.0, 64.0, 75.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 98.0, 90.0, 87.0, 89.0, 91.0, 90.0, 92.0, 88.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 148.0, 144.0, 148.0, 164.0, 142.0, 142.0, 168.0, 181.0, 161.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 173.0, 177.0, 176.0, 175.0, 173.0, 176.0, 175.0, 174.0, 173.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 71.0, 68.0, 69.0, 73.0, 69.0, 67.0, 62.0], "value": 68.9, "unit": "score"}]}]} 08:30:23 INFO - Return code: 0 08:30:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:30:23 ERROR - # TBPL SUCCESS # 08:30:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:30:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:30:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:30:23 INFO - 2017-01-16 08:30:23.925 screenresolution[2480:86199] starting screenresolution argv=screenresolution get 08:30:23 INFO - 2017-01-16 08:30:23.927 screenresolution[2480:86199] Display 0: 1600x1200x32@60 08:30:23 INFO - 2017-01-16 08:30:23.934 screenresolution[2481:86202] starting screenresolution argv=screenresolution list 08:30:23 INFO - Available Modes on Display 0 08:30:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:30:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:30:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:30:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:30:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:30:24 INFO - Intel Iris: 08:30:24 INFO - Chipset Model: Intel Iris 08:30:24 INFO - Type: GPU 08:30:24 INFO - Bus: Built-In 08:30:24 INFO - VRAM (Dynamic, Max): 1536 MB 08:30:24 INFO - Vendor: Intel (0x8086) 08:30:24 INFO - Device ID: 0x0a2e 08:30:24 INFO - Revision ID: 0x0009 08:30:24 INFO - Displays: 08:30:24 INFO - Display: 08:30:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:30:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:30:24 INFO - Display Serial Number: 1600x1200 60 08:30:24 INFO - Main Display: Yes 08:30:24 INFO - Mirror: Off 08:30:24 INFO - Online: Yes 08:30:24 INFO - Rotation: Supported 08:30:24 INFO - Return code: 0 08:30:24 INFO - Running post-action listener: _resource_record_post_action 08:30:24 INFO - [mozharness: 2017-01-16 16:30:24.168850Z] Finished run-tests step (success) 08:30:24 INFO - Running post-run listener: _resource_record_post_run 08:30:24 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 890368; Write bytes: 557658112; Read time: 5; Write time: 1716 08:30:24 INFO - TinderboxPrint: CPU usage
54.5% 08:30:24 INFO - TinderboxPrint: I/O read bytes / time
890,368 / 5 08:30:24 INFO - TinderboxPrint: I/O write bytes / time
557,658,112 / 1,716 08:30:24 INFO - TinderboxPrint: CPU idle
2,184.0 (45.5%) 08:30:24 INFO - TinderboxPrint: CPU system
974.2 (20.3%) 08:30:24 INFO - TinderboxPrint: CPU user
1,643.5 (34.2%) 08:30:24 INFO - TinderboxPrint: Swap in / out
252,497,920 / 0 08:30:24 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 252660224; Write bytes: 255731712; Read time: 18306; Write time: 548 08:30:24 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 890368; Write bytes: 300443648; Read time: 5; Write time: 1159 08:30:24 INFO - Running post-run listener: _upload_blobber_files 08:30:24 INFO - Blob upload gear active. 08:30:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:30:24 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 08:30:24 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:30:24 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:30:25 INFO - (blobuploader) - INFO - Open directory for files ... 08:30:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:30:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:30:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:26 INFO - (blobuploader) - INFO - Done attempting. 08:30:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:30:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:30:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:27 INFO - (blobuploader) - INFO - Done attempting. 08:30:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:30:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:30:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:28 INFO - (blobuploader) - INFO - Done attempting. 08:30:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:30:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:30:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:30 INFO - (blobuploader) - INFO - Done attempting. 08:30:30 INFO - (blobuploader) - INFO - Iteration through files over. 08:30:30 INFO - Return code: 0 08:30:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:30:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:30:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/977dc0e3224b8cecbc29f8ed96bb8fa2454a418f819a30f2b551774ac13e372f4a5210a0ffb1242f67ad22e2715cb53c4469790a60cf38e6a519509488bf08f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3e2b252d949551ac99a9ac81009e168a4dd5699b041188bf1b9c9465ee1a896cd25048a30b5f08d8660e09aed4844332b892d6e62621754c336087451b0820f9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fde1a60271d4189b38ecc69dbb294da57d18bd38ffd342fb3d06c45b0b055ed94a54563461a02f7a8af858af41ecc6b555897f4bdfe616f19ab0981118d8b073", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/aed2c93e0cd870295935c75803c3c07d781e0f955042076ed728bc7ed8e431e70b9846428cf9f81bcb8b708015533f95c718b8407ebec68fcfd90b969600816e"} 08:30:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:30:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:30:30 INFO - Contents: 08:30:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/977dc0e3224b8cecbc29f8ed96bb8fa2454a418f819a30f2b551774ac13e372f4a5210a0ffb1242f67ad22e2715cb53c4469790a60cf38e6a519509488bf08f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3e2b252d949551ac99a9ac81009e168a4dd5699b041188bf1b9c9465ee1a896cd25048a30b5f08d8660e09aed4844332b892d6e62621754c336087451b0820f9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fde1a60271d4189b38ecc69dbb294da57d18bd38ffd342fb3d06c45b0b055ed94a54563461a02f7a8af858af41ecc6b555897f4bdfe616f19ab0981118d8b073", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/aed2c93e0cd870295935c75803c3c07d781e0f955042076ed728bc7ed8e431e70b9846428cf9f81bcb8b708015533f95c718b8407ebec68fcfd90b969600816e"} 08:30:30 INFO - Running post-run listener: copy_logs_to_upload_dir 08:30:30 INFO - Copying logs to upload dir... 08:30:30 INFO - mkdir: /builds/slave/test/build/upload/logs 08:30:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.000659 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-16 08:30:30.360720) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-16 08:30:30.364270) ========= 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.bNGqItR6Vh/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.EPmSZ45z11/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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/Graphics/sha512/977dc0e3224b8cecbc29f8ed96bb8fa2454a418f819a30f2b551774ac13e372f4a5210a0ffb1242f67ad22e2715cb53c4469790a60cf38e6a519509488bf08f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3e2b252d949551ac99a9ac81009e168a4dd5699b041188bf1b9c9465ee1a896cd25048a30b5f08d8660e09aed4844332b892d6e62621754c336087451b0820f9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fde1a60271d4189b38ecc69dbb294da57d18bd38ffd342fb3d06c45b0b055ed94a54563461a02f7a8af858af41ecc6b555897f4bdfe616f19ab0981118d8b073", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/aed2c93e0cd870295935c75803c3c07d781e0f955042076ed728bc7ed8e431e70b9846428cf9f81bcb8b708015533f95c718b8407ebec68fcfd90b969600816e"} build_url:https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011584 build_url: 'https://queue.taskcluster.net/v1/task/a85NKMESQ5yBCj1bsZSaCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/977dc0e3224b8cecbc29f8ed96bb8fa2454a418f819a30f2b551774ac13e372f4a5210a0ffb1242f67ad22e2715cb53c4469790a60cf38e6a519509488bf08f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3e2b252d949551ac99a9ac81009e168a4dd5699b041188bf1b9c9465ee1a896cd25048a30b5f08d8660e09aed4844332b892d6e62621754c336087451b0820f9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fde1a60271d4189b38ecc69dbb294da57d18bd38ffd342fb3d06c45b0b055ed94a54563461a02f7a8af858af41ecc6b555897f4bdfe616f19ab0981118d8b073", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/aed2c93e0cd870295935c75803c3c07d781e0f955042076ed728bc7ed8e431e70b9846428cf9f81bcb8b708015533f95c718b8407ebec68fcfd90b969600816e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-16 08:30:30.403643) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 08:30:30.404366) ========= 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.bNGqItR6Vh/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.EPmSZ45z11/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.005662 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 08:30:30.457233) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-16 08:30:30.457926) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-16 08:30:30.458554) ========= ========= Total master_lag: 1.16 =========