builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0004 starttime: 1482439276.13 results: success (0) buildid: 20161222080253 builduid: ddab06d9e6ed460d9adee53458152dd7 revision: 8ba37e59d4ca91bfb2509d7f36284171a64e08b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:16.125932) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:16.126361) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:16.126707) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:16.154554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:16.154968) ========= 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.x84z1M5KCf/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.9RaP8opjeS/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-22 12:41:16-- 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% 159M=0s 2016-12-22 12:41:16 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104098 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:16.283238) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:16.283700) ========= 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.x84z1M5KCf/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.9RaP8opjeS/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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.025847 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:16.332356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 12:41:16.332729) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 8ba37e59d4ca91bfb2509d7f36284171a64e08b4 --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 8ba37e59d4ca91bfb2509d7f36284171a64e08b4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x84z1M5KCf/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.9RaP8opjeS/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-22 12:41:16,433 truncating revision to first 12 chars 2016-12-22 12:41:16,433 Setting DEBUG logging. 2016-12-22 12:41:16,433 attempt 1/10 2016-12-22 12:41:16,434 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/8ba37e59d4ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 12:41:17,643 unpacking tar archive at: graphics-8ba37e59d4ca/testing/mozharness/ program finished with exit code 0 elapsedTime=1.505171 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 12:41:17.861329) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:17.861656) ========= script_repo_revision: 8ba37e59d4ca91bfb2509d7f36284171a64e08b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:17.862040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:17.862306) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 12:41:17.882094) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-22 12:41:17.882470) ========= /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.x84z1M5KCf/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.9RaP8opjeS/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:41:18 INFO - MultiFileLogger online at 20161222 12:41:18 in /builds/slave/test 12:41:18 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 12:41:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:41:18 INFO - {'append_to_log': False, 12:41:18 INFO - 'base_work_dir': '/builds/slave/test', 12:41:18 INFO - 'blob_upload_branch': 'Graphics', 12:41:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:41:18 INFO - 'branch': 'Graphics', 12:41:18 INFO - 'buildbot_json_path': 'buildprops.json', 12:41:18 INFO - 'config_files': ('talos/mac_config.py',), 12:41:18 INFO - 'default_actions': ('clobber', 12:41:18 INFO - 'read-buildbot-config', 12:41:18 INFO - 'download-and-extract', 12:41:18 INFO - 'populate-webroot', 12:41:18 INFO - 'create-virtualenv', 12:41:18 INFO - 'install', 12:41:18 INFO - 'run-tests'), 12:41:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:41:18 INFO - 'download_minidump_stackwalk': True, 12:41:18 INFO - 'download_symbols': 'ondemand', 12:41:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:41:18 INFO - 'tooltool.py': '/tools/tooltool.py', 12:41:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:41:18 INFO - '/tools/misc-python/virtualenv.py')}, 12:41:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:41:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:41:18 INFO - 'installer_path': 'installer.exe', 12:41:18 INFO - 'log_level': 'info', 12:41:18 INFO - 'log_name': 'talos', 12:41:18 INFO - 'log_to_console': True, 12:41:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:41:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:41:18 INFO - 'opt_config_files': (), 12:41:18 INFO - 'pip_index': False, 12:41:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:41:18 INFO - 'cmd': ('bash', 12:41:18 INFO - '-c', 12:41:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:41:18 INFO - 'enabled': True, 12:41:18 INFO - 'halt_on_failure': False, 12:41:18 INFO - 'name': 'check_screen_resolution'},), 12:41:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:41:18 INFO - 'cmd': ('bash', 12:41:18 INFO - '-c', 12:41:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:41:18 INFO - 'enabled': True, 12:41:18 INFO - 'halt_on_failure': False, 12:41:18 INFO - 'name': 'check_screen_resolution'},), 12:41:18 INFO - 'run_cmd_checks_enabled': True, 12:41:18 INFO - 'sps_profile': False, 12:41:18 INFO - 'sps_profile_interval': 0, 12:41:18 INFO - 'suite': 'dromaeojs-e10s', 12:41:18 INFO - 'system_bits': '32', 12:41:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:41:18 INFO - 'title': 't-yosemite-r7-0004', 12:41:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:41:18 INFO - 'use_talos_json': True, 12:41:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:41:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:41:18 INFO - 'work_dir': 'build'} 12:41:18 INFO - [mozharness: 2016-12-22 20:41:18.089972Z] Running clobber step. 12:41:18 INFO - Running pre-action listener: _resource_record_pre_action 12:41:18 INFO - Running main action method: clobber 12:41:18 INFO - rmtree: /builds/slave/test/build 12:41:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:41:20 INFO - Running post-action listener: _resource_record_post_action 12:41:20 INFO - [mozharness: 2016-12-22 20:41:20.461729Z] Finished clobber step (success) 12:41:20 INFO - [mozharness: 2016-12-22 20:41:20.461858Z] Running read-buildbot-config step. 12:41:20 INFO - Running pre-action listener: _resource_record_pre_action 12:41:20 INFO - Running main action method: read_buildbot_config 12:41:20 INFO - Using buildbot properties: 12:41:20 INFO - { 12:41:20 INFO - "project": "", 12:41:20 INFO - "product": "firefox", 12:41:20 INFO - "script_repo_revision": "default", 12:41:20 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 12:41:20 INFO - "repository": "", 12:41:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 12:41:20 INFO - "buildid": "20161222080253", 12:41:20 INFO - "pgo_build": "False", 12:41:20 INFO - "basedir": "/builds/slave/test", 12:41:20 INFO - "buildnumber": 1, 12:41:20 INFO - "slavename": "t-yosemite-r7-0004", 12:41:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:41:20 INFO - "platform": "yosemite_r7", 12:41:20 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 12:41:20 INFO - "branch": "graphics", 12:41:20 INFO - "repo_path": "projects/graphics", 12:41:20 INFO - "revision": "8ba37e59d4ca91bfb2509d7f36284171a64e08b4", 12:41:20 INFO - "stage_platform": "macosx64", 12:41:20 INFO - "builduid": "ddab06d9e6ed460d9adee53458152dd7", 12:41:20 INFO - "slavebuilddir": "test" 12:41:20 INFO - } 12:41:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:41:20 INFO - Running post-action listener: _resource_record_post_action 12:41:20 INFO - [mozharness: 2016-12-22 20:41:20.465245Z] Finished read-buildbot-config step (success) 12:41:20 INFO - [mozharness: 2016-12-22 20:41:20.465392Z] Running download-and-extract step. 12:41:20 INFO - Running pre-action listener: _resource_record_pre_action 12:41:20 INFO - Running main action method: download_and_extract 12:41:20 INFO - mkdir: /builds/slave/test/build/tests 12:41:20 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')]} 12:41:20 INFO - trying https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:41:20 INFO - Downloading https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/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 12:41:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/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 12:41:21 INFO - Downloaded 1199 bytes. 12:41:21 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:41:21 INFO - Using the following test package requirements: 12:41:21 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:41:21 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:41:21 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:41:21 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:41:21 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:41:21 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:41:21 INFO - u'jsshell-mac.zip'], 12:41:21 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:41:21 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:41:21 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:41:21 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:41:21 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:41:21 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:41:21 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:41:21 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:41:21 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:41:21 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:41:21 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:41:21 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:41:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:41:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:41:21 INFO - Fetch https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:41:22 INFO - Expected file size: 16277754 12:41:22 INFO - Obtained file size: 16277754 12:41:29 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 12:41:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:41:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:41:29 INFO - Fetch https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:41:30 INFO - Expected file size: 16277754 12:41:30 INFO - Obtained file size: 16277754 12:41:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:41:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:41:36 INFO - Fetch https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:41:37 INFO - Expected file size: 14409734 12:41:37 INFO - Obtained file size: 14409734 12:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:38 INFO - trying https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:41:38 INFO - Downloading https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:41:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/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 12:41:40 INFO - Downloaded 69588655 bytes. 12:41:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:41:40 INFO - mkdir: /builds/slave/test/properties 12:41:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:41:40 INFO - Writing to file /builds/slave/test/properties/build_url 12:41:40 INFO - Contents: 12:41:40 INFO - build_url:https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:41:40 INFO - Running post-action listener: _resource_record_post_action 12:41:40 INFO - Running post-action listener: set_extra_try_arguments 12:41:40 INFO - [mozharness: 2016-12-22 20:41:40.824154Z] Finished download-and-extract step (success) 12:41:40 INFO - [mozharness: 2016-12-22 20:41:40.824308Z] Running populate-webroot step. 12:41:40 INFO - Running pre-action listener: _resource_record_pre_action 12:41:40 INFO - Running main action method: populate_webroot 12:41:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:41:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:41:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:41:40 INFO - '--tppagecycles', 12:41:40 INFO - '7'], 12:41:40 INFO - 'tests': ['tsvgm']}}, 12:41:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:41:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:41:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:41:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:41:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:41:40 INFO - 'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:41:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:41:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:41:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:41:40 INFO - 'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['damp', 'tps']}, 12:41:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:41:40 INFO - 'tests': ['damp', 'tps']}, 12:41:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['dromaeo_dom']}, 12:41:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:41:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['basic_compositor_video']}, 12:41:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:41:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['a11yr', 12:41:40 INFO - 'ts_paint', 12:41:40 INFO - 'tpaint', 12:41:40 INFO - 'sessionrestore', 12:41:40 INFO - 'sessionrestore_no_auto_restore', 12:41:40 INFO - 'tabpaint']}, 12:41:40 INFO - 'other-e10s': {'tests': ['a11yr', 12:41:40 INFO - 'ts_paint', 12:41:40 INFO - 'tpaint', 12:41:40 INFO - 'sessionrestore', 12:41:40 INFO - 'sessionrestore_no_auto_restore', 12:41:40 INFO - 'tabpaint']}, 12:41:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:41:40 INFO - 'ts_paint', 12:41:40 INFO - 'tpaint', 12:41:40 INFO - 'sessionrestore', 12:41:40 INFO - 'sessionrestore_no_auto_restore', 12:41:40 INFO - 'tabpaint']}, 12:41:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:41:40 INFO - 'ts_paint', 12:41:40 INFO - 'tpaint', 12:41:40 INFO - 'sessionrestore', 12:41:40 INFO - 'sessionrestore_no_auto_restore', 12:41:40 INFO - 'tabpaint']}, 12:41:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['a11yr', 12:41:40 INFO - 'ts_paint', 12:41:40 INFO - 'tpaint', 12:41:40 INFO - 'sessionrestore', 12:41:40 INFO - 'sessionrestore_no_auto_restore', 12:41:40 INFO - 'tabpaint']}, 12:41:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['a11yr', 12:41:40 INFO - 'ts_paint', 12:41:40 INFO - 'tpaint', 12:41:40 INFO - 'sessionrestore', 12:41:40 INFO - 'sessionrestore_no_auto_restore', 12:41:40 INFO - 'tabpaint']}, 12:41:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['tsvgx', 12:41:40 INFO - 'tsvgr_opacity', 12:41:40 INFO - 'tart', 12:41:40 INFO - 'tscrollx', 12:41:40 INFO - 'cart', 12:41:40 INFO - 'tsvg_static']}, 12:41:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:41:40 INFO - 'tsvgr_opacity', 12:41:40 INFO - 'tart', 12:41:40 INFO - 'tscrollx', 12:41:40 INFO - 'cart', 12:41:40 INFO - 'tsvg_static']}, 12:41:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:41:40 INFO - 'talos_options': ['--disable-e10s'], 12:41:40 INFO - 'tests': ['tp5o']}, 12:41:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:41:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:41:40 INFO - 'talos_options': ['--disable-e10s', 12:41:40 INFO - '--xperf_path', 12:41:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:41:40 INFO - 'tests': ['tp5n']}, 12:41:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:41:40 INFO - 'talos_options': ['--xperf_path', 12:41:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:41:40 INFO - 'tests': ['tp5n']}}, 12:41:40 INFO - 'talos.zip': {'path': '', 12:41:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:41:40 INFO - Running post-action listener: _resource_record_post_action 12:41:40 INFO - [mozharness: 2016-12-22 20:41:40.842555Z] Finished populate-webroot step (success) 12:41:40 INFO - [mozharness: 2016-12-22 20:41:40.842688Z] Running create-virtualenv step. 12:41:40 INFO - Running pre-action listener: _resource_record_pre_action 12:41:40 INFO - Running main action method: create_virtualenv 12:41:40 INFO - Creating virtualenv /builds/slave/test/build/venv 12:41:40 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 12:41:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:41:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:41:40 INFO - Using real prefix '/tools/python27' 12:41:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:41:41 INFO - Installing distribute.............................................................................................................................................................................................done. 12:41:44 INFO - Installing pip.................done. 12:41:44 INFO - Return code: 0 12:41:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:41:44 INFO - Reading from file tmpfile_stdout 12:41:44 INFO - Output received: 12:41:44 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) 12:41:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:41:44 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')]} 12:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:44 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')]} 12:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:44 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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:41:44 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 12:41:44 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 12:41:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:41:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:44 INFO - 'HOME': '/Users/cltbld', 12:41:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:44 INFO - 'LOGNAME': 'cltbld', 12:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:44 INFO - 'MOZ_NO_REMOTE': '1', 12:41:44 INFO - 'NO_EM_RESTART': '1', 12:41:44 INFO - 'PAGER': '/bin/cat', 12:41:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:44 INFO - 'PWD': '/builds/slave/test', 12:41:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:44 INFO - 'SHELL': '/bin/bash', 12:41:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:41:44 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:41:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:44 INFO - 'USER': 'cltbld', 12:41:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:44 INFO - 'XPC_FLAGS': '0x0', 12:41:44 INFO - 'XPC_SERVICE_NAME': '0', 12:41:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:45 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 12:41:45 INFO - Cleaning up... 12:41:45 INFO - Return code: 0 12:41:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:41: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')]} 12:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41: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')]} 12:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:41:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:41:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:41:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:41:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:45 INFO - 'HOME': '/Users/cltbld', 12:41:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:45 INFO - 'LOGNAME': 'cltbld', 12:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:45 INFO - 'MOZ_NO_REMOTE': '1', 12:41:45 INFO - 'NO_EM_RESTART': '1', 12:41:45 INFO - 'PAGER': '/bin/cat', 12:41:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:45 INFO - 'PWD': '/builds/slave/test', 12:41:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:45 INFO - 'SHELL': '/bin/bash', 12:41:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:41:45 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:41:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:45 INFO - 'USER': 'cltbld', 12:41:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:45 INFO - 'XPC_FLAGS': '0x0', 12:41:45 INFO - 'XPC_SERVICE_NAME': '0', 12:41:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:45 INFO - Downloading/unpacking psutil>=3.1.1 12:41:45 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 12:41:45 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 12:41:45 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 12:41:45 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 12:41:45 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 12:41:45 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 12:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:41:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:41:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:41:48 INFO - Installing collected packages: psutil 12:41:48 INFO - Running setup.py install for psutil 12:41:48 INFO - building 'psutil._psutil_osx' extension 12:41:48 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 12:41:49 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 12:41:49 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 12:41:49 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 12:41:49 INFO - building 'psutil._psutil_posix' extension 12:41:49 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 12:41:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:41:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:41:49 INFO - ^ 12:41:49 INFO - 1 warning generated. 12:41:49 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 12:41:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:41:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:41:50 INFO - Successfully installed psutil 12:41:50 INFO - Cleaning up... 12:41:50 INFO - Return code: 0 12:41:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:50 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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:41:50 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 12:41:50 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 12:41:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:41:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:50 INFO - 'HOME': '/Users/cltbld', 12:41:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:50 INFO - 'LOGNAME': 'cltbld', 12:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:50 INFO - 'MOZ_NO_REMOTE': '1', 12:41:50 INFO - 'NO_EM_RESTART': '1', 12:41:50 INFO - 'PAGER': '/bin/cat', 12:41:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:50 INFO - 'PWD': '/builds/slave/test', 12:41:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:50 INFO - 'SHELL': '/bin/bash', 12:41:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:41:50 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:41:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:50 INFO - 'USER': 'cltbld', 12:41:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:50 INFO - 'XPC_FLAGS': '0x0', 12:41:50 INFO - 'XPC_SERVICE_NAME': '0', 12:41:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:41:50 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 12:41:50 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 12:41:50 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 12:41:50 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 12:41:50 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 12:41:50 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 12:41:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:41:52 INFO - Installing collected packages: mozsystemmonitor 12:41:52 INFO - Running setup.py install for mozsystemmonitor 12:41:53 INFO - Successfully installed mozsystemmonitor 12:41:53 INFO - Cleaning up... 12:41:53 INFO - Return code: 0 12:41:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:41:53 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')]} 12:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:53 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')]} 12:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:53 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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:41:53 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 12:41:53 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 12:41:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:41:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:53 INFO - 'HOME': '/Users/cltbld', 12:41:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:53 INFO - 'LOGNAME': 'cltbld', 12:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:53 INFO - 'MOZ_NO_REMOTE': '1', 12:41:53 INFO - 'NO_EM_RESTART': '1', 12:41:53 INFO - 'PAGER': '/bin/cat', 12:41:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:53 INFO - 'PWD': '/builds/slave/test', 12:41:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:53 INFO - 'SHELL': '/bin/bash', 12:41:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:41:53 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:41:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:53 INFO - 'USER': 'cltbld', 12:41:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:53 INFO - 'XPC_FLAGS': '0x0', 12:41:53 INFO - 'XPC_SERVICE_NAME': '0', 12:41:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:53 INFO - Downloading/unpacking jsonschema==2.5.1 12:41:53 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 12:41:53 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 12:41:53 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 12:41:53 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 12:41:53 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 12:41:53 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 12:41:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:41:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:41: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 12:41: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 12:41: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 12:41: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 12:41: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 12:41: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 12:41:56 INFO - Downloading functools32-3.2.3-2.tar.gz 12:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:41:56 INFO - warning: no files found matching '*.txt' 12:41:56 INFO - no previously-included directories found matching 'build' 12:41:56 INFO - no previously-included directories found matching 'dist' 12:41:56 INFO - no previously-included directories found matching '.git*' 12:41:56 INFO - Installing collected packages: jsonschema, functools32 12:41:56 INFO - Running setup.py install for functools32 12:41:56 INFO - warning: no files found matching '*.txt' 12:41:56 INFO - no previously-included directories found matching 'build' 12:41:56 INFO - no previously-included directories found matching 'dist' 12:41:56 INFO - no previously-included directories found matching '.git*' 12:41:56 INFO - Successfully installed jsonschema functools32 12:41:56 INFO - Cleaning up... 12:41:56 INFO - Return code: 0 12:41:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:56 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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:41:56 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 12:41:56 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 12:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:56 INFO - 'HOME': '/Users/cltbld', 12:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:56 INFO - 'LOGNAME': 'cltbld', 12:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:56 INFO - 'MOZ_NO_REMOTE': '1', 12:41:56 INFO - 'NO_EM_RESTART': '1', 12:41:56 INFO - 'PAGER': '/bin/cat', 12:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:56 INFO - 'PWD': '/builds/slave/test', 12:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:56 INFO - 'SHELL': '/bin/bash', 12:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:41:56 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:56 INFO - 'USER': 'cltbld', 12:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:56 INFO - 'XPC_FLAGS': '0x0', 12:41:56 INFO - 'XPC_SERVICE_NAME': '0', 12:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:41:57 INFO - Cleaning up... 12:41:57 INFO - Return code: 0 12:41:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:41:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:57 INFO - 'HOME': '/Users/cltbld', 12:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:57 INFO - 'LOGNAME': 'cltbld', 12:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:57 INFO - 'MOZ_NO_REMOTE': '1', 12:41:57 INFO - 'NO_EM_RESTART': '1', 12:41:57 INFO - 'PAGER': '/bin/cat', 12:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:57 INFO - 'PWD': '/builds/slave/test', 12:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:57 INFO - 'SHELL': '/bin/bash', 12:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:41:57 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:57 INFO - 'USER': 'cltbld', 12:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:57 INFO - 'XPC_FLAGS': '0x0', 12:41:57 INFO - 'XPC_SERVICE_NAME': '0', 12:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:57 INFO - Downloading/unpacking blobuploader==1.2.4 12:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:41:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:41:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:41:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:41:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:41:59 INFO - Downloading blobuploader-1.2.4.tar.gz 12:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:42:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:42:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42:01 INFO - Downloading docopt-0.6.1.tar.gz 12:42:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:42:01 INFO - Installing collected packages: blobuploader, requests, docopt 12:42:01 INFO - Running setup.py install for blobuploader 12:42:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:42:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:42:01 INFO - Running setup.py install for requests 12:42:02 INFO - Running setup.py install for docopt 12:42:02 INFO - Successfully installed blobuploader requests docopt 12:42:02 INFO - Cleaning up... 12:42:02 INFO - Return code: 0 12:42:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:02 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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:42:02 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 12:42:02 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 12:42:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:42:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:02 INFO - 'HOME': '/Users/cltbld', 12:42:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:02 INFO - 'LOGNAME': 'cltbld', 12:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:02 INFO - 'MOZ_NO_REMOTE': '1', 12:42:02 INFO - 'NO_EM_RESTART': '1', 12:42:02 INFO - 'PAGER': '/bin/cat', 12:42:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:02 INFO - 'PWD': '/builds/slave/test', 12:42:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:02 INFO - 'SHELL': '/bin/bash', 12:42:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:42:02 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:42:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:02 INFO - 'USER': 'cltbld', 12:42:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:02 INFO - 'XPC_FLAGS': '0x0', 12:42:02 INFO - 'XPC_SERVICE_NAME': '0', 12:42:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:02 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-LQJaGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:02 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-iCwYuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:02 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-IHFkyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:02 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-eXiCDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:02 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-kKlkaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-8jSERx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-pO2OnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-JM08Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-NlbKLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-uEHRVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-Iss7CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-QNCgs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-N5Ciky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-ewWoGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:03 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-K3BQEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:04 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-Qx284V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:04 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-V9kzOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:42:04 INFO - Running setup.py install for manifestparser 12:42:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:42:04 INFO - Running setup.py install for mozcrash 12:42:04 INFO - Running setup.py install for mozdebug 12:42:04 INFO - Running setup.py install for mozdevice 12:42:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:42:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:42:04 INFO - Running setup.py install for mozfile 12:42:04 INFO - Running setup.py install for mozhttpd 12:42:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for mozinfo 12:42:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for mozInstall 12:42:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for mozleak 12:42:05 INFO - Running setup.py install for mozlog 12:42:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for moznetwork 12:42:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for mozprocess 12:42:06 INFO - Running setup.py install for mozprofile 12:42:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Running setup.py install for mozrunner 12:42:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Running setup.py install for mozscreenshot 12:42:06 INFO - Running setup.py install for moztest 12:42:06 INFO - Running setup.py install for mozversion 12:42:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:42:06 INFO - Cleaning up... 12:42:06 INFO - Return code: 0 12:42:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:06 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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:42:06 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 12:42:06 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 12:42:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:42:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:06 INFO - 'HOME': '/Users/cltbld', 12:42:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:06 INFO - 'LOGNAME': 'cltbld', 12:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:06 INFO - 'MOZ_NO_REMOTE': '1', 12:42:06 INFO - 'NO_EM_RESTART': '1', 12:42:06 INFO - 'PAGER': '/bin/cat', 12:42:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:06 INFO - 'PWD': '/builds/slave/test', 12:42:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:06 INFO - 'SHELL': '/bin/bash', 12:42:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:42:06 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:42:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:06 INFO - 'USER': 'cltbld', 12:42:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:06 INFO - 'XPC_FLAGS': '0x0', 12:42:06 INFO - 'XPC_SERVICE_NAME': '0', 12:42:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-65rGdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-oF4rEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-B0vgCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-SntlT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-KKP6Az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-qdnoYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-vKYbgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-FxNhoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:07 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-7McLC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:08 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-Wqa5ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:08 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-7a6Zy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:08 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-rn5sF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:08 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-SRVzGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:08 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-vDNbqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:08 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-dqid0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:08 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-wxNkSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:08 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-44kGPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:11 INFO - Downloading blessings-1.6.tar.gz 12:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:42:11 INFO - Installing collected packages: blessings 12:42:11 INFO - Running setup.py install for blessings 12:42:11 INFO - Successfully installed blessings 12:42:11 INFO - Cleaning up... 12:42:11 INFO - Return code: 0 12:42:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:42:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:12 INFO - Reading from file tmpfile_stdout 12:42:12 INFO - Current package versions: 12:42:12 INFO - blessings == 1.6 12:42:12 INFO - blobuploader == 1.2.4 12:42:12 INFO - docopt == 0.6.1 12:42:12 INFO - functools32 == 3.2.3-2 12:42:12 INFO - jsonschema == 2.5.1 12:42:12 INFO - manifestparser == 1.1 12:42:12 INFO - mozInstall == 1.12 12:42:12 INFO - mozcrash == 1.0 12:42:12 INFO - mozdebug == 0.1 12:42:12 INFO - mozdevice == 0.49 12:42:12 INFO - mozfile == 1.2 12:42:12 INFO - mozhttpd == 0.7 12:42:12 INFO - mozinfo == 0.9 12:42:12 INFO - mozleak == 0.1 12:42:12 INFO - mozlog == 3.4 12:42:12 INFO - moznetwork == 0.27 12:42:12 INFO - mozprocess == 0.23 12:42:12 INFO - mozprofile == 0.28 12:42:12 INFO - mozrunner == 6.13 12:42:12 INFO - mozscreenshot == 0.1 12:42:12 INFO - mozsystemmonitor == 0.3 12:42:12 INFO - moztest == 0.7 12:42:12 INFO - mozversion == 1.4 12:42:12 INFO - psutil == 3.1.1 12:42:12 INFO - requests == 1.2.3 12:42:12 INFO - wsgiref == 0.1.2 12:42:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42: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')]} 12:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42: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')]} 12:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:13 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 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:42:13 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 12:42:13 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 12:42:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:42:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:13 INFO - 'HOME': '/Users/cltbld', 12:42:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:13 INFO - 'LOGNAME': 'cltbld', 12:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:13 INFO - 'MOZ_NO_REMOTE': '1', 12:42:13 INFO - 'NO_EM_RESTART': '1', 12:42:13 INFO - 'PAGER': '/bin/cat', 12:42:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:13 INFO - 'PWD': '/builds/slave/test', 12:42:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:13 INFO - 'SHELL': '/bin/bash', 12:42:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:42:13 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:42:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:13 INFO - 'USER': 'cltbld', 12:42:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:13 INFO - 'XPC_FLAGS': '0x0', 12:42:13 INFO - 'XPC_SERVICE_NAME': '0', 12:42:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 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)) 12:42:13 INFO - Cleaning up... 12:42:13 INFO - Return code: 0 12:42:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc99a574f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, '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.x84z1M5KCf/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.9RaP8opjeS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 12:42:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:42:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:42:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:13 INFO - 'HOME': '/Users/cltbld', 12:42:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:13 INFO - 'LOGNAME': 'cltbld', 12:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:13 INFO - 'MOZ_NO_REMOTE': '1', 12:42:13 INFO - 'NO_EM_RESTART': '1', 12:42:13 INFO - 'PAGER': '/bin/cat', 12:42:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:13 INFO - 'PWD': '/builds/slave/test', 12:42:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:13 INFO - 'SHELL': '/bin/bash', 12:42:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:42:13 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:42:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:13 INFO - 'USER': 'cltbld', 12:42:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:13 INFO - 'XPC_FLAGS': '0x0', 12:42:13 INFO - 'XPC_SERVICE_NAME': '0', 12:42:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:42:14 INFO - Cleaning up... 12:42:14 INFO - Return code: 0 12:42:14 INFO - Running post-action listener: _resource_record_post_action 12:42:14 INFO - Running post-action listener: _start_resource_monitoring 12:42:14 INFO - Starting resource monitoring. 12:42:14 INFO - [mozharness: 2016-12-22 20:42:14.246956Z] Finished create-virtualenv step (success) 12:42:14 INFO - [mozharness: 2016-12-22 20:42:14.247915Z] Running install step. 12:42:14 INFO - Running pre-action listener: _resource_record_pre_action 12:42:14 INFO - Running main action method: install 12:42:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:14 INFO - Reading from file tmpfile_stdout 12:42:14 INFO - Detecting whether we're running mozinstall >=1.0... 12:42:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:42:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:42:14 INFO - Reading from file tmpfile_stdout 12:42:14 INFO - Output received: 12:42:14 INFO - Usage: mozinstall [options] installer 12:42:14 INFO - Options: 12:42:14 INFO - -h, --help show this help message and exit 12:42:14 INFO - -d DEST, --destination=DEST 12:42:14 INFO - Directory to install application into. [default: 12:42:14 INFO - "/builds/slave/test"] 12:42:14 INFO - --app=APP Application being installed. [default: firefox] 12:42:14 INFO - mkdir: /builds/slave/test/build/application 12:42:14 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'] 12:42:14 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 12:42:36 INFO - Reading from file tmpfile_stdout 12:42:36 INFO - Output received: 12:42:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:42:36 INFO - Running post-action listener: _resource_record_post_action 12:42:36 INFO - [mozharness: 2016-12-22 20:42:36.419576Z] Finished install step (success) 12:42:36 INFO - [mozharness: 2016-12-22 20:42:36.419757Z] Running run-tests step. 12:42:36 INFO - Running pre-action listener: _resource_record_pre_action 12:42:36 INFO - Running main action method: run_tests 12:42:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:42:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:42:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:42:36 INFO - 2016-12-22 12:42:36.434 screenresolution[1624:7358] starting screenresolution argv=screenresolution get 12:42:36 INFO - 2016-12-22 12:42:36.437 screenresolution[1624:7358] Display 0: 1600x1200x32@60 12:42:36 INFO - 2016-12-22 12:42:36.442 screenresolution[1625:7360] starting screenresolution argv=screenresolution list 12:42:36 INFO - Available Modes on Display 0 12:42:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:42:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:42:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:42:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:42:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:42:36 INFO - Intel Iris: 12:42:36 INFO - Chipset Model: Intel Iris 12:42:36 INFO - Type: GPU 12:42:36 INFO - Bus: Built-In 12:42:36 INFO - VRAM (Dynamic, Max): 1536 MB 12:42:36 INFO - Vendor: Intel (0x8086) 12:42:36 INFO - Device ID: 0x0a2e 12:42:36 INFO - Revision ID: 0x0009 12:42:36 INFO - Displays: 12:42:36 INFO - Display: 12:42:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:42:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:42:36 INFO - Display Serial Number: 1600x1200 60 12:42:36 INFO - Main Display: Yes 12:42:36 INFO - Mirror: Off 12:42:36 INFO - Online: Yes 12:42:36 INFO - Rotation: Supported 12:42:36 INFO - Return code: 0 12:42:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:42:36 INFO - Python 2.7.3 12:42:36 INFO - Return code: 0 12:42:36 INFO - grabbing minidump binary from tooltool 12:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:36 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 0x10b79dd30>, '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 0x10b6c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b792be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:42:36 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 12:42:36 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 12:42:36 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 12:42:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:42:36 INFO - Return code: 0 12:42:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:42:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:42:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:42:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:42:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:42:36 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/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--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 12:42:36 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/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0004 --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 12:42:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x84z1M5KCf/Render', 12:42:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:36 INFO - 'HOME': '/Users/cltbld', 12:42:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:36 INFO - 'LOGNAME': 'cltbld', 12:42:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:42:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:36 INFO - 'MOZ_NO_REMOTE': '1', 12:42:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:42:36 INFO - 'NO_EM_RESTART': '1', 12:42:36 INFO - 'PAGER': '/bin/cat', 12:42:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:36 INFO - 'PWD': '/builds/slave/test', 12:42:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:42:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:36 INFO - 'SHELL': '/bin/bash', 12:42:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9RaP8opjeS/Listeners', 12:42:36 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 12:42:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:36 INFO - 'USER': 'cltbld', 12:42:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:36 INFO - 'XPC_FLAGS': '0x0', 12:42:36 INFO - 'XPC_SERVICE_NAME': '0', 12:42:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:36 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/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--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 12:42:37 INFO - mozversion application_buildid: 20161222080253 12:42:37 INFO - mozversion application_changeset: 8ba37e59d4ca91bfb2509d7f36284171a64e08b4 12:42:37 INFO - mozversion application_display_name: Nightly 12:42:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:42:37 INFO - mozversion application_name: Firefox 12:42:37 INFO - mozversion application_remotingname: firefox 12:42:37 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:42:37 INFO - mozversion application_vendor: Mozilla 12:42:37 INFO - mozversion application_version: 53.0a1 12:42:37 INFO - mozversion platform_buildid: 20161222080253 12:42:37 INFO - mozversion platform_changeset: 8ba37e59d4ca91bfb2509d7f36284171a64e08b4 12:42:37 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:42:37 INFO - mozversion platform_version: 53.0a1 12:42:37 INFO - using testdate: 1482439357 12:42:37 INFO - actual date: 1482439357 12:42:37 INFO - starting webserver on 'localhost:49247' 12:42:37 INFO - SUITE-START | Running 2 tests 12:42:37 INFO - TEST-START | dromaeo_css 12:42:37 INFO - operating with platform_type : mac_ 12:42:37 INFO - Initialising browser for dromaeo_css test... 12:42:37 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmphgXzIN/profile http://localhost:49247/getInfo.html) 12:42:39 INFO - PROCESS | 1635 | 2016-12-22 12:42:39.974 plugin-container[1637:7527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 12:42:39 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:42:39 INFO - PROCESS | 1635 | 2016-12-22 12:42:39.977 plugin-container[1637:7527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1b, name = 'com.apple.CFPasteboardClient' 12:42:39 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:42:39 INFO - PROCESS | 1635 | 2016-12-22 12:42:39.977 plugin-container[1637:7527] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:42:40 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 12:42:40 INFO - PROCESS | 1635 | colorDepth:24 12:42:40 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 12:42:40 INFO - PROCESS | 1635 | __metrics 12:42:40 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 12:42:40 INFO - TEST-INFO | 1635: exit 0 12:42:40 INFO - Browser initialized. 12:42:40 INFO - Running cycle 1/1 for dromaeo_css test... 12:42:40 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmphgXzIN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:42:43 INFO - PROCESS | 1638 | 2016-12-22 12:42:43.150 plugin-container[1639:7646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 12:42:43 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:42:43 INFO - PROCESS | 1638 | 2016-12-22 12:42:43.150 plugin-container[1639:7646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 12:42:43 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:42:43 INFO - PROCESS | 1638 | 2016-12-22 12:42:43.150 plugin-container[1639:7646] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:42:52 INFO - PROCESS | 1638 | RSS: Main: 234721280 12:42:52 INFO - PROCESS | 1638 | 12:43:42 INFO - PROCESS | 1638 | 1482439422417 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:45:55 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 12:45:57 INFO - PROCESS | 1638 | RSS: Main: 254578688 12:45:57 INFO - PROCESS | 1638 | 12:48:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 12:49:00 INFO - PROCESS | 1638 | RSS: Main: 266657792 12:49:00 INFO - PROCESS | 1638 | 12:52:02 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 12:52:03 INFO - PROCESS | 1638 | RSS: Main: 270864384 12:52:03 INFO - PROCESS | 1638 | 12:55:05 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 12:55:05 INFO - PROCESS | 1638 | RSS: Main: 270413824 12:55:05 INFO - PROCESS | 1638 | 12:58:08 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 12:58:09 INFO - PROCESS | 1638 | RSS: Main: 274952192 12:58:09 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 13:01:12 INFO - PROCESS | 1638 | RSS: Main: 279019520 13:01:12 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | __start_tp_report 13:01:12 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 13:01:12 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 13:01:12 INFO - PROCESS | 1638 | |i|pagename|runs| 13:01:12 INFO - PROCESS | 1638 | |0;dojo.html;2835.588839618254;3071.7389021933136;3299.488579270214;3388.6780755828186;3393.202597389613;4628.444586649603;5312.1766126250395;5410;5649.887002259952;5664.716764161791;5650.350209725882;5700.857478563038;5766.711664416784;5779.393163717802;5783.855403614923;5149.665271757323;5180.04169228692;5221.947780522203;5288.735563221842;5317.760700768475;5265.9473405266035;5289.153735402337;5317.388500322474;5332.3601167859915;5362.3029006229135;5144.0740666679985;5282.234076058989;5282.788688452457;5299;5330.413654498002;5154.716490593016;5205.24523944026;5218.399884013349;5225.529702326809;5240.266362709223;5107.616928730323;5123.590112790967;5141.845744627667;5168.586513078944;5260.842174734764;4166.729162604421;5612.747426365823;5692.7153642318135;5696.6866822324755;5698.202251684767;5127.359080114986;6208.286047104596;6212.627242365427;6235.220597425322;6244.094606282064;6160.784372546939;6218.4714299284615;6245.437910588069;6297.0554416837385;6307.7161527730905;4880.6095512358925;4959.677620954626;5860.443257890493;5939.2575928009;5984.102384642295;5354.678719276856;5654.321481422174;5784.652920824764;6131.96934015327;6301.086342480315;6008.279006519158;6076.878462430726;6084.847878803065;6116.938830611637;6138.232720909887;6179.84550386244;6201.875962480725;6208.348123446973;6230.501559875199;6312.021636646327;5709.34688229092;5755.597108202385;6284.057391391237;6285.182926219562;6298.71655775482;6201.875962480725;6235.657038862815;6267.1539342189135;6304.873902521924;6314.863324601616;4869.440014398389;4890.975545122252;4910.165271903785;4946.653734238641;4988.700677959334;4452.844150454718;4455.376247325377;4478.48497422794;4499.910001800011;4510.706804057726;5917.55618328627;5930.911036334458;5937.82186534396;5956.583039187302;5991.310999235056;6139.952147135068;6191.1641928339095;6228.03465462854;6276.686165691697;6285.9057114143325;5910.1725758392995;5966.492848107872;6003.549733769984;6012.338642749294;6052.788152414732;5477.986572484103;5485.4788795064405;5492.176173573996;5503.284573005483;5523.447655234444;5470.671759694431;6217.813465596039;6255.56211065221;6256.5307601930035;6396.744130234832;6179.969100154651;6194.318624951162;6207.689615519296;6250.656213908097;6295.842603934938;6002.069679199731;6316.368363163647;6346.397092276226;6362.77730279438;6390.712417941297;6139.338817383189;6142.692865356804;6150.585365365901;6203.286622038523;6214.533909956862;6275.780347687809;6292.937070629235;6296.968515157395;6299.401556852092;6301.6534090625455;6185.41238582352;6187.443130118311;6188.4739797117745;6205.286392064971;6222.439980401605;5998.610090344114;6013.879722405615;6017.18767966319;6108.358622344767;6166.753329866934;4990.3762029746285;5969.822655884698;6027.819165425044;6041.818745437644;6045.697715114139;6091.0558863376245;6133.141360209664;6142.692865356625;6149.600275982047;6186.072089186658;5852.005159123045;5882.735276912635;6019.819405417845;6024.186734790747;6039.486643635164 13:01:12 INFO - PROCESS | 1638 | |1;ext.html;452.99547004529944;466.51948493052157;481.1603751453712;504.4551883965318;515.5257163409661;38170.80914595426;40094.79952600233;40100.39849402258;40222.59777402225;40239;40222.7988860056;40894.38658420126;40956;40984.79507602458;41004.58995410045;8341.582920853933;8607.61265743043;9294.767630809216;9515.857262141073;9538.856917146213;11056.281341712804;11338.943305283463;11397.658070257905;11490.655280341603;11520.481578328954;9695.757606059835;9801.313908026441;11258.66224013281;11440.427978601077;11449.084073274118;9310.767230819216;11286.54853805855;11289.379084150369;11309.151813614011;11314.943425282821;11169;11202.943985280102;11230.494627741771;11251.324920504796;11264.718382040433;39274.62538811156;39444.40833387501;39579.8021009896;39656.60343396558;39658;3426.3661222673886;3558.1104723819044;3582.462630605404;3589.2821435712963;3675.1730860556427;30205.69794302073;30278.54582181269;30361.848190759127;30382.240443988856;30561.930332438482;3067.720146556684;3105.767067469926;3138.843057847121;3161.4941609342623;3163.825989570573;428024;431875.840620798;432486;435389;441561.7921910402;56539;57023.14465283024;57043.42956570464;57062.42937570654;57332;59876;60083.699581501816;60139.69231692299;60575.69712151499;60612;15639.218039098227;15826.683466330609;15859.762103568455;15862.52412427629;15916.920415397848;11081.501332440059;11280.379579123228;12244.632661020183;12309.076819238593;12325.322107284006;12759.787820157071;15531.611709707347;15690;15695.607609809846;15708.450204242796;6373.362663733589;6886.931130688729;6958.199807022257;7002.299770022957;7074.752383666547;746.2834686303001;1023.247912784103;1024.5799222318963;1041.5365162502615;1066.5094056733835;27214.319642009108;27807.58288625672;29711.554326685116;30017.699823001927;30467.781288748203;869.7651634058768;1082.9458527073614;1100.8936019300552;1151.3552410650063;1233.9383030848421;15109.168995705342;15400.921935464634;15419.383224671112;15491.612709682122;15703.842961570239;612061;617522;618747;620047;620222;57009;57173;57414.7129264351;57523;57655.71172144113;1370.6367812529688;1388.7014291927285;1395.1629022586367;1400.0759498730788;1400.7268582626493;25154.618624006525;27674.308142296602;28832;29572.669229885138;29966.400671986874;47808.282875757584;49283;49385.50614493809;49417.75291123521;49508.50491495039;452072;552180;556218;556862;557186;38467.2306553873;38701;39691.64162838095;39693.60306397015;39694.2061158781;45305.546944530135;45416.318755219356;46070.07859842717;46727.766361167975;46766;34639;37466.62533374631;38188;38233.96668104931;38608.6139138605;37691;37946.0513487165;38337;38807;38938.610613894634;36853;37772.62227377691;37966;38020.61979380281;38060;30132.246693831952;33169;34019.4897076549;34049.14877128092;34086.65913340835;20955.05702243372;20963.26628567993;24504.632430513913;25373.60445175533;25679.229623111336 13:01:12 INFO - PROCESS | 1638 | |2;jquery.html;330.4580487999681;367.1995050789279;368.8708951866846;376.78523241752214;390.1047096912585;17523.47429577113;19322.90338548307;19346.61306773867;19693.803061969393;19958.30145944892;19675.21299148036;19849.702254466196;19972;20170.89914550429;20240.987950602484;5606.523445507137;5637.492625663689;5881.529477641779;5887.116932460144;5898.7935422260225;3800.37293846515;3986.7408618439854;4632.536746325374;5809.883802323951;5897.4692277695;5960.493358064569;5963.105534169866;5966.67183304918;5966.820995370146;5973.22348094747;5676.545876329884;5929.258842644669;5954.55340849438;5962.910556341659;5976.97011514944;5493.093639549509;5696.231008813798;5748.051571490699;5748.5688573357165;5761.855953601152;23463.648045279333;23826.523469530686;24063.03747850084;24161.395965100837;24482.387940301458;2225.276785044851;2585.46997865439;2600.2329312852753;2613.516499447608;2644.95524267915;8760.8685869712;9933.453660048766;10043.154569102173;10319.329243599143;10373.170146388271;1836.4848999575404;1848.9352872649526;1997.1212666426725;2002.7997200280001;2012.255465477768;119589.40205298917;123214.38392807978;125004.37497812453;125730;125928;9537.23702103844;11165.944170279097;11574.594889178838;11613.709657258636;11784.646460606196;14385;14459.421623134956;14461.710765784836;14462.05996610217;14494.637634059021;8164.67341306353;8475.703350382708;8513.574321283912;8572.314214862796;8603.182697643713;8220.917790822135;8240.711575094843;8281.54451505173;8325.333973282122;8357.12250213731;4125.195586860594;4294.613484786384;4550.158220729176;4552.271636538138;4618.838340658128;1589.8489643484081;1687.6624675065036;1695.364238410596;1870.2145099058428;1904.6381187574316;833.2292629317857;892.6652505310509;932.9207017403596;936.8782058332373;1050.406520316019;19649.115789789495;20484.283050093174;21266.045507257993;21287.467813304793;21440.24959126423;950.4772375193754;952.64752041745;957.6791774755535;965.82615129276;1002.6691191906654;8702.738917832476;10859.239853210358;11294.54821807135;11328.716782080513;11335.60325388624;543053.8541921874;545733;556285;557435.2128239414;557442;122852.77147228413;126542.36728816481;130380;131678;132991;763.5456903142622;968.41894863083;979.3095867413457;980.2060331131806;985.507246376826;13666.863331366956;17781.644367112844;18162;18802.435926922215;19170.329038484146;40976.385354219114;42809.57190428056;43473.782631086644;46404.839879003295;54338;52158.47841521536;77643;79409.60295198487;82253;82440.351192977;7991.92008079935;8063.354931605575;8098.83802323962;8439.324854011787;8498.235158835734;9263.76840578991;10812.297200682246;11234.988851003449;12263.632091037283;12333.62999110028;9802.725645666018;10136.306196961981;11090.722731931766;11222.887771122185;11235.325880447199;10672.413017283814;10797.460126993461;11133.498992545194;11187.552497899993;11322.26405283654;10996.835047474135;11162.995330419977;11321.320720756781;11478.540858365572;11478.82781758291;7950.801229969297;8088.231617996516;8158.8776168356335;8223.09545949957;8314.875276870973;8194.959025204837;9204.447733136034;10625.831158572706;11154.330740155616;11199.496022679163 13:01:12 INFO - PROCESS | 1638 | |3;mootools.html;1374.1480282225018;1496.3416096917356;1690.0366790929168;1836.3572749537664;1851.3335199328249;6126.601770884895;7471.663775130119;7713.1515533291295;8369.665213391465;8378.581070946459;7834.2949134577875;8224.095349511548;8244.258016778489;8259.339252859772;8263.21499457552;2277.533105713329;2332.696749422573;2339.894704738282;2342.7540108288554;2347.7065366829147;2336.696229490164;2350.612148995414;2373.6914201153827;2374.465745207332;2388.7969522590515;2315.513742114158;2324.2097686786487;2341.9648705269433;2349.3186975777003;2350.6239001759723;2276.9089236430523;2300.2064287820676;2317.2237300504266;2317.8145748340094;2328.371339738261;2289.576428360742;2318.2465698648007;2319.93040208794;2321.7910388065156;2322.953540929189;8299.875501867476;8304.086550479442;8313.08556058833;8326.25063744266;8352.582370881431;5027.245913113025;5156.04613146569;5179.559737422323;5181.35233095863;5243.632945693803;3332.2669012817382;3404.302118065815;3499.0727457223857;3590.4434812604086;3606.855725770966;1031.4822996100715;1038.9064984151387;1057.0328149742977;1065.7921705267477;1080.4921686807188;10527;10560.26078174528;10617.522211500502;10659.626913058084;10699.144068474503;4412.801423935931;4549.931751023737;4628.9537104628525;4640.95359046412;4663.626909847204;9158.725238242863;9268.443893366419;9274.90725092754;9279.628814847465;9280.907190928005;4405.889852753707;5104.285400043997;5196.012757576124;5214.921776173361;5220.791168353299;4084.7957602119777;4249.787510624456;4398.64810815134;4575.405197324327;4717.080169366941;3753.774773513598;3798.6961043116485;3829.6553310201657;3879.8254078566533;3893.688504919656;1082.5723839083519;1238.6531771103926;1255.629589271167;1268.6194141757435;1272.4846437192953;867.757028032152;876.8640860666607;877.8946526416871;897.8787863638456;904.4166512599337;5578.832635020955;5579;5613.550915926716;5673.77304907799;5887.8233652990475;783.8158032862273;902.5306840442933;903.3721563513295;908.5638893331238;958.5446912716543;3192.952105718416;3640.5449318835144;3758.981205094012;3854.325493038707;3895.2793733159456;9396.671116511026;9803.509824508747;9808.901910980941;9862.112409883144;9983.851857036389;9710.126088651914;9812.509374531244;9826;9828.508574571242;9830.311878168603;7546.1321947976685;7593.430492713069;7710.884336734954;7805.765827025199;7837.725679601301;5385.111456609616;5388.353397592314;5486.835394938158;5573.5819813513335;5626.437356264504;9105.499197544199;9158.129977652112;9282.164605185295;9329.11373419524;9360.017198194364;9179.541022948692;9294.860577091484;9358.4384936904;9493.85759213626;9498.762530936781;5501.834944951658;6207.875842483035;6411.519136064907;6506.707198175999;6777.085093512313;7817.179196184319;7941.761747147595;7943.801404964922;7944.563049032358;7960.920390796249;6977.2673869243;7098.467614929004;7138.107736532934;7158.248383919614;7219.133703955559;6926.099607051051;6940.7917762467205;6963.9651801740665;6970.616616086164;7048.718051278097;6476.417122459001;6528.477721782341;6577.079208910661;6580.967095164494;6602.10871532337;4692.601128904082;4706.10583989041;4755.83354582588;4789.736564488847;4838.806447742051;4171.874843754692;4587.793549290223;4673.392458980311;4841.176999909953;4848.684835485682 13:01:12 INFO - PROCESS | 1638 | |4;prototype.html;330.6756440455973;334.4498300296013;347.8851978846979;351.62200634318117;376.9641884021017;4898.7550622468825;5059.089363914499;5213.218017297398;5551.444855514446;5576.525995290405;4566.977165114178;4772.761361931907;4816.566509014188;5415.810446634368;5461.525388145198;3372.392969265531;3622.81885905705;4114.3211370124045;4188.39268306097;4237.711635716724;4018.97990510047;4208.4318616986775;4223.51429585597;4254.63835573975;4254.744715317075;3660.8651318091343;3858.305505009097;4221.430106935571;4242.596953289432;4254.574542545752;4021.698372622065;4229.773365723951;4234.449521562193;4245.511766146902;4254.617084462413;2947.513660246057;3726.2920045191327;3734.2158146788934;3741.887743367676;3750.043738846604;4185;4761.880952976168;4979.875503112451;5299.231611416323;5379.811706590251;4912.77892494835;4917.901641967141;4932.334134891816;4945.9258111128365;4956.851294461172;4668;4829.468758436569;4893.070316639827;5016.147254966628;5367.1483338248245;3285.589301337333;3836.712246581518;3966.671165159675;4158.625723684883;4164.562720914321;5058.165402708549;5663.716814159276;5799.478046975751;5968.31364393096;6090.208272924491;3341.381844358753;3896.649301562873;4143.647789937889;4275.40144379787;4277;3792.7724336539895;4169.498980367065;4339.587739164775;4345.152695224401;4357.4988876279;3717.423799311111;4053.695972801993;4563.2926896330455;4925.684928083795;4965.776540055706;5251.921221181686;5471.890562188734;5593.972030139905;5861.41385861419;5901;1229.2133034857698;1325.304215286982;1378.951736689211;1412.498563010137;1432.8292636655476;846.8983721953405;903.746950853762;979.2019504104132;991.7917237380086;994.9353292035994;4830.4444988826735;4840.515948405202;4891.654794931351;5082.313887625123;5239.947600524022;5086.898262034812;5095.414027386898;5764.827055188351;5783.835638340388;5811.4479124483105;4859.902801944012;5065.417476990193;5120.513551212629;5139.53302414843;5198.246254293106;2488.751124887533;2609.9347516312246;3007.5037618792653;3017.3060196155006;3034.969650303469;4984.37695288089;5121.590272778169;5128.871518319856;5451.64564303319;5941.821745347646;4652.232381657056;4790.688605240648;5340.652857564246;6194.938050619527;6217.191765070603;5438.389573004901;5494.862628434241;6113.449789518876;6246.781362652286;6249.406306400793;5554.916676249779;5584.553235741212;5717.742701578522;5820.962663067892;5981.726943761343;4790.257510085942;5004;5315.787368505216;5982.611130276518;5994.0109881869;5155.587552995677;5598.9082128984455;5837.503812176013;6063.454289114001;6247.718852651551;3914.5889681584163;3985.960140398675;3994.500687414073;4009.7594144351433;4011.2779699654343;5306.487651019441;5459.755768789078;5873.2658417697785;5966.313873904556;6060.787872424444;5361.906673258524;5754.56840736938;5786.961845031392;5869.7945571904775;5881.441263080001;5493.187248208162;5582.888342233051;5803.65178089316;5848.327442344185;5904.52763778888;5049.722265275298;5377.596680248918;5540.365592150245;5680.318361796446;5743.942560574341;4420.894776305923;5163.432022477599;5292.9206061908335;5447.428020057837;5729.2265544151005;4525.637948964009;5276.841694749163;5747.339056008445;5761.4444100091905;5782 13:01:12 INFO - PROCESS | 1638 | |5;yui.html;730.105620614747;731.5683746589511;738.5642470942144;774.3456779021725;805.7743831727116;11590.478428470718;12023.579174728886;13005.544805931795;15510.922445387785;15542.300596473158;11066.612668556601;11772.352520611363;13926.373313200906;13975.79036314456;14101.435942562286;674.7672053141658;703.057902410772;771.6874665760377;843.0852525010371;870.4907629037006;871.9520426376547;875.6059773102098;880.8678698195258;886.5434301334818;891.9286457083417;949.6154057606681;950.6102497975832;952.0336858089045;952.1240458777941;955.1308309438413;930.7766136127349;936.6487567162314;938.3009657804911;939.8778158839307;942.072059021863;588.3087372337529;690.858374033322;702.1013103227876;703.9190493093308;709.1171491493072;14601.78097328541;15031.699366012728;15036.774448383283;15414.537563873102;15676.980996235323;14115.223662698547;14320.570382888425;14434.422623095168;14478.276086195649;14633.268336583234;11640.126990475663;11655.358955257458;11717.472713727902;11724.531018759239;11752.371393221474;3705.1663375740513;3709.6290370962956;3735.3276410246144;3742.9064273393383;3877.903052423712;87418;90118.09881901163;106971.39542906862;113759.43120284345;114074.42962785134;10606;10866;10914.945425272981;10977.451127443595;11429.828552571718;21355.57288854214;21637.567248654937;21799.237026703988;21811.236606719005;21827.126914923225;7719.459637825411;7758.14660387357;7800.219978002155;7918.485298455582;8059.314958228499;4335.479742430865;4376.803043863034;4388.670849686287;4473.485549161888;4532.79602417892;3538.7876727396438;3619.3304238715923;3624.6919011883756;3640.7633503822167;3645.3620616392027;655.4101308822003;664.7473959895209;668.6924014953079;675.1594265139834;681.7477533312706;512.4260827872732;564.8729035966957;582.7202942587497;590.0352922970887;619.9504039676815;9115.680951166678;9177.495237761854;9193.349661547094;9212.078792120868;9393.20157786582;612.6354818882758;644.6462131993836;654.6301339743126;655.8262060553957;657.8190997475637;3417.6411476795074;4440.666949978765;4472.597466228055;4485;4487.775611219491;95055;96188.51905740566;104780.42829357566;105091.47454262679;112146.43926780314;92475;92536;92781;95147.57278640826;95722;83608.58195708983;84529.57735211284;86860.56569717238;87231.1276887223;88945.55527222322;6977.441804655717;8626.439281446686;8797.47215167092;8914.643414263617;9032.290312906232;47460;47664;48467.75766121147;49631.75184124056;50335.99328013346;60920;64540.67729661322;65664.34335656582;66766;67405.3259467399;11438.599649012245;12541.31022793755;12865.935670321589;12895.48418063267;13100.541481048118;12195.390230488618;14184.787228191379;14530.854691453373;14635;14695.779563306773;10300.742481438025;11608.419579021183;12116.21244619097;12312.138150329749;12357.93821030889;13509.054366194272;13622.727545448837;13688.93155534216;13813.723725525233;13878.375473103533;13117.66495588341;13132.474701012236;13453.192808431526;13468.5286014989;13694;10804.405757683402;10974.451277435937;11058.845829612746;11157.44212789341;11216.270942388719;10885.836712449161;11438.71403214893;11448.911595946434;11542.884571154182;11547.191696581158 13:01:12 INFO - PROCESS | 1638 | __end_tp_report 13:01:12 INFO - PROCESS | 1638 | __start_cc_report 13:01:12 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,370 13:01:12 INFO - PROCESS | 1638 | __end_cc_report 13:01:12 INFO - PROCESS | 1638 | __startTimestamp1482440472553__endTimestamp 13:01:12 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | ------- Summary: start ------- 13:01:12 INFO - PROCESS | 1638 | Number of tests: 6 13:01:12 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5709.54 Median:5991.31 stddev:676.67 (11.3%) stddev-sans-first:640.09 13:01:12 INFO - PROCESS | 1638 | Values: 2835.6 3071.7 3299.5 3388.7 3393.2 4628.4 5312.2 5410.0 5649.9 5664.7 5650.4 5700.9 5766.7 5779.4 5783.9 5149.7 5180.0 5221.9 5288.7 5317.8 5265.9 5289.2 5317.4 5332.4 5362.3 5144.1 5282.2 5282.8 5299.0 5330.4 5154.7 5205.2 5218.4 5225.5 5240.3 5107.6 5123.6 5141.8 5168.6 5260.8 4166.7 5612.7 5692.7 5696.7 5698.2 5127.4 6208.3 6212.6 6235.2 6244.1 6160.8 6218.5 6245.4 6297.1 6307.7 4880.6 4959.7 5860.4 5939.3 5984.1 5354.7 5654.3 5784.7 6132.0 6301.1 6008.3 6076.9 6084.8 6116.9 6138.2 6179.8 6201.9 6208.3 6230.5 6312.0 5709.3 5755.6 6284.1 6285.2 6298.7 6201.9 6235.7 6267.2 6304.9 6314.9 4869.4 4891.0 4910.2 4946.7 4988.7 4452.8 4455.4 4478.5 4499.9 4510.7 5917.6 5930.9 5937.8 5956.6 5991.3 6140.0 6191.2 6228.0 6276.7 6285.9 5910.2 5966.5 6003.5 6012.3 6052.8 5478.0 5485.5 5492.2 5503.3 5523.4 5470.7 6217.8 6255.6 6256.5 6396.7 6180.0 6194.3 6207.7 6250.7 6295.8 6002.1 6316.4 6346.4 6362.8 6390.7 6139.3 6142.7 6150.6 6203.3 6214.5 6275.8 6292.9 6297.0 6299.4 6301.7 6185.4 6187.4 6188.5 6205.3 6222.4 5998.6 6013.9 6017.2 6108.4 6166.8 4990.4 5969.8 6027.8 6041.8 6045.7 6091.1 6133.1 6142.7 6149.6 6186.1 5852.0 5882.7 6019.8 6024.2 6039.5 13:01:12 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:66729.67 Median:37956.03 stddev:142646.55 (375.8%) stddev-sans-first:142959.92 13:01:12 INFO - PROCESS | 1638 | Values: 453.0 466.5 481.2 504.5 515.5 38170.8 40094.8 40100.4 40222.6 40239.0 40222.8 40894.4 40956.0 40984.8 41004.6 8341.6 8607.6 9294.8 9515.9 9538.9 11056.3 11338.9 11397.7 11490.7 11520.5 9695.8 9801.3 11258.7 11440.4 11449.1 9310.8 11286.5 11289.4 11309.2 11314.9 11169.0 11202.9 11230.5 11251.3 11264.7 39274.6 39444.4 39579.8 39656.6 39658.0 3426.4 3558.1 3582.5 3589.3 3675.2 30205.7 30278.5 30361.8 30382.2 30561.9 3067.7 3105.8 3138.8 3161.5 3163.8 428024.0 431875.8 432486.0 435389.0 441561.8 56539.0 57023.1 57043.4 57062.4 57332.0 59876.0 60083.7 60139.7 60575.7 60612.0 15639.2 15826.7 15859.8 15862.5 15916.9 11081.5 11280.4 12244.6 12309.1 12325.3 12759.8 15531.6 15690.0 15695.6 15708.5 6373.4 6886.9 6958.2 7002.3 7074.8 746.3 1023.2 1024.6 1041.5 1066.5 27214.3 27807.6 29711.6 30017.7 30467.8 869.8 1082.9 1100.9 1151.4 1233.9 15109.2 15400.9 15419.4 15491.6 15703.8 612061.0 617522.0 618747.0 620047.0 620222.0 57009.0 57173.0 57414.7 57523.0 57655.7 1370.6 1388.7 1395.2 1400.1 1400.7 25154.6 27674.3 28832.0 29572.7 29966.4 47808.3 49283.0 49385.5 49417.8 49508.5 452072.0 552180.0 556218.0 556862.0 557186.0 38467.2 38701.0 39691.6 39693.6 39694.2 45305.5 45416.3 46070.1 46727.8 46766.0 34639.0 37466.6 38188.0 38234.0 38608.6 37691.0 37946.1 38337.0 38807.0 38938.6 36853.0 37772.6 37966.0 38020.6 38060.0 30132.2 33169.0 34019.5 34049.1 34086.7 20955.1 20963.3 24504.6 25373.6 25679.2 13:01:12 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:33394.92 Median:368.04 stddev:92767.72 (25206.2%) stddev-sans-first:92994.73 13:01:12 INFO - PROCESS | 1638 | Values: 330.5 367.2 368.9 376.8 390.1 17523.5 19322.9 19346.6 19693.8 19958.3 19675.2 19849.7 19972.0 20170.9 20241.0 5606.5 5637.5 5881.5 5887.1 5898.8 3800.4 3986.7 4632.5 5809.9 5897.5 5960.5 5963.1 5966.7 5966.8 5973.2 5676.5 5929.3 5954.6 5962.9 5977.0 5493.1 5696.2 5748.1 5748.6 5761.9 23463.6 23826.5 24063.0 24161.4 24482.4 2225.3 2585.5 2600.2 2613.5 2645.0 8760.9 9933.5 10043.2 10319.3 10373.2 1836.5 1848.9 1997.1 2002.8 2012.3 119589.4 123214.4 125004.4 125730.0 125928.0 9537.2 11165.9 11574.6 11613.7 11784.6 14385.0 14459.4 14461.7 14462.1 14494.6 8164.7 8475.7 8513.6 8572.3 8603.2 8220.9 8240.7 8281.5 8325.3 8357.1 4125.2 4294.6 4550.2 4552.3 4618.8 1589.8 1687.7 1695.4 1870.2 1904.6 833.2 892.7 932.9 936.9 1050.4 19649.1 20484.3 21266.0 21287.5 21440.2 950.5 952.6 957.7 965.8 1002.7 8702.7 10859.2 11294.5 11328.7 11335.6 543053.9 545733.0 556285.0 557435.2 557442.0 122852.8 126542.4 130380.0 131678.0 132991.0 763.5 968.4 979.3 980.2 985.5 13666.9 17781.6 18162.0 18802.4 19170.3 40976.4 42809.6 43473.8 46404.8 54338.0 52158.5 77643.0 79409.6 82253.0 82440.4 7991.9 8063.4 8098.8 8439.3 8498.2 9263.8 10812.3 11235.0 12263.6 12333.6 9802.7 10136.3 11090.7 11222.9 11235.3 10672.4 10797.5 11133.5 11187.6 11322.3 10996.8 11163.0 11321.3 11478.5 11478.8 7950.8 8088.2 8158.9 8223.1 8314.9 8195.0 9204.4 10625.8 11154.3 11199.5 13:01:12 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5353.92 Median:5386.73 stddev:2908.38 (54.0%) stddev-sans-first:2901.16 13:01:12 INFO - PROCESS | 1638 | Values: 1374.1 1496.3 1690.0 1836.4 1851.3 6126.6 7471.7 7713.2 8369.7 8378.6 7834.3 8224.1 8244.3 8259.3 8263.2 2277.5 2332.7 2339.9 2342.8 2347.7 2336.7 2350.6 2373.7 2374.5 2388.8 2315.5 2324.2 2342.0 2349.3 2350.6 2276.9 2300.2 2317.2 2317.8 2328.4 2289.6 2318.2 2319.9 2321.8 2323.0 8299.9 8304.1 8313.1 8326.3 8352.6 5027.2 5156.0 5179.6 5181.4 5243.6 3332.3 3404.3 3499.1 3590.4 3606.9 1031.5 1038.9 1057.0 1065.8 1080.5 10527.0 10560.3 10617.5 10659.6 10699.1 4412.8 4549.9 4629.0 4641.0 4663.6 9158.7 9268.4 9274.9 9279.6 9280.9 4405.9 5104.3 5196.0 5214.9 5220.8 4084.8 4249.8 4398.6 4575.4 4717.1 3753.8 3798.7 3829.7 3879.8 3893.7 1082.6 1238.7 1255.6 1268.6 1272.5 867.8 876.9 877.9 897.9 904.4 5578.8 5579.0 5613.6 5673.8 5887.8 783.8 902.5 903.4 908.6 958.5 3193.0 3640.5 3759.0 3854.3 3895.3 9396.7 9803.5 9808.9 9862.1 9983.9 9710.1 9812.5 9826.0 9828.5 9830.3 7546.1 7593.4 7710.9 7805.8 7837.7 5385.1 5388.4 5486.8 5573.6 5626.4 9105.5 9158.1 9282.2 9329.1 9360.0 9179.5 9294.9 9358.4 9493.9 9498.8 5501.8 6207.9 6411.5 6506.7 6777.1 7817.2 7941.8 7943.8 7944.6 7960.9 6977.3 7098.5 7138.1 7158.2 7219.1 6926.1 6940.8 6964.0 6970.6 7048.7 6476.4 6528.5 6577.1 6581.0 6602.1 4692.6 4706.1 4755.8 4789.7 4838.8 4171.9 4587.8 4673.4 4841.2 4848.7 13:01:12 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4577.04 Median:5010.07 stddev:1382.73 (27.6%) stddev-sans-first:1349.37 13:01:12 INFO - PROCESS | 1638 | Values: 330.7 334.4 347.9 351.6 377.0 4898.8 5059.1 5213.2 5551.4 5576.5 4567.0 4772.8 4816.6 5415.8 5461.5 3372.4 3622.8 4114.3 4188.4 4237.7 4019.0 4208.4 4223.5 4254.6 4254.7 3660.9 3858.3 4221.4 4242.6 4254.6 4021.7 4229.8 4234.4 4245.5 4254.6 2947.5 3726.3 3734.2 3741.9 3750.0 4185.0 4761.9 4979.9 5299.2 5379.8 4912.8 4917.9 4932.3 4945.9 4956.9 4668.0 4829.5 4893.1 5016.1 5367.1 3285.6 3836.7 3966.7 4158.6 4164.6 5058.2 5663.7 5799.5 5968.3 6090.2 3341.4 3896.6 4143.6 4275.4 4277.0 3792.8 4169.5 4339.6 4345.2 4357.5 3717.4 4053.7 4563.3 4925.7 4965.8 5251.9 5471.9 5594.0 5861.4 5901.0 1229.2 1325.3 1379.0 1412.5 1432.8 846.9 903.7 979.2 991.8 994.9 4830.4 4840.5 4891.7 5082.3 5239.9 5086.9 5095.4 5764.8 5783.8 5811.4 4859.9 5065.4 5120.5 5139.5 5198.2 2488.8 2609.9 3007.5 3017.3 3035.0 4984.4 5121.6 5128.9 5451.6 5941.8 4652.2 4790.7 5340.7 6194.9 6217.2 5438.4 5494.9 6113.4 6246.8 6249.4 5554.9 5584.6 5717.7 5821.0 5981.7 4790.3 5004.0 5315.8 5982.6 5994.0 5155.6 5598.9 5837.5 6063.5 6247.7 3914.6 3986.0 3994.5 4009.8 4011.3 5306.5 5459.8 5873.3 5966.3 6060.8 5361.9 5754.6 5787.0 5869.8 5881.4 5493.2 5582.9 5803.7 5848.3 5904.5 5049.7 5377.6 5540.4 5680.3 5743.9 4420.9 5163.4 5292.9 5447.4 5729.2 4525.6 5276.8 5747.3 5761.4 5782.0 13:01:12 INFO - PROCESS | 1638 | 13:01:12 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20432.12 Median:4456.63 stddev:29870.83 (670.3%) stddev-sans-first:29918.00 13:01:12 INFO - PROCESS | 1638 | Values: 730.1 731.6 738.6 774.3 805.8 11590.5 12023.6 13005.5 15510.9 15542.3 11066.6 11772.4 13926.4 13975.8 14101.4 674.8 703.1 771.7 843.1 870.5 872.0 875.6 880.9 886.5 891.9 949.6 950.6 952.0 952.1 955.1 930.8 936.6 938.3 939.9 942.1 588.3 690.9 702.1 703.9 709.1 14601.8 15031.7 15036.8 15414.5 15677.0 14115.2 14320.6 14434.4 14478.3 14633.3 11640.1 11655.4 11717.5 11724.5 11752.4 3705.2 3709.6 3735.3 3742.9 3877.9 87418.0 90118.1 106971.4 113759.4 114074.4 10606.0 10866.0 10914.9 10977.5 11429.8 21355.6 21637.6 21799.2 21811.2 21827.1 7719.5 7758.1 7800.2 7918.5 8059.3 4335.5 4376.8 4388.7 4473.5 4532.8 3538.8 3619.3 3624.7 3640.8 3645.4 655.4 664.7 668.7 675.2 681.7 512.4 564.9 582.7 590.0 620.0 9115.7 9177.5 9193.3 9212.1 9393.2 612.6 644.6 654.6 655.8 657.8 3417.6 4440.7 4472.6 4485.0 4487.8 95055.0 96188.5 104780.4 105091.5 112146.4 92475.0 92536.0 92781.0 95147.6 95722.0 83608.6 84529.6 86860.6 87231.1 88945.6 6977.4 8626.4 8797.5 8914.6 9032.3 47460.0 47664.0 48467.8 49631.8 50336.0 60920.0 64540.7 65664.3 66766.0 67405.3 11438.6 12541.3 12865.9 12895.5 13100.5 12195.4 14184.8 14530.9 14635.0 14695.8 10300.7 11608.4 12116.2 12312.1 12357.9 13509.1 13622.7 13688.9 13813.7 13878.4 13117.7 13132.5 13453.2 13468.5 13694.0 10804.4 10974.5 11058.8 11157.4 11216.3 10885.8 11438.7 11448.9 11542.9 11547.2 13:01:12 INFO - PROCESS | 1638 | -------- Summary: end -------- 13:01:12 INFO - PROCESS | 1638 | 13:01:13 INFO - TEST-INFO | 1638: exit 0 13:01:13 INFO - TEST-OK | dromaeo_css | took 1116191ms 13:01:13 INFO - TEST-START | kraken 13:01:13 INFO - operating with platform_type : mac_ 13:01:13 INFO - Initialising browser for kraken test... 13:01:13 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpmLR3km/profile http://localhost:49247/getInfo.html) 13:01:15 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 13:01:15 INFO - PROCESS | 1649 | colorDepth:24 13:01:15 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 13:01:15 INFO - PROCESS | 1649 | __metrics 13:01:15 INFO - PROCESS | 1649 | 2016-12-22 13:01:15.849 plugin-container[1650:9977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 13:01:15 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:01:15 INFO - PROCESS | 1649 | 2016-12-22 13:01:15.852 plugin-container[1650:9977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 13:01:15 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:01:15 INFO - PROCESS | 1649 | 2016-12-22 13:01:15.852 plugin-container[1650:9977] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:01:15 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 13:01:16 INFO - TEST-INFO | 1649: exit 0 13:01:16 INFO - Browser initialized. 13:01:16 INFO - Running cycle 1/1 for kraken test... 13:01:16 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpmLR3km/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:01:18 INFO - PROCESS | 1651 | 2016-12-22 13:01:18.993 plugin-container[1652:10101] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 13:01:18 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:01:18 INFO - PROCESS | 1651 | 2016-12-22 13:01:18.994 plugin-container[1652:10101] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 13:01:18 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:01:18 INFO - PROCESS | 1651 | 2016-12-22 13:01:18.994 plugin-container[1652:10101] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:01:28 INFO - PROCESS | 1651 | RSS: Main: 234930176 13:01:28 INFO - PROCESS | 1651 | 13:01:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 13:01:32 INFO - PROCESS | 1651 | RSS: Main: 247427072 13:01:32 INFO - PROCESS | 1651 | 13:01:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 13:01:36 INFO - PROCESS | 1651 | RSS: Main: 254935040 13:01:36 INFO - PROCESS | 1651 | 13:01:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 13:01:40 INFO - PROCESS | 1651 | RSS: Main: 257376256 13:01:40 INFO - PROCESS | 1651 | 13:01:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 13:01:42 INFO - PROCESS | 1651 | RSS: Main: 259653632 13:01:42 INFO - PROCESS | 1651 | 13:01:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:01:45 INFO - PROCESS | 1651 | RSS: Main: 260247552 13:01:45 INFO - PROCESS | 1651 | 13:01:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 13:01:49 INFO - PROCESS | 1651 | RSS: Main: 266276864 13:01:49 INFO - PROCESS | 1651 | 13:01:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 13:01:52 INFO - PROCESS | 1651 | RSS: Main: 265031680 13:01:52 INFO - PROCESS | 1651 | 13:01:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 13:01:56 INFO - PROCESS | 1651 | RSS: Main: 265338880 13:01:56 INFO - PROCESS | 1651 | 13:01:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:01:58 INFO - PROCESS | 1651 | RSS: Main: 264355840 13:01:58 INFO - PROCESS | 1651 | 13:02:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:02:01 INFO - PROCESS | 1651 | RSS: Main: 264364032 13:02:01 INFO - PROCESS | 1651 | 13:02:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:02:04 INFO - PROCESS | 1651 | RSS: Main: 264400896 13:02:04 INFO - PROCESS | 1651 | 13:02:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:02:08 INFO - PROCESS | 1651 | RSS: Main: 264667136 13:02:08 INFO - PROCESS | 1651 | 13:02:11 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:02:11 INFO - PROCESS | 1651 | RSS: Main: 264515584 13:02:11 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 13:02:13 INFO - PROCESS | 1651 | RSS: Main: 265588736 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | __start_tp_report 13:02:13 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 13:02:13 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 13:02:13 INFO - PROCESS | 1651 | |i|pagename|runs| 13:02:13 INFO - PROCESS | 1651 | |0;ai-astar;156;176;136;129;166;117;119;123;164;119 13:02:13 INFO - PROCESS | 1651 | |1;audio-beat-detection;151;117;113;148;113;115;160;140;169;154 13:02:13 INFO - PROCESS | 1651 | |2;audio-dft;201;215;256;278;217;203;196;193;203;199 13:02:13 INFO - PROCESS | 1651 | |3;audio-fft;83;59;59;86;60;63;72;61;72;60 13:02:13 INFO - PROCESS | 1651 | |4;audio-oscillator;90;72;84;72;71;71;82;70;70;83 13:02:13 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;90;92;129;87;125;133;112;87;89;87 13:02:13 INFO - PROCESS | 1651 | |6;imaging-darkroom;150;100;100;99;99;98;101;140;162;100 13:02:13 INFO - PROCESS | 1651 | |7;imaging-desaturate;146;139;91;93;92;97;96;93;92;93 13:02:13 INFO - PROCESS | 1651 | |8;json-parse-financial;61;60;64;64;86;97;93;97;94;62 13:02:13 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;71;87;106;124;78;74;73;74;73;74 13:02:13 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;88;89;90;87;88;89;91;89;86;90 13:02:13 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;157;190;148;153;147;143;157;130;168;182 13:02:13 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;176;217;172;172;174;171;172;176;195;184 13:02:13 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;63;80;68;72;79;74;88;61;62;58 13:02:13 INFO - PROCESS | 1651 | __end_tp_report 13:02:13 INFO - PROCESS | 1651 | __start_cc_report 13:02:13 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,939 13:02:13 INFO - PROCESS | 1651 | __end_cc_report 13:02:13 INFO - PROCESS | 1651 | __startTimestamp1482440533648__endTimestamp 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | ------- Summary: start ------- 13:02:13 INFO - PROCESS | 1651 | Number of tests: 14 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:140.50 Median:146.00 stddev:22.70 (15.5%) stddev-sans-first:23.38 13:02:13 INFO - PROCESS | 1651 | Values: 156.0 176.0 136.0 129.0 166.0 117.0 119.0 123.0 164.0 119.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:149.50 stddev:21.59 (14.4%) stddev-sans-first:22.38 13:02:13 INFO - PROCESS | 1651 | Values: 151.0 117.0 113.0 148.0 113.0 115.0 160.0 140.0 169.0 154.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:216.10 Median:209.00 stddev:28.34 (13.6%) stddev-sans-first:29.52 13:02:13 INFO - PROCESS | 1651 | Values: 201.0 215.0 256.0 278.0 217.0 203.0 196.0 193.0 203.0 199.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.50 Median:67.50 stddev:10.23 (15.2%) stddev-sans-first:9.19 13:02:13 INFO - PROCESS | 1651 | Values: 83.0 59.0 59.0 86.0 60.0 63.0 72.0 61.0 72.0 60.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:77.00 stddev:7.43 (9.6%) stddev-sans-first:6.06 13:02:13 INFO - PROCESS | 1651 | Values: 90.0 72.0 84.0 72.0 71.0 71.0 82.0 70.0 70.0 83.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:103.10 Median:87.00 stddev:19.42 (22.3%) stddev-sans-first:20.01 13:02:13 INFO - PROCESS | 1651 | Values: 90.0 92.0 129.0 87.0 125.0 133.0 112.0 87.0 89.0 87.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:114.90 Median:156.00 stddev:25.23 (16.2%) stddev-sans-first:23.35 13:02:13 INFO - PROCESS | 1651 | Values: 150.0 100.0 100.0 99.0 99.0 98.0 101.0 140.0 162.0 100.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:103.20 Median:93.00 stddev:20.86 (22.4%) stddev-sans-first:15.33 13:02:13 INFO - PROCESS | 1651 | Values: 146.0 139.0 91.0 93.0 92.0 97.0 96.0 93.0 92.0 93.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:77.80 Median:89.50 stddev:16.76 (18.7%) stddev-sans-first:16.64 13:02:13 INFO - PROCESS | 1651 | Values: 61.0 60.0 64.0 64.0 86.0 97.0 93.0 97.0 94.0 62.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:74.00 stddev:17.75 (24.0%) stddev-sans-first:18.25 13:02:13 INFO - PROCESS | 1651 | Values: 71.0 87.0 106.0 124.0 78.0 74.0 73.0 74.0 73.0 74.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:89.00 stddev:1.49 (1.7%) stddev-sans-first:1.56 13:02:13 INFO - PROCESS | 1651 | Values: 88.0 89.0 90.0 87.0 88.0 89.0 91.0 89.0 86.0 90.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:157.50 Median:157.00 stddev:18.12 (11.5%) stddev-sans-first:19.22 13:02:13 INFO - PROCESS | 1651 | Values: 157.0 190.0 148.0 153.0 147.0 143.0 157.0 130.0 168.0 182.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:176.00 stddev:14.69 (8.3%) stddev-sans-first:15.48 13:02:13 INFO - PROCESS | 1651 | Values: 176.0 217.0 172.0 172.0 174.0 171.0 172.0 176.0 195.0 184.0 13:02:13 INFO - PROCESS | 1651 | 13:02:13 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:73.00 stddev:9.80 (13.4%) stddev-sans-first:10.01 13:02:13 INFO - PROCESS | 1651 | Values: 63.0 80.0 68.0 72.0 79.0 74.0 88.0 61.0 62.0 58.0 13:02:13 INFO - PROCESS | 1651 | -------- Summary: end -------- 13:02:13 INFO - PROCESS | 1651 | 13:02:14 INFO - TEST-INFO | 1651: exit 0 13:02:14 INFO - TEST-OK | kraken | took 61356ms 13:02:14 INFO - SUITE-END | took 1177s 13:02:14 INFO - Completed test suite (00:19:37) 13:02:14 INFO - javascript benchmark 13:02:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7206.938721260059, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2835.588839618254, 3071.7389021933136, 3299.488579270214, 3388.6780755828186, 3393.202597389613, 4628.444586649603, 5312.1766126250395, 5410.0, 5649.887002259952, 5664.716764161791, 5650.350209725882, 5700.857478563038, 5766.711664416784, 5779.393163717802, 5783.855403614923, 5149.665271757323, 5180.04169228692, 5221.947780522203, 5288.735563221842, 5317.760700768475, 5265.9473405266035, 5289.153735402337, 5317.388500322474, 5332.3601167859915, 5362.3029006229135, 5144.0740666679985, 5282.234076058989, 5282.788688452457, 5299.0, 5330.413654498002, 5154.716490593016, 5205.24523944026, 5218.399884013349, 5225.529702326809, 5240.266362709223, 5107.616928730323, 5123.590112790967, 5141.845744627667, 5168.586513078944, 5260.842174734764, 4166.729162604421, 5612.747426365823, 5692.7153642318135, 5696.6866822324755, 5698.202251684767, 5127.359080114986, 6208.286047104596, 6212.627242365427, 6235.220597425322, 6244.094606282064, 6160.784372546939, 6218.4714299284615, 6245.437910588069, 6297.0554416837385, 6307.7161527730905, 4880.6095512358925, 4959.677620954626, 5860.443257890493, 5939.2575928009, 5984.102384642295, 5354.678719276856, 5654.321481422174, 5784.652920824764, 6131.96934015327, 6301.086342480315, 6008.279006519158, 6076.878462430726, 6084.847878803065, 6116.938830611637, 6138.232720909887, 6179.84550386244, 6201.875962480725, 6208.348123446973, 6230.501559875199, 6312.021636646327, 5709.34688229092, 5755.597108202385, 6284.057391391237, 6285.182926219562, 6298.71655775482, 6201.875962480725, 6235.657038862815, 6267.1539342189135, 6304.873902521924, 6314.863324601616, 4869.440014398389, 4890.975545122252, 4910.165271903785, 4946.653734238641, 4988.700677959334, 4452.844150454718, 4455.376247325377, 4478.48497422794, 4499.910001800011, 4510.706804057726, 5917.55618328627, 5930.911036334458, 5937.82186534396, 5956.583039187302, 5991.310999235056, 6139.952147135068, 6191.1641928339095, 6228.03465462854, 6276.686165691697, 6285.9057114143325, 5910.1725758392995, 5966.492848107872, 6003.549733769984, 6012.338642749294, 6052.788152414732, 5477.986572484103, 5485.4788795064405, 5492.176173573996, 5503.284573005483, 5523.447655234444, 5470.671759694431, 6217.813465596039, 6255.56211065221, 6256.5307601930035, 6396.744130234832, 6179.969100154651, 6194.318624951162, 6207.689615519296, 6250.656213908097, 6295.842603934938, 6002.069679199731, 6316.368363163647, 6346.397092276226, 6362.77730279438, 6390.712417941297, 6139.338817383189, 6142.692865356804, 6150.585365365901, 6203.286622038523, 6214.533909956862, 6275.780347687809, 6292.937070629235, 6296.968515157395, 6299.401556852092, 6301.6534090625455, 6185.41238582352, 6187.443130118311, 6188.4739797117745, 6205.286392064971, 6222.439980401605, 5998.610090344114, 6013.879722405615, 6017.18767966319, 6108.358622344767, 6166.753329866934, 4990.3762029746285, 5969.822655884698, 6027.819165425044, 6041.818745437644, 6045.697715114139, 6091.0558863376245, 6133.141360209664, 6142.692865356625, 6149.600275982047, 6186.072089186658, 5852.005159123045, 5882.735276912635, 6019.819405417845, 6024.186734790747, 6039.486643635164], "value": 5665.9040445677465, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.99547004529944, 466.51948493052157, 481.1603751453712, 504.4551883965318, 515.5257163409661, 38170.80914595426, 40094.79952600233, 40100.39849402258, 40222.59777402225, 40239.0, 40222.7988860056, 40894.38658420126, 40956.0, 40984.79507602458, 41004.58995410045, 8341.582920853933, 8607.61265743043, 9294.767630809216, 9515.857262141073, 9538.856917146213, 11056.281341712804, 11338.943305283463, 11397.658070257905, 11490.655280341603, 11520.481578328954, 9695.757606059835, 9801.313908026441, 11258.66224013281, 11440.427978601077, 11449.084073274118, 9310.767230819216, 11286.54853805855, 11289.379084150369, 11309.151813614011, 11314.943425282821, 11169.0, 11202.943985280102, 11230.494627741771, 11251.324920504796, 11264.718382040433, 39274.62538811156, 39444.40833387501, 39579.8021009896, 39656.60343396558, 39658.0, 3426.3661222673886, 3558.1104723819044, 3582.462630605404, 3589.2821435712963, 3675.1730860556427, 30205.69794302073, 30278.54582181269, 30361.848190759127, 30382.240443988856, 30561.930332438482, 3067.720146556684, 3105.767067469926, 3138.843057847121, 3161.4941609342623, 3163.825989570573, 428024.0, 431875.840620798, 432486.0, 435389.0, 441561.7921910402, 56539.0, 57023.14465283024, 57043.42956570464, 57062.42937570654, 57332.0, 59876.0, 60083.699581501816, 60139.69231692299, 60575.69712151499, 60612.0, 15639.218039098227, 15826.683466330609, 15859.762103568455, 15862.52412427629, 15916.920415397848, 11081.501332440059, 11280.379579123228, 12244.632661020183, 12309.076819238593, 12325.322107284006, 12759.787820157071, 15531.611709707347, 15690.0, 15695.607609809846, 15708.450204242796, 6373.362663733589, 6886.931130688729, 6958.199807022257, 7002.299770022957, 7074.752383666547, 746.2834686303001, 1023.247912784103, 1024.5799222318963, 1041.5365162502615, 1066.5094056733835, 27214.319642009108, 27807.58288625672, 29711.554326685116, 30017.699823001927, 30467.781288748203, 869.7651634058768, 1082.9458527073614, 1100.8936019300552, 1151.3552410650063, 1233.9383030848421, 15109.168995705342, 15400.921935464634, 15419.383224671112, 15491.612709682122, 15703.842961570239, 612061.0, 617522.0, 618747.0, 620047.0, 620222.0, 57009.0, 57173.0, 57414.7129264351, 57523.0, 57655.71172144113, 1370.6367812529688, 1388.7014291927285, 1395.1629022586367, 1400.0759498730788, 1400.7268582626493, 25154.618624006525, 27674.308142296602, 28832.0, 29572.669229885138, 29966.400671986874, 47808.282875757584, 49283.0, 49385.50614493809, 49417.75291123521, 49508.50491495039, 452072.0, 552180.0, 556218.0, 556862.0, 557186.0, 38467.2306553873, 38701.0, 39691.64162838095, 39693.60306397015, 39694.2061158781, 45305.546944530135, 45416.318755219356, 46070.07859842717, 46727.766361167975, 46766.0, 34639.0, 37466.62533374631, 38188.0, 38233.96668104931, 38608.6139138605, 37691.0, 37946.0513487165, 38337.0, 38807.0, 38938.610613894634, 36853.0, 37772.62227377691, 37966.0, 38020.61979380281, 38060.0, 30132.246693831952, 33169.0, 34019.4897076549, 34049.14877128092, 34086.65913340835, 20955.05702243372, 20963.26628567993, 24504.632430513913, 25373.60445175533, 25679.229623111336], "value": 19874.675397339503, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.4580487999681, 367.1995050789279, 368.8708951866846, 376.78523241752214, 390.1047096912585, 17523.47429577113, 19322.90338548307, 19346.61306773867, 19693.803061969393, 19958.30145944892, 19675.21299148036, 19849.702254466196, 19972.0, 20170.89914550429, 20240.987950602484, 5606.523445507137, 5637.492625663689, 5881.529477641779, 5887.116932460144, 5898.7935422260225, 3800.37293846515, 3986.7408618439854, 4632.536746325374, 5809.883802323951, 5897.4692277695, 5960.493358064569, 5963.105534169866, 5966.67183304918, 5966.820995370146, 5973.22348094747, 5676.545876329884, 5929.258842644669, 5954.55340849438, 5962.910556341659, 5976.97011514944, 5493.093639549509, 5696.231008813798, 5748.051571490699, 5748.5688573357165, 5761.855953601152, 23463.648045279333, 23826.523469530686, 24063.03747850084, 24161.395965100837, 24482.387940301458, 2225.276785044851, 2585.46997865439, 2600.2329312852753, 2613.516499447608, 2644.95524267915, 8760.8685869712, 9933.453660048766, 10043.154569102173, 10319.329243599143, 10373.170146388271, 1836.4848999575404, 1848.9352872649526, 1997.1212666426725, 2002.7997200280001, 2012.255465477768, 119589.40205298917, 123214.38392807978, 125004.37497812453, 125730.0, 125928.0, 9537.23702103844, 11165.944170279097, 11574.594889178838, 11613.709657258636, 11784.646460606196, 14385.0, 14459.421623134956, 14461.710765784836, 14462.05996610217, 14494.637634059021, 8164.67341306353, 8475.703350382708, 8513.574321283912, 8572.314214862796, 8603.182697643713, 8220.917790822135, 8240.711575094843, 8281.54451505173, 8325.333973282122, 8357.12250213731, 4125.195586860594, 4294.613484786384, 4550.158220729176, 4552.271636538138, 4618.838340658128, 1589.8489643484081, 1687.6624675065036, 1695.364238410596, 1870.2145099058428, 1904.6381187574316, 833.2292629317857, 892.6652505310509, 932.9207017403596, 936.8782058332373, 1050.406520316019, 19649.115789789495, 20484.283050093174, 21266.045507257993, 21287.467813304793, 21440.24959126423, 950.4772375193754, 952.64752041745, 957.6791774755535, 965.82615129276, 1002.6691191906654, 8702.738917832476, 10859.239853210358, 11294.54821807135, 11328.716782080513, 11335.60325388624, 543053.8541921874, 545733.0, 556285.0, 557435.2128239414, 557442.0, 122852.77147228413, 126542.36728816481, 130380.0, 131678.0, 132991.0, 763.5456903142622, 968.41894863083, 979.3095867413457, 980.2060331131806, 985.507246376826, 13666.863331366956, 17781.644367112844, 18162.0, 18802.435926922215, 19170.329038484146, 40976.385354219114, 42809.57190428056, 43473.782631086644, 46404.839879003295, 54338.0, 52158.47841521536, 77643.0, 79409.60295198487, 82253.0, 82440.351192977, 7991.92008079935, 8063.354931605575, 8098.83802323962, 8439.324854011787, 8498.235158835734, 9263.76840578991, 10812.297200682246, 11234.988851003449, 12263.632091037283, 12333.62999110028, 9802.725645666018, 10136.306196961981, 11090.722731931766, 11222.887771122185, 11235.325880447199, 10672.413017283814, 10797.460126993461, 11133.498992545194, 11187.552497899993, 11322.26405283654, 10996.835047474135, 11162.995330419977, 11321.320720756781, 11478.540858365572, 11478.82781758291, 7950.801229969297, 8088.231617996516, 8158.8776168356335, 8223.09545949957, 8314.875276870973, 8194.959025204837, 9204.447733136034, 10625.831158572706, 11154.330740155616, 11199.496022679163], "value": 9321.386990460316, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.1480282225018, 1496.3416096917356, 1690.0366790929168, 1836.3572749537664, 1851.3335199328249, 6126.601770884895, 7471.663775130119, 7713.1515533291295, 8369.665213391465, 8378.581070946459, 7834.2949134577875, 8224.095349511548, 8244.258016778489, 8259.339252859772, 8263.21499457552, 2277.533105713329, 2332.696749422573, 2339.894704738282, 2342.7540108288554, 2347.7065366829147, 2336.696229490164, 2350.612148995414, 2373.6914201153827, 2374.465745207332, 2388.7969522590515, 2315.513742114158, 2324.2097686786487, 2341.9648705269433, 2349.3186975777003, 2350.6239001759723, 2276.9089236430523, 2300.2064287820676, 2317.2237300504266, 2317.8145748340094, 2328.371339738261, 2289.576428360742, 2318.2465698648007, 2319.93040208794, 2321.7910388065156, 2322.953540929189, 8299.875501867476, 8304.086550479442, 8313.08556058833, 8326.25063744266, 8352.582370881431, 5027.245913113025, 5156.04613146569, 5179.559737422323, 5181.35233095863, 5243.632945693803, 3332.2669012817382, 3404.302118065815, 3499.0727457223857, 3590.4434812604086, 3606.855725770966, 1031.4822996100715, 1038.9064984151387, 1057.0328149742977, 1065.7921705267477, 1080.4921686807188, 10527.0, 10560.26078174528, 10617.522211500502, 10659.626913058084, 10699.144068474503, 4412.801423935931, 4549.931751023737, 4628.9537104628525, 4640.95359046412, 4663.626909847204, 9158.725238242863, 9268.443893366419, 9274.90725092754, 9279.628814847465, 9280.907190928005, 4405.889852753707, 5104.285400043997, 5196.012757576124, 5214.921776173361, 5220.791168353299, 4084.7957602119777, 4249.787510624456, 4398.64810815134, 4575.405197324327, 4717.080169366941, 3753.774773513598, 3798.6961043116485, 3829.6553310201657, 3879.8254078566533, 3893.688504919656, 1082.5723839083519, 1238.6531771103926, 1255.629589271167, 1268.6194141757435, 1272.4846437192953, 867.757028032152, 876.8640860666607, 877.8946526416871, 897.8787863638456, 904.4166512599337, 5578.832635020955, 5579.0, 5613.550915926716, 5673.77304907799, 5887.8233652990475, 783.8158032862273, 902.5306840442933, 903.3721563513295, 908.5638893331238, 958.5446912716543, 3192.952105718416, 3640.5449318835144, 3758.981205094012, 3854.325493038707, 3895.2793733159456, 9396.671116511026, 9803.509824508747, 9808.901910980941, 9862.112409883144, 9983.851857036389, 9710.126088651914, 9812.509374531244, 9826.0, 9828.508574571242, 9830.311878168603, 7546.1321947976685, 7593.430492713069, 7710.884336734954, 7805.765827025199, 7837.725679601301, 5385.111456609616, 5388.353397592314, 5486.835394938158, 5573.5819813513335, 5626.437356264504, 9105.499197544199, 9158.129977652112, 9282.164605185295, 9329.11373419524, 9360.017198194364, 9179.541022948692, 9294.860577091484, 9358.4384936904, 9493.85759213626, 9498.762530936781, 5501.834944951658, 6207.875842483035, 6411.519136064907, 6506.707198175999, 6777.085093512313, 7817.179196184319, 7941.761747147595, 7943.801404964922, 7944.563049032358, 7960.920390796249, 6977.2673869243, 7098.467614929004, 7138.107736532934, 7158.248383919614, 7219.133703955559, 6926.099607051051, 6940.7917762467205, 6963.9651801740665, 6970.616616086164, 7048.718051278097, 6476.417122459001, 6528.477721782341, 6577.079208910661, 6580.967095164494, 6602.10871532337, 4692.601128904082, 4706.10583989041, 4755.83354582588, 4789.736564488847, 4838.806447742051, 4171.874843754692, 4587.793549290223, 4673.392458980311, 4841.176999909953, 4848.684835485682], "value": 4361.632931282849, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.6756440455973, 334.4498300296013, 347.8851978846979, 351.62200634318117, 376.9641884021017, 4898.7550622468825, 5059.089363914499, 5213.218017297398, 5551.444855514446, 5576.525995290405, 4566.977165114178, 4772.761361931907, 4816.566509014188, 5415.810446634368, 5461.525388145198, 3372.392969265531, 3622.81885905705, 4114.3211370124045, 4188.39268306097, 4237.711635716724, 4018.97990510047, 4208.4318616986775, 4223.51429585597, 4254.63835573975, 4254.744715317075, 3660.8651318091343, 3858.305505009097, 4221.430106935571, 4242.596953289432, 4254.574542545752, 4021.698372622065, 4229.773365723951, 4234.449521562193, 4245.511766146902, 4254.617084462413, 2947.513660246057, 3726.2920045191327, 3734.2158146788934, 3741.887743367676, 3750.043738846604, 4185.0, 4761.880952976168, 4979.875503112451, 5299.231611416323, 5379.811706590251, 4912.77892494835, 4917.901641967141, 4932.334134891816, 4945.9258111128365, 4956.851294461172, 4668.0, 4829.468758436569, 4893.070316639827, 5016.147254966628, 5367.1483338248245, 3285.589301337333, 3836.712246581518, 3966.671165159675, 4158.625723684883, 4164.562720914321, 5058.165402708549, 5663.716814159276, 5799.478046975751, 5968.31364393096, 6090.208272924491, 3341.381844358753, 3896.649301562873, 4143.647789937889, 4275.40144379787, 4277.0, 3792.7724336539895, 4169.498980367065, 4339.587739164775, 4345.152695224401, 4357.4988876279, 3717.423799311111, 4053.695972801993, 4563.2926896330455, 4925.684928083795, 4965.776540055706, 5251.921221181686, 5471.890562188734, 5593.972030139905, 5861.41385861419, 5901.0, 1229.2133034857698, 1325.304215286982, 1378.951736689211, 1412.498563010137, 1432.8292636655476, 846.8983721953405, 903.746950853762, 979.2019504104132, 991.7917237380086, 994.9353292035994, 4830.4444988826735, 4840.515948405202, 4891.654794931351, 5082.313887625123, 5239.947600524022, 5086.898262034812, 5095.414027386898, 5764.827055188351, 5783.835638340388, 5811.4479124483105, 4859.902801944012, 5065.417476990193, 5120.513551212629, 5139.53302414843, 5198.246254293106, 2488.751124887533, 2609.9347516312246, 3007.5037618792653, 3017.3060196155006, 3034.969650303469, 4984.37695288089, 5121.590272778169, 5128.871518319856, 5451.64564303319, 5941.821745347646, 4652.232381657056, 4790.688605240648, 5340.652857564246, 6194.938050619527, 6217.191765070603, 5438.389573004901, 5494.862628434241, 6113.449789518876, 6246.781362652286, 6249.406306400793, 5554.916676249779, 5584.553235741212, 5717.742701578522, 5820.962663067892, 5981.726943761343, 4790.257510085942, 5004.0, 5315.787368505216, 5982.611130276518, 5994.0109881869, 5155.587552995677, 5598.9082128984455, 5837.503812176013, 6063.454289114001, 6247.718852651551, 3914.5889681584163, 3985.960140398675, 3994.500687414073, 4009.7594144351433, 4011.2779699654343, 5306.487651019441, 5459.755768789078, 5873.2658417697785, 5966.313873904556, 6060.787872424444, 5361.906673258524, 5754.56840736938, 5786.961845031392, 5869.7945571904775, 5881.441263080001, 5493.187248208162, 5582.888342233051, 5803.65178089316, 5848.327442344185, 5904.52763778888, 5049.722265275298, 5377.596680248918, 5540.365592150245, 5680.318361796446, 5743.942560574341, 4420.894776305923, 5163.432022477599, 5292.9206061908335, 5447.428020057837, 5729.2265544151005, 4525.637948964009, 5276.841694749163, 5747.339056008445, 5761.4444100091905, 5782.0], "value": 4153.7649993933355, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.105620614747, 731.5683746589511, 738.5642470942144, 774.3456779021725, 805.7743831727116, 11590.478428470718, 12023.579174728886, 13005.544805931795, 15510.922445387785, 15542.300596473158, 11066.612668556601, 11772.352520611363, 13926.373313200906, 13975.79036314456, 14101.435942562286, 674.7672053141658, 703.057902410772, 771.6874665760377, 843.0852525010371, 870.4907629037006, 871.9520426376547, 875.6059773102098, 880.8678698195258, 886.5434301334818, 891.9286457083417, 949.6154057606681, 950.6102497975832, 952.0336858089045, 952.1240458777941, 955.1308309438413, 930.7766136127349, 936.6487567162314, 938.3009657804911, 939.8778158839307, 942.072059021863, 588.3087372337529, 690.858374033322, 702.1013103227876, 703.9190493093308, 709.1171491493072, 14601.78097328541, 15031.699366012728, 15036.774448383283, 15414.537563873102, 15676.980996235323, 14115.223662698547, 14320.570382888425, 14434.422623095168, 14478.276086195649, 14633.268336583234, 11640.126990475663, 11655.358955257458, 11717.472713727902, 11724.531018759239, 11752.371393221474, 3705.1663375740513, 3709.6290370962956, 3735.3276410246144, 3742.9064273393383, 3877.903052423712, 87418.0, 90118.09881901163, 106971.39542906862, 113759.43120284345, 114074.42962785134, 10606.0, 10866.0, 10914.945425272981, 10977.451127443595, 11429.828552571718, 21355.57288854214, 21637.567248654937, 21799.237026703988, 21811.236606719005, 21827.126914923225, 7719.459637825411, 7758.14660387357, 7800.219978002155, 7918.485298455582, 8059.314958228499, 4335.479742430865, 4376.803043863034, 4388.670849686287, 4473.485549161888, 4532.79602417892, 3538.7876727396438, 3619.3304238715923, 3624.6919011883756, 3640.7633503822167, 3645.3620616392027, 655.4101308822003, 664.7473959895209, 668.6924014953079, 675.1594265139834, 681.7477533312706, 512.4260827872732, 564.8729035966957, 582.7202942587497, 590.0352922970887, 619.9504039676815, 9115.680951166678, 9177.495237761854, 9193.349661547094, 9212.078792120868, 9393.20157786582, 612.6354818882758, 644.6462131993836, 654.6301339743126, 655.8262060553957, 657.8190997475637, 3417.6411476795074, 4440.666949978765, 4472.597466228055, 4485.0, 4487.775611219491, 95055.0, 96188.51905740566, 104780.42829357566, 105091.47454262679, 112146.43926780314, 92475.0, 92536.0, 92781.0, 95147.57278640826, 95722.0, 83608.58195708983, 84529.57735211284, 86860.56569717238, 87231.1276887223, 88945.55527222322, 6977.441804655717, 8626.439281446686, 8797.47215167092, 8914.643414263617, 9032.290312906232, 47460.0, 47664.0, 48467.75766121147, 49631.75184124056, 50335.99328013346, 60920.0, 64540.67729661322, 65664.34335656582, 66766.0, 67405.3259467399, 11438.599649012245, 12541.31022793755, 12865.935670321589, 12895.48418063267, 13100.541481048118, 12195.390230488618, 14184.787228191379, 14530.854691453373, 14635.0, 14695.779563306773, 10300.742481438025, 11608.419579021183, 12116.21244619097, 12312.138150329749, 12357.93821030889, 13509.054366194272, 13622.727545448837, 13688.93155534216, 13813.723725525233, 13878.375473103533, 13117.66495588341, 13132.474701012236, 13453.192808431526, 13468.5286014989, 13694.0, 10804.405757683402, 10974.451277435937, 11058.845829612746, 11157.44212789341, 11216.270942388719, 10885.836712449161, 11438.71403214893, 11448.911595946434, 11542.884571154182, 11547.191696581158], "value": 7367.456015208402, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.6000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 176.0, 136.0, 129.0, 166.0, 117.0, 119.0, 123.0, 164.0, 119.0], "value": 140.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 113.0, 148.0, 113.0, 115.0, 160.0, 140.0, 169.0, 154.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 215.0, 256.0, 278.0, 217.0, 203.0, 196.0, 193.0, 203.0, 199.0], "value": 216.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 86.0, 60.0, 63.0, 72.0, 61.0, 72.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 84.0, 72.0, 71.0, 71.0, 82.0, 70.0, 70.0, 83.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 129.0, 87.0, 125.0, 133.0, 112.0, 87.0, 89.0, 87.0], "value": 103.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 100.0, 100.0, 99.0, 99.0, 98.0, 101.0, 140.0, 162.0, 100.0], "value": 114.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 139.0, 91.0, 93.0, 92.0, 97.0, 96.0, 93.0, 92.0, 93.0], "value": 103.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 64.0, 64.0, 86.0, 97.0, 93.0, 97.0, 94.0, 62.0], "value": 77.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 87.0, 106.0, 124.0, 78.0, 74.0, 73.0, 74.0, 73.0, 74.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 90.0, 87.0, 88.0, 89.0, 91.0, 89.0, 86.0, 90.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 190.0, 148.0, 153.0, 147.0, 143.0, 157.0, 130.0, 168.0, 182.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 217.0, 172.0, 172.0, 174.0, 171.0, 172.0, 176.0, 195.0, 184.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 80.0, 68.0, 72.0, 79.0, 74.0, 88.0, 61.0, 62.0, 58.0], "value": 70.5, "unit": "score"}]}]} 13:02:14 INFO - Return code: 0 13:02:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:02:14 ERROR - # TBPL SUCCESS # 13:02:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:02:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:02:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:02:14 INFO - 2016-12-22 13:02:14.965 screenresolution[1656:10481] starting screenresolution argv=screenresolution get 13:02:14 INFO - 2016-12-22 13:02:14.968 screenresolution[1656:10481] Display 0: 1600x1200x32@60 13:02:14 INFO - 2016-12-22 13:02:14.974 screenresolution[1657:10484] starting screenresolution argv=screenresolution list 13:02:14 INFO - Available Modes on Display 0 13:02:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:02:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:02:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:02:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:02:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:02:15 INFO - Intel Iris: 13:02:15 INFO - Chipset Model: Intel Iris 13:02:15 INFO - Type: GPU 13:02:15 INFO - Bus: Built-In 13:02:15 INFO - VRAM (Dynamic, Max): 1536 MB 13:02:15 INFO - Vendor: Intel (0x8086) 13:02:15 INFO - Device ID: 0x0a2e 13:02:15 INFO - Revision ID: 0x0009 13:02:15 INFO - Displays: 13:02:15 INFO - Display: 13:02:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:02:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:02:15 INFO - Display Serial Number: 1600x1200 60 13:02:15 INFO - Main Display: Yes 13:02:15 INFO - Mirror: Off 13:02:15 INFO - Online: Yes 13:02:15 INFO - Rotation: Supported 13:02:15 INFO - Return code: 0 13:02:15 INFO - Running post-action listener: _resource_record_post_action 13:02:15 INFO - [mozharness: 2016-12-22 21:02:15.215074Z] Finished run-tests step (success) 13:02:15 INFO - Running post-run listener: _resource_record_post_run 13:02:15 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 37677568; Write bytes: 551390208; Read time: 469; Write time: 2039 13:02:15 INFO - TinderboxPrint: CPU usage
54.6% 13:02:15 INFO - TinderboxPrint: I/O read bytes / time
37,677,568 / 469 13:02:15 INFO - TinderboxPrint: I/O write bytes / time
551,390,208 / 2,039 13:02:15 INFO - TinderboxPrint: CPU idle
2,181.0 (45.4%) 13:02:15 INFO - TinderboxPrint: CPU system
969.3 (20.2%) 13:02:15 INFO - TinderboxPrint: CPU user
1,651.5 (34.4%) 13:02:15 INFO - TinderboxPrint: Swap in / out
287,608,832 / 0 13:02:15 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249956864; Write bytes: 242302976; Read time: 18058; Write time: 443 13:02:15 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 35445248; Write bytes: 299502592; Read time: 418; Write time: 1558 13:02:15 INFO - Running post-run listener: _upload_blobber_files 13:02:15 INFO - Blob upload gear active. 13:02:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:02:15 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 13:02:15 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'] 13:02:15 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 13:02:16 INFO - (blobuploader) - INFO - Open directory for files ... 13:02:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:02:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:02:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:17 INFO - (blobuploader) - INFO - Done attempting. 13:02:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:02:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:02:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:18 INFO - (blobuploader) - INFO - Done attempting. 13:02:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:02:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:02:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:19 INFO - (blobuploader) - INFO - Done attempting. 13:02:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:02:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:02:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:21 INFO - (blobuploader) - INFO - Done attempting. 13:02:21 INFO - (blobuploader) - INFO - Iteration through files over. 13:02:21 INFO - Return code: 0 13:02:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:02:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:02:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1eb7ad897a1165fcb56695c69fb48cdd21dde5f06311a609b4a7319bc40a05661b2d2ee43351f1b7e6b6b4fec787e50e63570b47a0e79de1eba262b549f9885b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/41e43db9316c1cb863e433e1cc7316904c494e85b951c92c6cb65de5f40f1216a963c804bf610e97dc8d1dbaf4c9a56c276349aa99f71f61113d3220824b471c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c55e8fe382609debe28444bb3d3c08c21c8fe6340e33bcbe7e1f3592fe69113eadb695cdcf00c999e4cd4399544a5261414bc7d8688362f2dec57fc19a40bee3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a27cc393e2026f2898fbcf9fb104c22ec05e1c3cdde58b80b5f4f802772833258708ff5eddbba48205bad840097ad1750fd7dda1321fafa55a06a920f5b492f9"} 13:02:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:02:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:02:21 INFO - Contents: 13:02:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1eb7ad897a1165fcb56695c69fb48cdd21dde5f06311a609b4a7319bc40a05661b2d2ee43351f1b7e6b6b4fec787e50e63570b47a0e79de1eba262b549f9885b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/41e43db9316c1cb863e433e1cc7316904c494e85b951c92c6cb65de5f40f1216a963c804bf610e97dc8d1dbaf4c9a56c276349aa99f71f61113d3220824b471c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c55e8fe382609debe28444bb3d3c08c21c8fe6340e33bcbe7e1f3592fe69113eadb695cdcf00c999e4cd4399544a5261414bc7d8688362f2dec57fc19a40bee3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a27cc393e2026f2898fbcf9fb104c22ec05e1c3cdde58b80b5f4f802772833258708ff5eddbba48205bad840097ad1750fd7dda1321fafa55a06a920f5b492f9"} 13:02:21 INFO - Running post-run listener: copy_logs_to_upload_dir 13:02:21 INFO - Copying logs to upload dir... 13:02:21 INFO - mkdir: /builds/slave/test/build/upload/logs 13:02:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.578408 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-22 13:02:21.499696) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 13:02:21.502825) ========= 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.x84z1M5KCf/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.9RaP8opjeS/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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/1eb7ad897a1165fcb56695c69fb48cdd21dde5f06311a609b4a7319bc40a05661b2d2ee43351f1b7e6b6b4fec787e50e63570b47a0e79de1eba262b549f9885b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/41e43db9316c1cb863e433e1cc7316904c494e85b951c92c6cb65de5f40f1216a963c804bf610e97dc8d1dbaf4c9a56c276349aa99f71f61113d3220824b471c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c55e8fe382609debe28444bb3d3c08c21c8fe6340e33bcbe7e1f3592fe69113eadb695cdcf00c999e4cd4399544a5261414bc7d8688362f2dec57fc19a40bee3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a27cc393e2026f2898fbcf9fb104c22ec05e1c3cdde58b80b5f4f802772833258708ff5eddbba48205bad840097ad1750fd7dda1321fafa55a06a920f5b492f9"} build_url:https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010686 build_url: 'https://queue.taskcluster.net/v1/task/CDU5zIGkRJGiLdV3EIVn4w/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/1eb7ad897a1165fcb56695c69fb48cdd21dde5f06311a609b4a7319bc40a05661b2d2ee43351f1b7e6b6b4fec787e50e63570b47a0e79de1eba262b549f9885b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/41e43db9316c1cb863e433e1cc7316904c494e85b951c92c6cb65de5f40f1216a963c804bf610e97dc8d1dbaf4c9a56c276349aa99f71f61113d3220824b471c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c55e8fe382609debe28444bb3d3c08c21c8fe6340e33bcbe7e1f3592fe69113eadb695cdcf00c999e4cd4399544a5261414bc7d8688362f2dec57fc19a40bee3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a27cc393e2026f2898fbcf9fb104c22ec05e1c3cdde58b80b5f4f802772833258708ff5eddbba48205bad840097ad1750fd7dda1321fafa55a06a920f5b492f9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 13:02:21.538378) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 13:02:21.538749) ========= 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.x84z1M5KCf/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.9RaP8opjeS/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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.005113 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 13:02:21.587604) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 13:02:21.588009) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 13:02:21.588365) ========= ========= Total master_lag: 0.18 =========