builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0033 starttime: 1489193593.65 results: success (0) buildid: 20170310152022 builduid: d0415ab1d0f841d6a11ef93363150380 revision: e5eb0121a580e4b8acf53886861363f669a864ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:13.646339) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:13.646758) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:13.647029) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:13.672088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:13.672383) ========= 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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-10 16:53:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 37.2M=0s 2017-03-10 16:53:13 (37.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.161093 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:13.856989) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:13.857307) ========= 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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.020949 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:13.906519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-03-10 16:53:13.906883) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e5eb0121a580e4b8acf53886861363f669a864ad --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e5eb0121a580e4b8acf53886861363f669a864ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OyI4WBLaMO/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.Caz5Iigc5D/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-10 16:53:14,120 truncating revision to first 12 chars 2017-03-10 16:53:14,120 Setting DEBUG logging. 2017-03-10 16:53:14,120 attempt 1/10 2017-03-10 16:53:14,120 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e5eb0121a580?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-10 16:53:17,954 unpacking tar archive at: mozilla-beta-e5eb0121a580/testing/mozharness/ program finished with exit code 0 elapsedTime=4.090632 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-03-10 16:53:18.021247) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:18.021563) ========= script_repo_revision: e5eb0121a580e4b8acf53886861363f669a864ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:18.022001) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:18.022262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-10 16:53:18.042395) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-10 16:53:18.042863) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OyI4WBLaMO/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.Caz5Iigc5D/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:53:18 INFO - MultiFileLogger online at 20170310 16:53:18 in /builds/slave/test 16:53:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:53:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:53:18 INFO - {'append_to_log': False, 16:53:18 INFO - 'base_work_dir': '/builds/slave/test', 16:53:18 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:53:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:53:18 INFO - 'branch': 'Mozilla-Beta', 16:53:18 INFO - 'buildbot_json_path': 'buildprops.json', 16:53:18 INFO - 'config_files': ('talos/mac_config.py',), 16:53:18 INFO - 'default_actions': ('clobber', 16:53:18 INFO - 'read-buildbot-config', 16:53:18 INFO - 'download-and-extract', 16:53:18 INFO - 'populate-webroot', 16:53:18 INFO - 'create-virtualenv', 16:53:18 INFO - 'install', 16:53:18 INFO - 'run-tests'), 16:53:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:53:18 INFO - 'download_minidump_stackwalk': True, 16:53:18 INFO - 'download_symbols': 'ondemand', 16:53:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:53:18 INFO - 'tooltool.py': '/tools/tooltool.py', 16:53:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:53:18 INFO - '/tools/misc-python/virtualenv.py')}, 16:53:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:53:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:53:18 INFO - 'installer_path': 'installer.exe', 16:53:18 INFO - 'log_level': 'info', 16:53:18 INFO - 'log_name': 'talos', 16:53:18 INFO - 'log_to_console': True, 16:53:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:53:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:53:18 INFO - 'opt_config_files': (), 16:53:18 INFO - 'pip_index': False, 16:53:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:53:18 INFO - 'cmd': ('bash', 16:53:18 INFO - '-c', 16:53:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:53:18 INFO - 'enabled': True, 16:53:18 INFO - 'halt_on_failure': False, 16:53:18 INFO - 'name': 'check_screen_resolution'},), 16:53:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:53:18 INFO - 'cmd': ('bash', 16:53:18 INFO - '-c', 16:53:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:53:18 INFO - 'enabled': True, 16:53:18 INFO - 'halt_on_failure': False, 16:53:18 INFO - 'name': 'check_screen_resolution'},), 16:53:18 INFO - 'run_cmd_checks_enabled': True, 16:53:18 INFO - 'sps_profile': False, 16:53:18 INFO - 'sps_profile_interval': 0, 16:53:18 INFO - 'suite': 'dromaeojs-e10s', 16:53:18 INFO - 'system_bits': '32', 16:53:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:53:18 INFO - 'title': 't-yosemite-r7-0033', 16:53:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:53:18 INFO - 'use_talos_json': True, 16:53:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:53:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:53:18 INFO - 'work_dir': 'build'} 16:53:18 INFO - [mozharness: 2017-03-11 00:53:18.386729Z] Running clobber step. 16:53:18 INFO - Running pre-action listener: _resource_record_pre_action 16:53:18 INFO - Running main action method: clobber 16:53:18 INFO - rmtree: /builds/slave/test/build 16:53:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:53:20 INFO - Running post-action listener: _resource_record_post_action 16:53:20 INFO - [mozharness: 2017-03-11 00:53:20.110947Z] Finished clobber step (success) 16:53:20 INFO - [mozharness: 2017-03-11 00:53:20.111117Z] Running read-buildbot-config step. 16:53:20 INFO - Running pre-action listener: _resource_record_pre_action 16:53:20 INFO - Running main action method: read_buildbot_config 16:53:20 INFO - Using buildbot properties: 16:53:20 INFO - { 16:53:20 INFO - "project": "", 16:53:20 INFO - "product": "firefox", 16:53:20 INFO - "script_repo_revision": "production", 16:53:20 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 16:53:20 INFO - "repository": "", 16:53:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 16:53:20 INFO - "buildid": "20170310152022", 16:53:20 INFO - "pgo_build": "False", 16:53:20 INFO - "basedir": "/builds/slave/test", 16:53:20 INFO - "buildnumber": 19, 16:53:20 INFO - "slavename": "t-yosemite-r7-0033", 16:53:20 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:53:20 INFO - "platform": "yosemite_r7", 16:53:20 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:53:20 INFO - "branch": "mozilla-beta", 16:53:20 INFO - "repo_path": "releases/mozilla-beta", 16:53:20 INFO - "revision": "e5eb0121a580e4b8acf53886861363f669a864ad", 16:53:20 INFO - "stage_platform": "macosx64", 16:53:20 INFO - "builduid": "d0415ab1d0f841d6a11ef93363150380", 16:53:20 INFO - "slavebuilddir": "test" 16:53:20 INFO - } 16:53:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 16:53:20 INFO - Running post-action listener: _resource_record_post_action 16:53:20 INFO - [mozharness: 2017-03-11 00:53:20.114439Z] Finished read-buildbot-config step (success) 16:53:20 INFO - [mozharness: 2017-03-11 00:53:20.114549Z] Running download-and-extract step. 16:53:20 INFO - Running pre-action listener: _resource_record_pre_action 16:53:20 INFO - Running main action method: download_and_extract 16:53:20 INFO - mkdir: /builds/slave/test/build/tests 16:53: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')]} 16:53:20 INFO - trying https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 16:53:20 INFO - Downloading https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 16:53:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 16:53:21 INFO - Downloaded 1165 bytes. 16:53:21 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 16:53:21 INFO - Using the following test package requirements: 16:53:21 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 16:53:21 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:53:21 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 16:53:21 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:53:21 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 16:53:21 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:53:21 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:53:21 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 16:53:21 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 16:53:21 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:53:21 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 16:53:21 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:53:21 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 16:53:21 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:53:21 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 16:53:21 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:53:21 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 16:53:21 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 16:53:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 16:53:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 16:53:21 INFO - Fetch https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 16:53:21 INFO - Expected file size: 16104061 16:53:21 INFO - Obtained file size: 16104061 16:53:28 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:53:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 16:53:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 16:53:28 INFO - Fetch https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 16:53:29 INFO - Expected file size: 16104061 16:53:29 INFO - Obtained file size: 16104061 16:53:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 16:53:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 16:53:36 INFO - Fetch https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 16:53:37 INFO - Expected file size: 14403650 16:53:37 INFO - Obtained file size: 14403650 16:53:37 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')]} 16:53:37 INFO - trying https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:53:37 INFO - Downloading https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 16:53:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 16:53:39 INFO - Downloaded 58694561 bytes. 16:53:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:53:39 INFO - mkdir: /builds/slave/test/properties 16:53:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:53:39 INFO - Writing to file /builds/slave/test/properties/build_url 16:53:39 INFO - Contents: 16:53:39 INFO - build_url:https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:53:40 INFO - Running post-action listener: _resource_record_post_action 16:53:40 INFO - Running post-action listener: set_extra_try_arguments 16:53:40 INFO - [mozharness: 2017-03-11 00:53:40.124936Z] Finished download-and-extract step (success) 16:53:40 INFO - [mozharness: 2017-03-11 00:53:40.125046Z] Running populate-webroot step. 16:53:40 INFO - Running pre-action listener: _resource_record_pre_action 16:53:40 INFO - Running main action method: populate_webroot 16:53:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:53:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:53:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:53:40 INFO - '--tppagecycles', 16:53:40 INFO - '7'], 16:53:40 INFO - 'tests': ['tsvgm']}}, 16:53:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:53:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:53:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:53:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:53:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:53:40 INFO - 'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:53:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:53:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:53:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:53:40 INFO - 'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['damp', 'tps']}, 16:53:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:53:40 INFO - 'tests': ['damp', 'tps']}, 16:53:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['dromaeo_dom']}, 16:53:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:53:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['basic_compositor_video']}, 16:53:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:53:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['a11yr', 16:53:40 INFO - 'ts_paint', 16:53:40 INFO - 'tpaint', 16:53:40 INFO - 'sessionrestore', 16:53:40 INFO - 'sessionrestore_no_auto_restore', 16:53:40 INFO - 'tabpaint']}, 16:53:40 INFO - 'other-e10s': {'tests': ['a11yr', 16:53:40 INFO - 'ts_paint', 16:53:40 INFO - 'tpaint', 16:53:40 INFO - 'sessionrestore', 16:53:40 INFO - 'sessionrestore_no_auto_restore', 16:53:40 INFO - 'tabpaint']}, 16:53:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:53:40 INFO - 'ts_paint', 16:53:40 INFO - 'tpaint', 16:53:40 INFO - 'sessionrestore', 16:53:40 INFO - 'sessionrestore_no_auto_restore', 16:53:40 INFO - 'tabpaint']}, 16:53:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:53:40 INFO - 'ts_paint', 16:53:40 INFO - 'tpaint', 16:53:40 INFO - 'sessionrestore', 16:53:40 INFO - 'sessionrestore_no_auto_restore', 16:53:40 INFO - 'tabpaint']}, 16:53:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['a11yr', 16:53:40 INFO - 'ts_paint', 16:53:40 INFO - 'tpaint', 16:53:40 INFO - 'sessionrestore', 16:53:40 INFO - 'sessionrestore_no_auto_restore', 16:53:40 INFO - 'tabpaint']}, 16:53:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['a11yr', 16:53:40 INFO - 'ts_paint', 16:53:40 INFO - 'tpaint', 16:53:40 INFO - 'sessionrestore', 16:53:40 INFO - 'sessionrestore_no_auto_restore', 16:53:40 INFO - 'tabpaint']}, 16:53:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['tsvgx', 16:53:40 INFO - 'tsvgr_opacity', 16:53:40 INFO - 'tart', 16:53:40 INFO - 'tscrollx', 16:53:40 INFO - 'cart', 16:53:40 INFO - 'tsvg_static']}, 16:53:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:53:40 INFO - 'tsvgr_opacity', 16:53:40 INFO - 'tart', 16:53:40 INFO - 'tscrollx', 16:53:40 INFO - 'cart', 16:53:40 INFO - 'tsvg_static']}, 16:53:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:53:40 INFO - 'talos_options': ['--disable-e10s'], 16:53:40 INFO - 'tests': ['tp5o']}, 16:53:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:53:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:53:40 INFO - 'talos_options': ['--disable-e10s', 16:53:40 INFO - '--xperf_path', 16:53:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:53:40 INFO - 'tests': ['tp5n']}, 16:53:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:53:40 INFO - 'talos_options': ['--xperf_path', 16:53:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:53:40 INFO - 'tests': ['tp5n']}}, 16:53:40 INFO - 'talos.zip': {'path': '', 16:53:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:53:40 INFO - Running post-action listener: _resource_record_post_action 16:53:40 INFO - [mozharness: 2017-03-11 00:53:40.143285Z] Finished populate-webroot step (success) 16:53:40 INFO - [mozharness: 2017-03-11 00:53:40.143397Z] Running create-virtualenv step. 16:53:40 INFO - Running pre-action listener: _resource_record_pre_action 16:53:40 INFO - Running main action method: create_virtualenv 16:53:40 INFO - Creating virtualenv /builds/slave/test/build/venv 16:53: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 16:53:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:53:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:53:40 INFO - Using real prefix '/tools/python27' 16:53:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:53:40 INFO - Installing distribute.............................................................................................................................................................................................done. 16:53:43 INFO - Installing pip.................done. 16:53:43 INFO - Return code: 0 16:53:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:53:44 INFO - Reading from file tmpfile_stdout 16:53:44 INFO - Output received: 16:53: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) 16:53:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:53: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')]} 16:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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')]} 16:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:53: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 16:53: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 16:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:44 INFO - 'HOME': '/Users/cltbld', 16:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:44 INFO - 'LOGNAME': 'cltbld', 16:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:44 INFO - 'MOZ_NO_REMOTE': '1', 16:53:44 INFO - 'NO_EM_RESTART': '1', 16:53:44 INFO - 'PAGER': '/bin/cat', 16:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:44 INFO - 'PWD': '/builds/slave/test', 16:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:44 INFO - 'SHELL': '/bin/bash', 16:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:53:44 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:44 INFO - 'USER': 'cltbld', 16:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:44 INFO - 'XPC_FLAGS': '0x0', 16:53:44 INFO - 'XPC_SERVICE_NAME': '0', 16:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:44 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 16:53:44 INFO - Cleaning up... 16:53:44 INFO - Return code: 0 16:53:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:53: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')]} 16:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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')]} 16:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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', '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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:53: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:53: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 psutil>=3.1.1 16:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:44 INFO - 'HOME': '/Users/cltbld', 16:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:44 INFO - 'LOGNAME': 'cltbld', 16:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:44 INFO - 'MOZ_NO_REMOTE': '1', 16:53:44 INFO - 'NO_EM_RESTART': '1', 16:53:44 INFO - 'PAGER': '/bin/cat', 16:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:44 INFO - 'PWD': '/builds/slave/test', 16:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:44 INFO - 'SHELL': '/bin/bash', 16:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:53:44 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:44 INFO - 'USER': 'cltbld', 16:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:44 INFO - 'XPC_FLAGS': '0x0', 16:53:44 INFO - 'XPC_SERVICE_NAME': '0', 16:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:44 INFO - Downloading/unpacking psutil>=3.1.1 16:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:53:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:53:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:53:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:53:48 INFO - Installing collected packages: psutil 16:53:48 INFO - Running setup.py install for psutil 16:53:48 INFO - building 'psutil._psutil_osx' extension 16:53: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 16:53: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:53: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:53:48 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 16:53:48 INFO - building 'psutil._psutil_posix' extension 16:53:48 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 16:53:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:53:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:53:48 INFO - ^ 16:53:48 INFO - 1 warning generated. 16:53:48 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 16:53:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:53:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:53:49 INFO - Successfully installed psutil 16:53:49 INFO - Cleaning up... 16:53:49 INFO - Return code: 0 16:53:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:53:49 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')]} 16:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:49 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')]} 16:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:49 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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:53:49 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 16:53:49 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 16:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:49 INFO - 'HOME': '/Users/cltbld', 16:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:49 INFO - 'LOGNAME': 'cltbld', 16:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:49 INFO - 'MOZ_NO_REMOTE': '1', 16:53:49 INFO - 'NO_EM_RESTART': '1', 16:53:49 INFO - 'PAGER': '/bin/cat', 16:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:49 INFO - 'PWD': '/builds/slave/test', 16:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:49 INFO - 'SHELL': '/bin/bash', 16:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:53:49 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:49 INFO - 'USER': 'cltbld', 16:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:49 INFO - 'XPC_FLAGS': '0x0', 16:53:49 INFO - 'XPC_SERVICE_NAME': '0', 16:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:53:49 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 16:53:49 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 16:53:49 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 16:53:49 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 16:53:49 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 16:53:49 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 16:53:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:53:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:53:52 INFO - Installing collected packages: mozsystemmonitor 16:53:52 INFO - Running setup.py install for mozsystemmonitor 16:53:52 INFO - Successfully installed mozsystemmonitor 16:53:52 INFO - Cleaning up... 16:53:52 INFO - Return code: 0 16:53:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:53:52 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')]} 16:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:52 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')]} 16:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:52 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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:53:52 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 16:53:52 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 16:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:52 INFO - 'HOME': '/Users/cltbld', 16:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:52 INFO - 'LOGNAME': 'cltbld', 16:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:52 INFO - 'MOZ_NO_REMOTE': '1', 16:53:52 INFO - 'NO_EM_RESTART': '1', 16:53:52 INFO - 'PAGER': '/bin/cat', 16:53:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:52 INFO - 'PWD': '/builds/slave/test', 16:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:52 INFO - 'SHELL': '/bin/bash', 16:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:53:52 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:52 INFO - 'USER': 'cltbld', 16:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:52 INFO - 'XPC_FLAGS': '0x0', 16:53:52 INFO - 'XPC_SERVICE_NAME': '0', 16:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:52 INFO - Downloading/unpacking jsonschema==2.5.1 16:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:53:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:53:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:53: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 16:53: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 16:53: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 16:53: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 16:53: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 16:53: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 16:53:55 INFO - Downloading functools32-3.2.3-2.tar.gz 16:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:53:55 INFO - warning: no files found matching '*.txt' 16:53:55 INFO - no previously-included directories found matching 'build' 16:53:55 INFO - no previously-included directories found matching 'dist' 16:53:55 INFO - no previously-included directories found matching '.git*' 16:53:55 INFO - Installing collected packages: jsonschema, functools32 16:53:55 INFO - Running setup.py install for functools32 16:53:55 INFO - warning: no files found matching '*.txt' 16:53:55 INFO - no previously-included directories found matching 'build' 16:53:55 INFO - no previously-included directories found matching 'dist' 16:53:55 INFO - no previously-included directories found matching '.git*' 16:53:55 INFO - Successfully installed jsonschema functools32 16:53:55 INFO - Cleaning up... 16:53:55 INFO - Return code: 0 16:53:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:53:55 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')]} 16:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:55 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')]} 16:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:55 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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:53:55 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 16:53:55 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 16:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:55 INFO - 'HOME': '/Users/cltbld', 16:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:55 INFO - 'LOGNAME': 'cltbld', 16:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:55 INFO - 'MOZ_NO_REMOTE': '1', 16:53:55 INFO - 'NO_EM_RESTART': '1', 16:53:55 INFO - 'PAGER': '/bin/cat', 16:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:55 INFO - 'PWD': '/builds/slave/test', 16:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:55 INFO - 'SHELL': '/bin/bash', 16:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:53:55 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:55 INFO - 'USER': 'cltbld', 16:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:55 INFO - 'XPC_FLAGS': '0x0', 16:53:55 INFO - 'XPC_SERVICE_NAME': '0', 16:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:53:56 INFO - Cleaning up... 16:53:56 INFO - Return code: 0 16:53:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:53: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')]} 16:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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')]} 16:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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', '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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:53: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:53: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 blobuploader==1.2.4 16:53:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:53:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:56 INFO - 'HOME': '/Users/cltbld', 16:53:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:56 INFO - 'LOGNAME': 'cltbld', 16:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:56 INFO - 'MOZ_NO_REMOTE': '1', 16:53:56 INFO - 'NO_EM_RESTART': '1', 16:53:56 INFO - 'PAGER': '/bin/cat', 16:53:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:56 INFO - 'PWD': '/builds/slave/test', 16:53:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:56 INFO - 'SHELL': '/bin/bash', 16:53:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:53:56 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:53:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:56 INFO - 'USER': 'cltbld', 16:53:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:56 INFO - 'XPC_FLAGS': '0x0', 16:53:56 INFO - 'XPC_SERVICE_NAME': '0', 16:53:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:56 INFO - Downloading/unpacking blobuploader==1.2.4 16:53:56 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 16:53:56 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 16:53:56 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 16:53:56 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 16:53:56 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 16:53:56 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 16:53:59 INFO - Downloading blobuploader-1.2.4.tar.gz 16:53:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:53:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:53:59 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 16:53:59 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 16:53:59 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 16:53:59 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 16:53:59 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 16:53:59 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 16:53:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:53:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:53:59 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 16:53:59 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 16:53:59 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 16:53:59 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 16:53:59 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 16:53:59 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 16:54:00 INFO - Downloading docopt-0.6.1.tar.gz 16:54:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:54:00 INFO - Installing collected packages: blobuploader, requests, docopt 16:54:00 INFO - Running setup.py install for blobuploader 16:54:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:54:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:54:00 INFO - Running setup.py install for requests 16:54:01 INFO - Running setup.py install for docopt 16:54:01 INFO - Successfully installed blobuploader requests docopt 16:54:01 INFO - Cleaning up... 16:54:01 INFO - Return code: 0 16:54:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:01 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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:54:01 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 16:54:01 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 16:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:01 INFO - 'HOME': '/Users/cltbld', 16:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:01 INFO - 'LOGNAME': 'cltbld', 16:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:01 INFO - 'MOZ_NO_REMOTE': '1', 16:54:01 INFO - 'NO_EM_RESTART': '1', 16:54:01 INFO - 'PAGER': '/bin/cat', 16:54:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:01 INFO - 'PWD': '/builds/slave/test', 16:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:01 INFO - 'SHELL': '/bin/bash', 16:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:54:01 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:01 INFO - 'USER': 'cltbld', 16:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:01 INFO - 'XPC_FLAGS': '0x0', 16:54:01 INFO - 'XPC_SERVICE_NAME': '0', 16:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:54:01 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kBU1Ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:54:01 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-svvxuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:54:01 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-OQW6t9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:54:01 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-IhO8fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:54:01 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-4Ke3tB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-S5qMVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-byjqLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-17gRBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-KAn45P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-vCwX_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-oVzIdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-t4Nio4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GKiGM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:54:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-VU4OQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:54:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-5CdsUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:54:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-m0zgUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:54:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GkOAco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:54:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:54:03 INFO - Running setup.py install for manifestparser 16:54:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:54:03 INFO - Running setup.py install for mozcrash 16:54:03 INFO - Running setup.py install for mozdebug 16:54:03 INFO - Running setup.py install for mozdevice 16:54:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:54:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:54:03 INFO - Running setup.py install for mozfile 16:54:04 INFO - Running setup.py install for mozhttpd 16:54:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:54:04 INFO - Running setup.py install for mozinfo 16:54:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:54:04 INFO - Running setup.py install for mozInstall 16:54:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:54:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:54:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:54:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:54:04 INFO - Running setup.py install for mozleak 16:54:04 INFO - Running setup.py install for mozlog 16:54:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:54:04 INFO - Running setup.py install for moznetwork 16:54:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:54:05 INFO - Running setup.py install for mozprocess 16:54:05 INFO - Running setup.py install for mozprofile 16:54:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:54:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:54:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:54:05 INFO - Running setup.py install for mozrunner 16:54:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:54:05 INFO - Running setup.py install for mozscreenshot 16:54:05 INFO - Running setup.py install for moztest 16:54:05 INFO - Running setup.py install for mozversion 16:54:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:54:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:54:05 INFO - Cleaning up... 16:54:05 INFO - Return code: 0 16:54:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:54:05 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')]} 16:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:05 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')]} 16:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:05 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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:54:05 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 16:54:05 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 16:54:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:54:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:05 INFO - 'HOME': '/Users/cltbld', 16:54:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:05 INFO - 'LOGNAME': 'cltbld', 16:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:05 INFO - 'MOZ_NO_REMOTE': '1', 16:54:05 INFO - 'NO_EM_RESTART': '1', 16:54:05 INFO - 'PAGER': '/bin/cat', 16:54:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:05 INFO - 'PWD': '/builds/slave/test', 16:54:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:05 INFO - 'SHELL': '/bin/bash', 16:54:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:54:05 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:54:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:05 INFO - 'USER': 'cltbld', 16:54:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:05 INFO - 'XPC_FLAGS': '0x0', 16:54:05 INFO - 'XPC_SERVICE_NAME': '0', 16:54:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:54:06 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_PnhQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:54:06 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)) 16:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:54:06 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-wMzMoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:54:06 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)) 16:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:54:06 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-O11CZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:54:06 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)) 16:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:54:06 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-o9AwhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:54:06 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)) 16:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:54:06 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-NMNtIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:54:06 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)) 16:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:54:06 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-QIlMam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:54:06 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)) 16:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:54:06 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_usCkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:54:06 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)) 16:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:54:06 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-j7rFsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:54: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)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-PdUMaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:54:07 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)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-WN8eMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:54:07 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)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-utxXc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:54:07 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)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-cfaZZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ghAM0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:54:07 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)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-RKF5qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:54:07 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)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-H2Z6yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:54:07 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)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-NqIKy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:54:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:54:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-wO2F_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:54: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)) 16:54: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)) 16:54: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)) 16:54: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)) 16:54: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)) 16:54: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)) 16:54:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:54: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 16:54: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 16:54: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 16:54: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 16:54: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 16:54: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 16:54:10 INFO - Downloading blessings-1.6.tar.gz 16:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:54:10 INFO - Installing collected packages: blessings 16:54:10 INFO - Running setup.py install for blessings 16:54:10 INFO - Successfully installed blessings 16:54:10 INFO - Cleaning up... 16:54:11 INFO - Return code: 0 16:54:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:54:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:54:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:54:11 INFO - Reading from file tmpfile_stdout 16:54:11 INFO - Current package versions: 16:54:11 INFO - blessings == 1.6 16:54:11 INFO - blobuploader == 1.2.4 16:54:11 INFO - docopt == 0.6.1 16:54:11 INFO - functools32 == 3.2.3-2 16:54:11 INFO - jsonschema == 2.5.1 16:54:11 INFO - manifestparser == 1.1 16:54:11 INFO - mozInstall == 1.12 16:54:11 INFO - mozcrash == 1.0 16:54:11 INFO - mozdebug == 0.1 16:54:11 INFO - mozdevice == 0.49 16:54:11 INFO - mozfile == 1.2 16:54:11 INFO - mozhttpd == 0.7 16:54:11 INFO - mozinfo == 0.9 16:54:11 INFO - mozleak == 0.1 16:54:11 INFO - mozlog == 3.4 16:54:11 INFO - moznetwork == 0.27 16:54:11 INFO - mozprocess == 0.24 16:54:11 INFO - mozprofile == 0.28 16:54:11 INFO - mozrunner == 6.13 16:54:11 INFO - mozscreenshot == 0.1 16:54:11 INFO - mozsystemmonitor == 0.3 16:54:11 INFO - moztest == 0.8 16:54:11 INFO - mozversion == 1.4 16:54:11 INFO - psutil == 3.1.1 16:54:11 INFO - requests == 1.2.3 16:54:11 INFO - wsgiref == 0.1.2 16:54:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:54:11 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')]} 16:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:11 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')]} 16:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:12 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 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:54:12 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 16:54:12 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 16:54:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:54:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:12 INFO - 'HOME': '/Users/cltbld', 16:54:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:12 INFO - 'LOGNAME': 'cltbld', 16:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:12 INFO - 'MOZ_NO_REMOTE': '1', 16:54:12 INFO - 'NO_EM_RESTART': '1', 16:54:12 INFO - 'PAGER': '/bin/cat', 16:54:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:12 INFO - 'PWD': '/builds/slave/test', 16:54:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:12 INFO - 'SHELL': '/bin/bash', 16:54:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:54:12 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:54:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:12 INFO - 'USER': 'cltbld', 16:54:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:12 INFO - 'XPC_FLAGS': '0x0', 16:54:12 INFO - 'XPC_SERVICE_NAME': '0', 16:54:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 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)) 16:54:12 INFO - Cleaning up... 16:54:12 INFO - Return code: 0 16:54:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:54: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')]} 16:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54: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')]} 16:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1059fbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059fe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8208d7e860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, '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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 16:54:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:54:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:54:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:12 INFO - 'HOME': '/Users/cltbld', 16:54:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:12 INFO - 'LOGNAME': 'cltbld', 16:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:12 INFO - 'MOZ_NO_REMOTE': '1', 16:54:12 INFO - 'NO_EM_RESTART': '1', 16:54:12 INFO - 'PAGER': '/bin/cat', 16:54:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:12 INFO - 'PWD': '/builds/slave/test', 16:54:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:12 INFO - 'SHELL': '/bin/bash', 16:54:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:54:12 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:54:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:12 INFO - 'USER': 'cltbld', 16:54:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:12 INFO - 'XPC_FLAGS': '0x0', 16:54:12 INFO - 'XPC_SERVICE_NAME': '0', 16:54:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:54:13 INFO - Cleaning up... 16:54:13 INFO - Return code: 0 16:54:13 INFO - Running post-action listener: _resource_record_post_action 16:54:13 INFO - Running post-action listener: _start_resource_monitoring 16:54:13 INFO - Starting resource monitoring. 16:54:13 INFO - [mozharness: 2017-03-11 00:54:13.088530Z] Finished create-virtualenv step (success) 16:54:13 INFO - [mozharness: 2017-03-11 00:54:13.089381Z] Running install step. 16:54:13 INFO - Running pre-action listener: _resource_record_pre_action 16:54:13 INFO - Running main action method: install 16:54:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:54:13 INFO - Reading from file tmpfile_stdout 16:54:13 INFO - Detecting whether we're running mozinstall >=1.0... 16:54:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:54:13 INFO - Reading from file tmpfile_stdout 16:54:13 INFO - Output received: 16:54:13 INFO - Usage: mozinstall [options] installer 16:54:13 INFO - Options: 16:54:13 INFO - -h, --help show this help message and exit 16:54:13 INFO - -d DEST, --destination=DEST 16:54:13 INFO - Directory to install application into. [default: 16:54:13 INFO - "/builds/slave/test"] 16:54:13 INFO - --app=APP Application being installed. [default: firefox] 16:54:13 INFO - mkdir: /builds/slave/test/build/application 16:54:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:54:28 INFO - Reading from file tmpfile_stdout 16:54:28 INFO - Output received: 16:54:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:54:28 INFO - Running post-action listener: _resource_record_post_action 16:54:28 INFO - [mozharness: 2017-03-11 00:54:28.101089Z] Finished install step (success) 16:54:28 INFO - [mozharness: 2017-03-11 00:54:28.101264Z] Running run-tests step. 16:54:28 INFO - Running pre-action listener: _resource_record_pre_action 16:54:28 INFO - Running main action method: run_tests 16:54:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:54:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:54:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:54:28 INFO - 2017-03-10 16:54:28.115 screenresolution[2726:46244] starting screenresolution argv=screenresolution get 16:54:28 INFO - 2017-03-10 16:54:28.118 screenresolution[2726:46244] Display 0: 1600x1200x32@60 16:54:28 INFO - 2017-03-10 16:54:28.124 screenresolution[2727:46246] starting screenresolution argv=screenresolution list 16:54:28 INFO - Available Modes on Display 0 16:54:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:54:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:54:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:54:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:54:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:54:28 INFO - Intel Iris: 16:54:28 INFO - Chipset Model: Intel Iris 16:54:28 INFO - Type: GPU 16:54:28 INFO - Bus: Built-In 16:54:28 INFO - VRAM (Dynamic, Max): 1536 MB 16:54:28 INFO - Vendor: Intel (0x8086) 16:54:28 INFO - Device ID: 0x0a2e 16:54:28 INFO - Revision ID: 0x0009 16:54:28 INFO - Displays: 16:54:28 INFO - Display: 16:54:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:54:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:54:28 INFO - Display Serial Number: 1600x1200 60 16:54:28 INFO - Main Display: Yes 16:54:28 INFO - Mirror: Off 16:54:28 INFO - Online: Yes 16:54:28 INFO - Rotation: Supported 16:54:28 INFO - Return code: 0 16:54:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:54:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:54:28 INFO - Python 2.7.3 16:54:28 INFO - Return code: 0 16:54:28 INFO - grabbing minidump binary from tooltool 16:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:54:28 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 0x1057d9d30>, '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 0x105700db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1057cebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:54:28 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 16:54:28 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 16:54:28 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 16:54:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:54:28 INFO - Return code: 0 16:54:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:54:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:54:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:54:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:54:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:54:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 16:54:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 16:54:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OyI4WBLaMO/Render', 16:54:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:28 INFO - 'HOME': '/Users/cltbld', 16:54:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:28 INFO - 'LOGNAME': 'cltbld', 16:54:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:54:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:28 INFO - 'MOZ_NO_REMOTE': '1', 16:54:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:54:28 INFO - 'NO_EM_RESTART': '1', 16:54:28 INFO - 'PAGER': '/bin/cat', 16:54:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:28 INFO - 'PWD': '/builds/slave/test', 16:54:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:54:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:28 INFO - 'SHELL': '/bin/bash', 16:54:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Caz5Iigc5D/Listeners', 16:54:28 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 16:54:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:28 INFO - 'USER': 'cltbld', 16:54:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:28 INFO - 'XPC_FLAGS': '0x0', 16:54:28 INFO - 'XPC_SERVICE_NAME': '0', 16:54:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 16:54:28 INFO - mozversion application_buildid: 20170310152022 16:54:28 INFO - mozversion application_changeset: e5eb0121a580e4b8acf53886861363f669a864ad 16:54:28 INFO - mozversion application_display_name: Firefox 16:54:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:54:28 INFO - mozversion application_name: Firefox 16:54:28 INFO - mozversion application_remotingname: firefox 16:54:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:54:28 INFO - mozversion application_vendor: Mozilla 16:54:28 INFO - mozversion application_version: 53.0 16:54:28 INFO - mozversion platform_buildid: 20170310152022 16:54:28 INFO - mozversion platform_changeset: e5eb0121a580e4b8acf53886861363f669a864ad 16:54:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:54:28 INFO - mozversion platform_version: 53.0 16:54:28 INFO - using testdate: 1489193668 16:54:28 INFO - actual date: 1489193668 16:54:28 INFO - starting webserver on 'localhost:51069' 16:54:28 INFO - SUITE-START | Running 2 tests 16:54:28 INFO - TEST-START | dromaeo_css 16:54:28 INFO - operating with platform_type : mac_ 16:54:28 INFO - Initialising browser for dromaeo_css test... 16:54:28 INFO - TEST-INFO | started process 2737 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpKS28h_/profile http://localhost:51069/getInfo.html) 16:54:31 INFO - PROCESS | 2737 | 2017-03-10 16:54:31.570 plugin-container[2740:46406] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 16:54:31 INFO - PROCESS | 2737 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:31 INFO - PROCESS | 2737 | 2017-03-10 16:54:31.571 plugin-container[2740:46406] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 16:54:31 INFO - PROCESS | 2737 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:31 INFO - PROCESS | 2737 | 2017-03-10 16:54:31.571 plugin-container[2740:46406] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:54:31 INFO - PROCESS | 2737 | __metrics Screen width/height:1600/1200 16:54:31 INFO - PROCESS | 2737 | colorDepth:24 16:54:31 INFO - PROCESS | 2737 | Browser inner width/height: 1024/689 16:54:31 INFO - PROCESS | 2737 | __metrics 16:54:31 ERROR - PROCESS | 2737 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 16:54:32 INFO - TEST-INFO | 2737: exit 0 16:54:32 INFO - Browser initialized. 16:54:32 INFO - Running cycle 1/1 for dromaeo_css test... 16:54:32 INFO - TEST-INFO | started process 2741 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpKS28h_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:54:34 INFO - PROCESS | 2741 | 2017-03-10 16:54:34.827 plugin-container[2742:46548] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 16:54:34 INFO - PROCESS | 2741 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:34 INFO - PROCESS | 2741 | 2017-03-10 16:54:34.828 plugin-container[2742:46548] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 16:54:34 INFO - PROCESS | 2741 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:34 INFO - PROCESS | 2741 | 2017-03-10 16:54:34.828 plugin-container[2742:46548] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:54:44 INFO - PROCESS | 2741 | RSS: Main: 172470272 16:54:44 INFO - PROCESS | 2741 | 16:57:45 INFO - PROCESS | 2741 | Cycle 1(1): loaded http://localhost:51069/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51069/tests/dromaeo/cssquery-ext.html) 16:57:47 INFO - PROCESS | 2741 | RSS: Main: 188518400 16:57:47 INFO - PROCESS | 2741 | 17:00:50 INFO - PROCESS | 2741 | Cycle 1(1): loaded http://localhost:51069/tests/dromaeo/cssquery-ext.html (next: http://localhost:51069/tests/dromaeo/cssquery-jquery.html) 17:00:50 INFO - PROCESS | 2741 | RSS: Main: 200077312 17:00:50 INFO - PROCESS | 2741 | 17:03:52 INFO - PROCESS | 2741 | Cycle 1(1): loaded http://localhost:51069/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51069/tests/dromaeo/cssquery-mootools.html) 17:03:53 INFO - PROCESS | 2741 | RSS: Main: 202047488 17:03:53 INFO - PROCESS | 2741 | 17:06:54 INFO - PROCESS | 2741 | Cycle 1(1): loaded http://localhost:51069/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51069/tests/dromaeo/cssquery-prototype.html) 17:06:55 INFO - PROCESS | 2741 | RSS: Main: 205713408 17:06:55 INFO - PROCESS | 2741 | 17:09:58 INFO - PROCESS | 2741 | Cycle 1(1): loaded http://localhost:51069/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51069/tests/dromaeo/cssquery-yui.html) 17:09:59 INFO - PROCESS | 2741 | RSS: Main: 207671296 17:09:59 INFO - PROCESS | 2741 | 17:13:01 INFO - PROCESS | 2741 | Cycle 1(1): loaded http://localhost:51069/tests/dromaeo/cssquery-yui.html (next: http://localhost:51069/tests/dromaeo/cssquery-dojo.html) 17:13:02 INFO - PROCESS | 2741 | RSS: Main: 208158720 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - PROCESS | 2741 | __start_tp_report 17:13:02 INFO - PROCESS | 2741 | _x_x_mozilla_page_load 17:13:02 INFO - PROCESS | 2741 | _x_x_mozilla_page_load_details 17:13:02 INFO - PROCESS | 2741 | |i|pagename|runs| 17:13:02 INFO - PROCESS | 2741 | |0;dojo.html;3291.950620740689;3429.639887811781;3485.1461391958974;3485.668861458162;3492.2840817632386;5688.11834165705;5700.287464066992;5742.569307301958;5781.797637082703;5855.707214639272;5785.363610002896;5828.184054232411;5863.237779088723;5864.560157988147;5872.7944521941745;3997.820098095578;4222.282212023963;4752.12085764133;5205.687658740469;5279.3928698199625;5203.687778733288;5284.207368894658;5308.522232999029;5309.522143007129;5310.681359118446;5102.056119617882;5143.614228932845;5203.297554830087;5204.828913494472;5238.659487133325;5131.666441681279;5146.330976973007;5166.07010738067;5210.791568337262;5236.345456817898;4660.464046634646;4792.586187074821;5156.381234251876;5162.148245539482;5173.430922598511;5532.474414930575;5582.497575218209;5658.321001479808;5713.571482138815;5777.88444231113;6174.96912515439;6242.344553821828;6256.280527739323;6266.997280435108;6306.306306306303;6268.5298602604535;6277.183966084425;6278.340774218687;6288.465480434169;6323.810285691437;5961.195238642771;5978.252718410199;5978.282606087298;5992.191054207669;6036.2756469223095;6147.415995480422;6278.497720182374;6283.874322513524;6304.18045654062;6350.110984462179;6240.469560087444;6267.62394256346;6270.122182894398;6275.466585340206;6283.465905398001;6303.590266632654;6308.589941653778;6310.400511951358;6322.399372059647;6327.936720632827;4968.677035992649;6125.998614523584;6220.844478888064;6227.06594010902;6338.080978258126;6224.533160013018;6269.216347956505;6315.179026726588;6335.65153916539;6345.302016778151;5023.673461225008;5027.924581131286;5049.343585333883;5051.696898186047;5068;4367.978160109179;4438.644908407319;4451.866444006685;4462.085272519126;4474.574915383034;5979.342272350038;5989.730462129215;6012.909806352908;6082.9695851520455;6115.877682446326;6224.559023066442;6276.843078922972;6281.654509001957;6290.150829637941;6299.811005669837;5869.20765696634;5903.173555702205;5995.880082398327;6107.083937409424;6119.020956646915;5460.208269800856;5517.117261238182;5544.750486228129;5571.693556854412;5591.832245032656;5530.419305972976;6445.2265728112925;6562.704678289393;6568.507361947873;6568.967155164193;6177.876442471035;6180.351063138306;6210.378962103753;6239.251289845248;6243.96878015607;6451.967740161269;6467.773627923;6511.641859697762;6588.176477940257;6606.47148228131;4951.950480495149;6256.968715156395;6285.088662143872;6287.245530536365;6297.086922396135;6128.417800308964;6155.538334624832;6250.218722659668;6255.624662520263;6269.62382257066;6118.862843290023;6139.798803208702;6145.3407579954055;6155.1075094112;6295.685215739286;6239.625622462667;6284.654344010941;6293.402126797947;6296.874062518816;6299.370062993664;5139.997700448376;6081.209442772411;6117.84705382369;6129.295131059806;6149.969250153899;6002.819915402545;6058.0610005449225;6072.332043475126;6125.509959203342;6138.109974053648;4671.902103005859;5907.675077870758;6053.667048312385;6129.908051379144;6131.908021379593 17:13:02 INFO - PROCESS | 2741 | |1;ext.html;448.56041079741834;454.7248914406784;466.96497762667803;505.9930737831716;542.8100164942269;41131;43585.34621980669;43710.562894371;45870.770646146724;45988.770056149675;44368;44955.77522112385;45101.54898451015;45154.54845451545;45840.770796146055;9433.339666223334;9502;9703.854442183372;9709.029097090304;9723.562439690195;11528.538858445652;11776.057915366793;11788.941055294754;11818;11832.585859494919;9777.90222097777;11640.592579259684;11783.234089784179;11808.645740627795;11833.881661183364;9989.450580218085;11337.886621133766;11629.883701162964;11712.941435292854;11749.94125029378;11383.373914434702;11423.885761142365;11457.083433325313;11484.253523521027;11583.247088939192;38967.220655587014;39247.8037609813;39297.214055719014;39328.410073848914;39386;3592.497050412944;4092.1815636872448;4115.259253334399;4179.435776170209;4180.874573762792;29211.70788292111;29592.70407295921;29883.40233195324;29946.25134371658;30372.78508859643;3202.1994501374656;3245.545623612696;3338.8998330050135;3346.8509311200187;3360.512725654766;508412;509330;510390;510857;510907.4454627703;60692;60957.695211524544;61171.941402929675;61206.38793612096;61284.38715612876;64418.677906611105;64954;65026.67486662536;65131.67434162894;65189.67405163038;17239.482815515556;17305;17458.301667933396;17514.912425438048;17555.73666395005;12198.146129771008;12503.624891253277;13341.66645833862;13925.234112123728;14311.499097531745;21273.68089478659;22755.88622056879;22757.431064223194;22790.772092279196;22798.31605051851;8955.955220223857;9116.4074335168;9140.771480713036;9158.633654653873;9221.446713197229;895.54327293081;900.2123142250531;902.0888902208816;933.5752232734089;940.4169414962766;30788.384232315228;31223.843880780452;31592.578333975027;32057;33351.83268585588;1054.7626783973699;1055.6252530351614;1088.0207812968233;1102.6195962392962;1116.7598966222301;13844.446222151;14281.78577321341;14876;15084;15632.567265973365;637788;692131;693825;694860;704161;57581.56046098881;57701.13448298279;57917.710411447675;58080;58144.709276454196;1315.81578578999;1378.9244389376302;1472.168224952898;1477.7561702319213;1497.8801695864304;29092.709072909;29821.70178298276;29970.25074373158;30280.242993925327;30362;42017.78991105025;50788.98422031465;51374.972500550524;51546;51571;624717;629495;630060;632717;641021;27343.589846151925;27634.447311053264;28232.29419264535;28412;29053.418931621673;45605;45769.54230457786;46528.53471465242;46852.76573617246;47373.763131184125;37580.624193758806;38376.42435363416;38425.80787096047;39029.609703903734;39608;31206.063818085495;37936;38192;38839.8348049559;39233.41149882697;36697.81651091728;37339.81330093441;37644.81177594094;37852.621473786014;38014.04964875789;33014.83492582522;34463.96608101761;35018.299634007686;35077.94766157019;35225.64774352223;25548.872255638602;25664.486710266065;25713.971441142145;25811.870940645927;26062.478750425264 17:13:02 INFO - PROCESS | 2741 | |2;jquery.html;405.13503669665255;408.5812042656279;414.54400159824195;418.8303736986521;436.11903954012894;20190.798092019093;21043.789562104375;21758.564828703416;22311.442213944618;22363.55272894545;22617.208397706083;22690.54618907621;22800.631962082247;22873.4281642959;23010.53978920425;6006.339302676702;6174.166487524194;6191.752329906798;6244.0321750128805;6306.148669929569;5887.4406931341455;6158;6186.907196392058;6267.43593076623;6299;6007.729652165641;6191.7926004429155;6236.095766113911;6242.843928901769;6256.124142620014;6102.786402475915;6190;6231.937680623227;6239.65681887496;6242.001279795257;5247.291615631879;5406.135018397077;5478.424765399615;5498.1752737089355;5503.257060296849;27721.16836494888;27931;27948.720512794815;28012.879484820583;28096.297592560146;2770.6813716422666;2818.0841226601233;2873.454043731684;2886.1774394297527;2896.0877323643194;15635.921820390939;16587.419440319652;16616.335346586122;16698.58253543659;17268.74096888535;1558.3065535836556;1864.114545590847;1898.1268616436455;1913.9808601913942;1915.9425217243504;112949;135371;140408.29795851058;141390;143226.13547728988;9743.561539730586;11469.598564050386;11960.940195298968;11969.64091077269;12028;15098.924505377403;15390.230488475756;15589.376424942875;15608.219589020504;15630;7594.544327340267;9053.547322633842;9294.90705092954;9315.39449935752;9493.145616894513;7483.32650061497;7538.906858505596;9194.63221471147;9265.073492650681;9344.392614480037;7631.885521717179;7713.807154821062;7755.146933837386;7783;7805.804854878674;1688.7551305060697;1918.3957053528095;1996.780354161041;2025.5138766695886;2028.8376929845576;925.0148591749766;1021.0861279155115;1075.7687097273968;1086.4784903246327;1100.8018556659717;18699.626007479776;23205.53588928246;23641.40896477602;23683.881580592333;23720.288391348287;1109.6670998700358;1117.463617463622;1121.265571050963;1156.988430115705;1168.8129899216085;10846.945765271123;12015.579454719043;12208.206466579644;12305.753884922431;12416.379181040911;630341;772502;785176;787935;804323;161940;163381;164735;164786.3521364771;169824;977.1742877268697;1066.99466502667;1090.8036553420302;1134.5631931706187;1135.8815808517222;20344.491387715425;21601.675974860704;21604.13583456644;21614.783852161905;21646;53132;64903;64986.67506662625;65072;65078.02382964164;86190.56904715436;95551.52224238834;96354;96396;97230.02769972493;6558.967205164135;7185.784426467005;7457.366123879531;7625.466217364731;8538.829223415622;10799.622013229498;11086.390248536409;13184.60446186616;13337.3331333431;13439.596812095262;8536.70121545743;10981;11730.472128754056;11754.941225293818;11826.881731182579;8592.441491303045;11451.942740286246;11555.075593952295;11732.765344693229;12062;10873.510692019036;11823.11326650522;11986.280823150639;12020.459079341626;12086.75826483483;8288.254057134887;8372.706955256537;8487.660493580188;8728.345374097094;8773;11157;11194.776104477702;11333.886661133283;11537.307761534335;11550.364729939934 17:13:02 INFO - PROCESS | 2741 | |3;mootools.html;1425.907316024459;1474.0197768483958;1485.14604102641;1498.2883130513005;1498.6178524476254;7725.806854828632;7991.600419979007;8304.795804608328;8711.607977641006;8734.25758810501;8183.099859015503;8496.745097647065;8526.531040792754;8604.139586041409;8607;2391.1152873436854;2442.3405680466262;2447.951040979179;2455.656208130863;2463.2979600813774;2408.048820715825;2421.309926670899;2437.585610446228;2447.7919376852988;2449.2529778417575;2395.568797616428;2406.759324067588;2426.1387207541334;2431.4407686232175;2432.0393444589376;2378.9643155352683;2382.3924899150693;2383.1420688552107;2401.603735383651;2411.9276421707377;1872.8595355348402;2375.7171127591255;2421.782039616443;2435.5007223519137;2523.079076137215;8540.743777686617;8619.224269815684;8630.352723545759;8666.350023748244;8667.566621668955;5216.295800067019;5369.275147855068;5384.9730751346;5397.7031263280505;5409.918851217235;3021.47124253257;3529.699975502085;3639.7452178347526;3670.394384926484;3696.907577310562;1264.740728150727;1265.316728966357;1306.000909304383;1324.9536266230732;1331.4274861809515;11013.669589912235;11022.44887755609;11038.944805276002;11052.226344155912;11069.280496767684;4295.689814606512;4716;4802.231642937113;4891.559759621651;4923.769057735566;9375.765605859908;9863.802723945624;9869.851952220723;9874.456904870302;9895.604175832887;4481.798319075584;5417.59368047398;5490.258815059916;5491.039068163135;5507.476789704971;4465.37484752135;4589.472210695746;4958.528939750718;5011.223260394645;5035.345405097314;4020.537638171589;4084.611961863618;4124.1133156371525;4159.521655009652;4178.644815190682;1229.7518019210509;1247.9812802807965;1338.3977210255423;1348.440397235156;1358.802973568827;937.4422218779815;954.289054654287;962.5764663548157;972.9367591106698;996.7786424492269;5669;5670.518005969457;5792.391798861143;5891.734871930774;5982.73077711504;588.0679123589159;590.4892268187987;594.8839976204686;979.941663003954;1066.578701412953;3135.419947309755;3949.4668219790533;3954.9011274718364;3992.6007399260425;4071.002604361891;7724.806879827936;8212.301954333829;9979.401235925723;10240.283180177465;10256.58973641046;6964.965175174092;8724.607392667218;9962.501874906226;9981.600735970625;10092.798144037079;7098.935159726082;7127.322904324081;7388.704451821867;7943.801404964806;8115.026196856533;5881.411858814085;5887.4406931341455;5904.645721256738;5975.760969561169;6194.566380353513;9007.414518056305;9484.383515071499;9501.572429240563;9615.2307815376;9615.51922403863;9512.714618561455;9645.758856028655;9654;9664.130228279488;9683.951580242336;6804.523683342119;6990.825229369307;7141.64291785419;7328.083989501312;7508.774736757906;7976.282134607681;8222.095569487472;8231.547264900488;8244.258016778518;8252.09226985019;7184.748533801292;7244.384227340735;7299.05112335393;7391.852162956819;7445.702171913063;7137.357637812622;7216.386607138452;7224.169220539705;7278.053852999076;7345.3021962913435;5319.920201196907;6517.478601711947;6673.232578253563;6683.465322774069;6685.164354455693;5042.420121685906;5075.593952483719;5094.1849304111165;5220.477952204901;5280.1815718563685;4520.4575450946095;5124.180131178993;5154.587632989427;5206.453322401093;5228 17:13:02 INFO - PROCESS | 2741 | |4;prototype.html;294.49200129776125;360.0530604510136;367.4910249304714;373.4696730642488;381.33457117330255;5179.430262671103;5308.336457942757;5471.288732464779;5487.204355368469;5492.862678433036;4546.931796023062;5184.792608295673;5302.045631786277;5332.946670533293;5395.946040539593;3874.6751948830615;4225.915481690365;4260.126674031816;4266.7439953602725;4278.459754488382;3729.9440508392395;3936.1734035852364;4256.616904478597;4270.572942705736;4280.272353699878;3278.459054256045;3388.932221355584;3612.8916132516065;4163.83344666213;4170.666346692273;3702.648818000562;3785.71607129465;3993.2463484324644;4192.4759405074365;4207.158568286324;3349.748768842322;3390.8982730518123;3780.2628487444686;3781;3785.8674946376746;4101.4668093148;4487.865364039084;5067.923981140286;5104.387473503166;5252.553532949666;4904.877378065541;4911.631627627942;4915.926261106087;4921.80312787488;4942.604591632661;4727;4968.59651138971;4979;5689.317281926154;5752.568557358173;3955.1496428267774;4101.384792281152;4126.277901367249;4176.310908700061;4179.791010449496;4734.905301893943;5537.36320323164;5823.951688696033;6056.4851987581105;6059.787907423263;4173.895652608649;4251.80866860992;4253.723507971972;4254.893627659271;4261.952703816786;3699.8335074921692;4174.645155161785;4257.765822879772;4310.612044915973;4322.740635561876;3827.9043023924623;4110.321796903478;4552.954470455319;4843.806247750051;4871.027233970248;4511.909761804745;5065.721385323843;5310.778520940128;5626.690532020778;5779.479846813807;1158.6466127831093;1198.9040876729841;1337.1881101108054;1375.6835927736477;1388.8611138886233;849.2993280543453;932.8619084716635;996.028871849955;998.8651532043082;1001.6077652063638;4746.5253474652945;4808.701650554331;5220.843374698765;5236.92144617831;5253.579713622901;5266.578673706094;5363;5413.431589683027;5545.861353466115;5604.3835178130375;4395.571454265678;4710.4347478302825;4812.687175333662;5131.2034940957265;5224.4139151120435;2712.186344096763;2836.305105249227;2937.2510009947327;2967.9109626711233;3008.4506479163247;5114.43741188469;5831;5922.970385148133;5933.3473317934995;6020.548458865603;4986.60375094974;5097.8215762448135;5456.843283097435;5803.622764520293;5919.3192782829665;5044.798208071709;5467.469108649584;5693.829185124453;5925.37052310618;5971.223740913653;4810.6463650396445;4834.903301933871;4878.487758785418;5129.692218466904;5569.470900264469;4815.098036275722;5294.364676238876;5395.56835453155;5651.2935883014625;5682.9147562785765;5365.6244062916785;5934.050551911846;6023.27720673522;6028.487578555872;6052.788152414644;3257.609086909586;3671.889843304705;3779.6787273082095;3782.167923056923;3783.24335132969;5008.949910500848;5052.492782866693;5637.379888212376;5647.29408823897;5680.971595141998;4977.427595826382;5024.824131155392;5138.640295179302;5505.174223866452;5778.595498315078;4799.592034677092;5712.7714891403875;5729.140628905698;5733.7065173929095;5776.335721391925;4509.706869053501;4900.921797204609;5103.974480127576;5202.60980426462;5299;4562.38407814954;4709.858704238878;5638.126090455986;5691.630044047124;5713.314402271588;4871.244957031524;5435.293411856592;5636.633618814764;5643.855334972848;5646.943530564806 17:13:02 INFO - PROCESS | 2741 | |5;yui.html;732.220703690033;745.7017193122754;791.4064451661254;857.652650676476;857.6697971281056;15185.164815935119;16302.103384313858;16353.509394718178;16364.91817540912;16367.017978921273;15001.249937503137;15169.620759481018;15186.924065379686;15197.012194207397;15197.316120774563;729.2197348836747;904.3579058868212;917.4174399256486;917.9357444978854;926.2358554192784;753.6194221917924;878.8374150782093;899.0515006667963;917.454114801692;923.0169869089432;970.8349580571285;971.1842052675751;980.6028558433845;984.119213304096;985.014985014985;947.1191791633778;969.6703120938879;978.7063880835792;979.2410881566807;980.4705459052103;724.6159535446222;725.0538047847557;726.2483329753733;727.6907314391362;728.4318231779221;14956.775648365283;15281;15629.531114066596;16285.941413808183;16479.670406591922;14933.775993360108;15150.621234469116;15194.620134496727;15197.544073677807;15353;11648.883511164864;11966.581169659106;12010.399480025964;12025.759484810254;12476.75046499074;3783.9621603783885;3906.921861562753;3911.5892831252595;3925.862594809168;3928.292907276689;110141.44929275382;112205.87794122036;112315.87684123137;114133;116242.41878790637;10957.671269861758;10977.689638076352;11047.558097676163;11181.72045698864;11440.599579014695;21931.67102493464;21951.780482194972;22004.77995220027;22005.22981695633;22006.229781957554;8012.79968000793;8106.837863242702;8174.468659537111;8274.586270686441;8373.832523349622;4408.977955110204;4418;4498.685092043525;4546.272596384629;4549.567791059844;3733.831977561016;3837.9616203838164;3838.9040273992814;3842.3275926713272;3846.211526637033;668.4084585142087;676.5331920974512;681.2234053179379;704.8695991241534;706.6678661029308;676.5703778100941;689.4381079420257;712.1596516110935;725.3689290317458;751.7894989402976;9100.544972751468;10502.842457363146;10748.193885458466;10756.623518176826;10788.838167427495;691.6334342798325;707.2821086597108;716.8351279205708;729.3435907683128;735.9879222494827;4891.095147397743;4956.430010548761;5005.189827937211;5009.724465154379;5035.924461133086;71388.21529461804;107883.46058269659;112308.43845780882;114000;116239;80649;87755.12244877598;95412.52293738487;97919.51040244896;101515;56898.57753556194;75561.6221918898;86286;88309;89686;10708.464576771286;11068.5572577096;11382.332437008201;11477.311361318347;11492.770144597229;51126.488735113664;52730.472695272554;53107;53244;54560.72719636376;62242;69302;70292.64853675904;72068.27931720615;73913.6304318475;13230.66923326886;13330.466781328638;13374.264415456853;13820.032597718466;13883.375248114062;14855.851441485447;15096.320665569856;15361.459249214144;15393.999390039611;15404.46084387041;13718.794218086538;13810.861891380957;14186.219757913414;14256.073355231876;14276.500322489079;13815.585532434043;14347.641308967359;14391.640208994859;14399.49601763975;14506.637334066732;13622.727545449234;13708.794368084287;14534.200618966059;14588.416463341347;14800.48198313054;11156.575283680024;11545.422728863356;11615.361155136548;11811.940940295244;12020.579279725167;10656.733581660521;11010.019008395515;11156.944215278872;11259.155563332619;11282.041026512838 17:13:02 INFO - PROCESS | 2741 | __end_tp_report 17:13:02 INFO - PROCESS | 2741 | __start_cc_report 17:13:02 INFO - PROCESS | 2741 | _x_x_mozilla_cycle_collect,368 17:13:02 INFO - PROCESS | 2741 | __end_cc_report 17:13:02 INFO - PROCESS | 2741 | __startTimestamp1489194782141__endTimestamp 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - PROCESS | 2741 | ------- Summary: start ------- 17:13:02 INFO - PROCESS | 2741 | Number of tests: 6 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - PROCESS | 2741 | [#0] dojo.html Cycles:165 Average:5773.51 Median:6082.97 stddev:698.39 (11.5%) stddev-sans-first:672.85 17:13:02 INFO - PROCESS | 2741 | Values: 3292.0 3429.6 3485.1 3485.7 3492.3 5688.1 5700.3 5742.6 5781.8 5855.7 5785.4 5828.2 5863.2 5864.6 5872.8 3997.8 4222.3 4752.1 5205.7 5279.4 5203.7 5284.2 5308.5 5309.5 5310.7 5102.1 5143.6 5203.3 5204.8 5238.7 5131.7 5146.3 5166.1 5210.8 5236.3 4660.5 4792.6 5156.4 5162.1 5173.4 5532.5 5582.5 5658.3 5713.6 5777.9 6175.0 6242.3 6256.3 6267.0 6306.3 6268.5 6277.2 6278.3 6288.5 6323.8 5961.2 5978.3 5978.3 5992.2 6036.3 6147.4 6278.5 6283.9 6304.2 6350.1 6240.5 6267.6 6270.1 6275.5 6283.5 6303.6 6308.6 6310.4 6322.4 6327.9 4968.7 6126.0 6220.8 6227.1 6338.1 6224.5 6269.2 6315.2 6335.7 6345.3 5023.7 5027.9 5049.3 5051.7 5068.0 4368.0 4438.6 4451.9 4462.1 4474.6 5979.3 5989.7 6012.9 6083.0 6115.9 6224.6 6276.8 6281.7 6290.2 6299.8 5869.2 5903.2 5995.9 6107.1 6119.0 5460.2 5517.1 5544.8 5571.7 5591.8 5530.4 6445.2 6562.7 6568.5 6569.0 6177.9 6180.4 6210.4 6239.3 6244.0 6452.0 6467.8 6511.6 6588.2 6606.5 4952.0 6257.0 6285.1 6287.2 6297.1 6128.4 6155.5 6250.2 6255.6 6269.6 6118.9 6139.8 6145.3 6155.1 6295.7 6239.6 6284.7 6293.4 6296.9 6299.4 5140.0 6081.2 6117.8 6129.3 6150.0 6002.8 6058.1 6072.3 6125.5 6138.1 4671.9 5907.7 6053.7 6129.9 6131.9 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - PROCESS | 2741 | [#1] ext.html Cycles:180 Average:74199.74 Median:37975.02 stddev:164019.02 (431.9%) stddev-sans-first:164385.67 17:13:02 INFO - PROCESS | 2741 | Values: 448.6 454.7 467.0 506.0 542.8 41131.0 43585.3 43710.6 45870.8 45988.8 44368.0 44955.8 45101.5 45154.5 45840.8 9433.3 9502.0 9703.9 9709.0 9723.6 11528.5 11776.1 11788.9 11818.0 11832.6 9777.9 11640.6 11783.2 11808.6 11833.9 9989.5 11337.9 11629.9 11712.9 11749.9 11383.4 11423.9 11457.1 11484.3 11583.2 38967.2 39247.8 39297.2 39328.4 39386.0 3592.5 4092.2 4115.3 4179.4 4180.9 29211.7 29592.7 29883.4 29946.3 30372.8 3202.2 3245.5 3338.9 3346.9 3360.5 508412.0 509330.0 510390.0 510857.0 510907.4 60692.0 60957.7 61171.9 61206.4 61284.4 64418.7 64954.0 65026.7 65131.7 65189.7 17239.5 17305.0 17458.3 17514.9 17555.7 12198.1 12503.6 13341.7 13925.2 14311.5 21273.7 22755.9 22757.4 22790.8 22798.3 8956.0 9116.4 9140.8 9158.6 9221.4 895.5 900.2 902.1 933.6 940.4 30788.4 31223.8 31592.6 32057.0 33351.8 1054.8 1055.6 1088.0 1102.6 1116.8 13844.4 14281.8 14876.0 15084.0 15632.6 637788.0 692131.0 693825.0 694860.0 704161.0 57581.6 57701.1 57917.7 58080.0 58144.7 1315.8 1378.9 1472.2 1477.8 1497.9 29092.7 29821.7 29970.3 30280.2 30362.0 42017.8 50789.0 51375.0 51546.0 51571.0 624717.0 629495.0 630060.0 632717.0 641021.0 27343.6 27634.4 28232.3 28412.0 29053.4 45605.0 45769.5 46528.5 46852.8 47373.8 37580.6 38376.4 38425.8 39029.6 39608.0 31206.1 37936.0 38192.0 38839.8 39233.4 36697.8 37339.8 37644.8 37852.6 38014.0 33014.8 34464.0 35018.3 35077.9 35225.6 25548.9 25664.5 25714.0 25811.9 26062.5 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - PROCESS | 2741 | [#2] jquery.html Cycles:180 Average:42319.68 Median:23108.04 stddev:126653.33 (548.1%) stddev-sans-first:126969.52 17:13:02 INFO - PROCESS | 2741 | Values: 405.1 408.6 414.5 418.8 436.1 20190.8 21043.8 21758.6 22311.4 22363.6 22617.2 22690.5 22800.6 22873.4 23010.5 6006.3 6174.2 6191.8 6244.0 6306.1 5887.4 6158.0 6186.9 6267.4 6299.0 6007.7 6191.8 6236.1 6242.8 6256.1 6102.8 6190.0 6231.9 6239.7 6242.0 5247.3 5406.1 5478.4 5498.2 5503.3 27721.2 27931.0 27948.7 28012.9 28096.3 2770.7 2818.1 2873.5 2886.2 2896.1 15635.9 16587.4 16616.3 16698.6 17268.7 1558.3 1864.1 1898.1 1914.0 1915.9 112949.0 135371.0 140408.3 141390.0 143226.1 9743.6 11469.6 11960.9 11969.6 12028.0 15098.9 15390.2 15589.4 15608.2 15630.0 7594.5 9053.5 9294.9 9315.4 9493.1 7483.3 7538.9 9194.6 9265.1 9344.4 7631.9 7713.8 7755.1 7783.0 7805.8 1688.8 1918.4 1996.8 2025.5 2028.8 925.0 1021.1 1075.8 1086.5 1100.8 18699.6 23205.5 23641.4 23683.9 23720.3 1109.7 1117.5 1121.3 1157.0 1168.8 10846.9 12015.6 12208.2 12305.8 12416.4 630341.0 772502.0 785176.0 787935.0 804323.0 161940.0 163381.0 164735.0 164786.4 169824.0 977.2 1067.0 1090.8 1134.6 1135.9 20344.5 21601.7 21604.1 21614.8 21646.0 53132.0 64903.0 64986.7 65072.0 65078.0 86190.6 95551.5 96354.0 96396.0 97230.0 6559.0 7185.8 7457.4 7625.5 8538.8 10799.6 11086.4 13184.6 13337.3 13439.6 8536.7 10981.0 11730.5 11754.9 11826.9 8592.4 11451.9 11555.1 11732.8 12062.0 10873.5 11823.1 11986.3 12020.5 12086.8 8288.3 8372.7 8487.7 8728.3 8773.0 11157.0 11194.8 11333.9 11537.3 11550.4 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - PROCESS | 2741 | [#3] mootools.html Cycles:180 Average:5525.54 Median:5413.76 stddev:2960.94 (54.7%) stddev-sans-first:2953.22 17:13:02 INFO - PROCESS | 2741 | Values: 1425.9 1474.0 1485.1 1498.3 1498.6 7725.8 7991.6 8304.8 8711.6 8734.3 8183.1 8496.7 8526.5 8604.1 8607.0 2391.1 2442.3 2448.0 2455.7 2463.3 2408.0 2421.3 2437.6 2447.8 2449.3 2395.6 2406.8 2426.1 2431.4 2432.0 2379.0 2382.4 2383.1 2401.6 2411.9 1872.9 2375.7 2421.8 2435.5 2523.1 8540.7 8619.2 8630.4 8666.4 8667.6 5216.3 5369.3 5385.0 5397.7 5409.9 3021.5 3529.7 3639.7 3670.4 3696.9 1264.7 1265.3 1306.0 1325.0 1331.4 11013.7 11022.4 11038.9 11052.2 11069.3 4295.7 4716.0 4802.2 4891.6 4923.8 9375.8 9863.8 9869.9 9874.5 9895.6 4481.8 5417.6 5490.3 5491.0 5507.5 4465.4 4589.5 4958.5 5011.2 5035.3 4020.5 4084.6 4124.1 4159.5 4178.6 1229.8 1248.0 1338.4 1348.4 1358.8 937.4 954.3 962.6 972.9 996.8 5669.0 5670.5 5792.4 5891.7 5982.7 588.1 590.5 594.9 979.9 1066.6 3135.4 3949.5 3954.9 3992.6 4071.0 7724.8 8212.3 9979.4 10240.3 10256.6 6965.0 8724.6 9962.5 9981.6 10092.8 7098.9 7127.3 7388.7 7943.8 8115.0 5881.4 5887.4 5904.6 5975.8 6194.6 9007.4 9484.4 9501.6 9615.2 9615.5 9512.7 9645.8 9654.0 9664.1 9684.0 6804.5 6990.8 7141.6 7328.1 7508.8 7976.3 8222.1 8231.5 8244.3 8252.1 7184.7 7244.4 7299.1 7391.9 7445.7 7137.4 7216.4 7224.2 7278.1 7345.3 5319.9 6517.5 6673.2 6683.5 6685.2 5042.4 5075.6 5094.2 5220.5 5280.2 4520.5 5124.2 5154.6 5206.5 5228.0 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - PROCESS | 2741 | [#4] prototype.html Cycles:180 Average:4513.98 Median:4932.20 stddev:1345.65 (27.3%) stddev-sans-first:1311.63 17:13:02 INFO - PROCESS | 2741 | Values: 294.5 360.1 367.5 373.5 381.3 5179.4 5308.3 5471.3 5487.2 5492.9 4546.9 5184.8 5302.0 5332.9 5395.9 3874.7 4225.9 4260.1 4266.7 4278.5 3729.9 3936.2 4256.6 4270.6 4280.3 3278.5 3388.9 3612.9 4163.8 4170.7 3702.6 3785.7 3993.2 4192.5 4207.2 3349.7 3390.9 3780.3 3781.0 3785.9 4101.5 4487.9 5067.9 5104.4 5252.6 4904.9 4911.6 4915.9 4921.8 4942.6 4727.0 4968.6 4979.0 5689.3 5752.6 3955.1 4101.4 4126.3 4176.3 4179.8 4734.9 5537.4 5824.0 6056.5 6059.8 4173.9 4251.8 4253.7 4254.9 4262.0 3699.8 4174.6 4257.8 4310.6 4322.7 3827.9 4110.3 4553.0 4843.8 4871.0 4511.9 5065.7 5310.8 5626.7 5779.5 1158.6 1198.9 1337.2 1375.7 1388.9 849.3 932.9 996.0 998.9 1001.6 4746.5 4808.7 5220.8 5236.9 5253.6 5266.6 5363.0 5413.4 5545.9 5604.4 4395.6 4710.4 4812.7 5131.2 5224.4 2712.2 2836.3 2937.3 2967.9 3008.5 5114.4 5831.0 5923.0 5933.3 6020.5 4986.6 5097.8 5456.8 5803.6 5919.3 5044.8 5467.5 5693.8 5925.4 5971.2 4810.6 4834.9 4878.5 5129.7 5569.5 4815.1 5294.4 5395.6 5651.3 5682.9 5365.6 5934.1 6023.3 6028.5 6052.8 3257.6 3671.9 3779.7 3782.2 3783.2 5008.9 5052.5 5637.4 5647.3 5681.0 4977.4 5024.8 5138.6 5505.2 5778.6 4799.6 5712.8 5729.1 5733.7 5776.3 4509.7 4900.9 5104.0 5202.6 5299.0 4562.4 4709.9 5638.1 5691.6 5713.3 4871.2 5435.3 5636.6 5643.9 5646.9 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - PROCESS | 2741 | [#5] yui.html Cycles:180 Average:21320.21 Median:3810.96 stddev:30685.18 (805.2%) stddev-sans-first:30732.32 17:13:02 INFO - PROCESS | 2741 | Values: 732.2 745.7 791.4 857.7 857.7 15185.2 16302.1 16353.5 16364.9 16367.0 15001.2 15169.6 15186.9 15197.0 15197.3 729.2 904.4 917.4 917.9 926.2 753.6 878.8 899.1 917.5 923.0 970.8 971.2 980.6 984.1 985.0 947.1 969.7 978.7 979.2 980.5 724.6 725.1 726.2 727.7 728.4 14956.8 15281.0 15629.5 16285.9 16479.7 14933.8 15150.6 15194.6 15197.5 15353.0 11648.9 11966.6 12010.4 12025.8 12476.8 3784.0 3906.9 3911.6 3925.9 3928.3 110141.4 112205.9 112315.9 114133.0 116242.4 10957.7 10977.7 11047.6 11181.7 11440.6 21931.7 21951.8 22004.8 22005.2 22006.2 8012.8 8106.8 8174.5 8274.6 8373.8 4409.0 4418.0 4498.7 4546.3 4549.6 3733.8 3838.0 3838.9 3842.3 3846.2 668.4 676.5 681.2 704.9 706.7 676.6 689.4 712.2 725.4 751.8 9100.5 10502.8 10748.2 10756.6 10788.8 691.6 707.3 716.8 729.3 736.0 4891.1 4956.4 5005.2 5009.7 5035.9 71388.2 107883.5 112308.4 114000.0 116239.0 80649.0 87755.1 95412.5 97919.5 101515.0 56898.6 75561.6 86286.0 88309.0 89686.0 10708.5 11068.6 11382.3 11477.3 11492.8 51126.5 52730.5 53107.0 53244.0 54560.7 62242.0 69302.0 70292.6 72068.3 73913.6 13230.7 13330.5 13374.3 13820.0 13883.4 14855.9 15096.3 15361.5 15394.0 15404.5 13718.8 13810.9 14186.2 14256.1 14276.5 13815.6 14347.6 14391.6 14399.5 14506.6 13622.7 13708.8 14534.2 14588.4 14800.5 11156.6 11545.4 11615.4 11811.9 12020.6 10656.7 11010.0 11156.9 11259.2 11282.0 17:13:02 INFO - PROCESS | 2741 | -------- Summary: end -------- 17:13:02 INFO - PROCESS | 2741 | 17:13:02 INFO - TEST-INFO | 2741: exit 0 17:13:02 INFO - TEST-OK | dromaeo_css | took 1114020ms 17:13:02 INFO - TEST-START | kraken 17:13:02 INFO - operating with platform_type : mac_ 17:13:02 INFO - Initialising browser for kraken test... 17:13:02 INFO - TEST-INFO | started process 2745 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpjfEJzB/profile http://localhost:51069/getInfo.html) 17:13:05 INFO - PROCESS | 2745 | 2017-03-10 17:13:05.415 plugin-container[2747:48364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 17:13:05 INFO - PROCESS | 2745 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:13:05 INFO - PROCESS | 2745 | 2017-03-10 17:13:05.416 plugin-container[2747:48364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 17:13:05 INFO - PROCESS | 2745 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:13:05 INFO - PROCESS | 2745 | 2017-03-10 17:13:05.416 plugin-container[2747:48364] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:13:05 INFO - PROCESS | 2745 | __metrics Screen width/height:1600/1200 17:13:05 INFO - PROCESS | 2745 | colorDepth:24 17:13:05 INFO - PROCESS | 2745 | Browser inner width/height: 1024/689 17:13:05 INFO - PROCESS | 2745 | __metrics 17:13:05 ERROR - PROCESS | 2745 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 17:13:06 INFO - TEST-INFO | 2745: exit 0 17:13:06 INFO - Browser initialized. 17:13:06 INFO - Running cycle 1/1 for kraken test... 17:13:06 INFO - TEST-INFO | started process 2748 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpjfEJzB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:13:08 INFO - PROCESS | 2748 | 2017-03-10 17:13:08.616 plugin-container[2749:48489] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 17:13:08 INFO - PROCESS | 2748 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:13:08 INFO - PROCESS | 2748 | 2017-03-10 17:13:08.616 plugin-container[2749:48489] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 17:13:08 INFO - PROCESS | 2748 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:13:08 INFO - PROCESS | 2748 | 2017-03-10 17:13:08.616 plugin-container[2749:48489] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:13:18 INFO - PROCESS | 2748 | RSS: Main: 172494848 17:13:18 INFO - PROCESS | 2748 | 17:13:21 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51069/tests/kraken/driver.html?testName=audio-beat-detection) 17:13:22 INFO - PROCESS | 2748 | RSS: Main: 185466880 17:13:22 INFO - PROCESS | 2748 | 17:13:24 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51069/tests/kraken/driver.html?testName=audio-dft) 17:13:25 INFO - PROCESS | 2748 | RSS: Main: 194367488 17:13:25 INFO - PROCESS | 2748 | 17:13:28 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51069/tests/kraken/driver.html?testName=audio-fft) 17:13:29 INFO - PROCESS | 2748 | RSS: Main: 195616768 17:13:29 INFO - PROCESS | 2748 | 17:13:31 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51069/tests/kraken/driver.html?testName=audio-oscillator) 17:13:32 INFO - PROCESS | 2748 | RSS: Main: 198303744 17:13:32 INFO - PROCESS | 2748 | 17:13:34 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51069/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:13:34 INFO - PROCESS | 2748 | RSS: Main: 198615040 17:13:34 INFO - PROCESS | 2748 | 17:13:37 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51069/tests/kraken/driver.html?testName=imaging-darkroom) 17:13:38 INFO - PROCESS | 2748 | RSS: Main: 202723328 17:13:38 INFO - PROCESS | 2748 | 17:13:41 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51069/tests/kraken/driver.html?testName=imaging-desaturate) 17:13:42 INFO - PROCESS | 2748 | RSS: Main: 202223616 17:13:42 INFO - PROCESS | 2748 | 17:13:45 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51069/tests/kraken/driver.html?testName=json-parse-financial) 17:13:45 INFO - PROCESS | 2748 | RSS: Main: 202244096 17:13:45 INFO - PROCESS | 2748 | 17:13:47 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51069/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:13:48 INFO - PROCESS | 2748 | RSS: Main: 201248768 17:13:48 INFO - PROCESS | 2748 | 17:13:50 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51069/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:13:50 INFO - PROCESS | 2748 | RSS: Main: 201211904 17:13:50 INFO - PROCESS | 2748 | 17:13:52 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51069/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:13:53 INFO - PROCESS | 2748 | RSS: Main: 201199616 17:13:53 INFO - PROCESS | 2748 | 17:13:56 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51069/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:13:57 INFO - PROCESS | 2748 | RSS: Main: 201580544 17:13:57 INFO - PROCESS | 2748 | 17:14:00 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51069/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:14:00 INFO - PROCESS | 2748 | RSS: Main: 201371648 17:14:00 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:51069/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51069/tests/kraken/driver.html?testName=ai-astar) 17:14:02 INFO - PROCESS | 2748 | RSS: Main: 203010048 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | __start_tp_report 17:14:02 INFO - PROCESS | 2748 | _x_x_mozilla_page_load 17:14:02 INFO - PROCESS | 2748 | _x_x_mozilla_page_load_details 17:14:02 INFO - PROCESS | 2748 | |i|pagename|runs| 17:14:02 INFO - PROCESS | 2748 | |0;ai-astar;114;118;117;119;117;117;119;118;115;119 17:14:02 INFO - PROCESS | 2748 | |1;audio-beat-detection;143;130;147;187;98;147;145;111;132;119 17:14:02 INFO - PROCESS | 2748 | |2;audio-dft;198;201;283;233;199;261;251;196;195;199 17:14:02 INFO - PROCESS | 2748 | |3;audio-fft;83;57;62;84;62;83;83;69;71;59 17:14:02 INFO - PROCESS | 2748 | |4;audio-oscillator;90;70;81;75;70;71;79;70;71;82 17:14:02 INFO - PROCESS | 2748 | |5;imaging-gaussian-blur;90;88;89;87;116;131;94;134;136;87 17:14:02 INFO - PROCESS | 2748 | |6;imaging-darkroom;155;112;99;98;99;156;115;94;96;95 17:14:02 INFO - PROCESS | 2748 | |7;imaging-desaturate;93;96;92;135;138;94;94;143;150;93 17:14:02 INFO - PROCESS | 2748 | |8;json-parse-financial;58;56;58;61;62;67;84;96;98;62 17:14:02 INFO - PROCESS | 2748 | |9;json-stringify-tinderbox;68;73;69;86;85;93;61;85;112;106 17:14:02 INFO - PROCESS | 2748 | |10;stanford-crypto-aes;79;86;83;86;85;84;80;96;75;105 17:14:02 INFO - PROCESS | 2748 | |11;stanford-crypto-ccm;133;132;133;150;148;144;155;167;179;137 17:14:02 INFO - PROCESS | 2748 | |12;stanford-crypto-pbkdf2;158;147;235;177;165;164;163;164;194;238 17:14:02 INFO - PROCESS | 2748 | |13;stanford-crypto-sha256-iterative;66;69;68;82;76;58;57;55;85;87 17:14:02 INFO - PROCESS | 2748 | __end_tp_report 17:14:02 INFO - PROCESS | 2748 | __start_cc_report 17:14:02 INFO - PROCESS | 2748 | _x_x_mozilla_cycle_collect,930 17:14:02 INFO - PROCESS | 2748 | __end_cc_report 17:14:02 INFO - PROCESS | 2748 | __startTimestamp1489194842803__endTimestamp 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | ------- Summary: start ------- 17:14:02 INFO - PROCESS | 2748 | Number of tests: 14 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#0] ai-astar Cycles:10 Average:117.30 Median:118.00 stddev:1.70 (1.4%) stddev-sans-first:1.32 17:14:02 INFO - PROCESS | 2748 | Values: 114.0 118.0 117.0 119.0 117.0 117.0 119.0 118.0 115.0 119.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:146.00 stddev:24.46 (16.8%) stddev-sans-first:25.80 17:14:02 INFO - PROCESS | 2748 | Values: 143.0 130.0 147.0 187.0 98.0 147.0 145.0 111.0 132.0 119.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#2] audio-dft Cycles:10 Average:221.60 Median:217.00 stddev:32.80 (15.1%) stddev-sans-first:33.66 17:14:02 INFO - PROCESS | 2748 | Values: 198.0 201.0 283.0 233.0 199.0 261.0 251.0 196.0 195.0 199.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#3] audio-fft Cycles:10 Average:71.30 Median:77.00 stddev:11.09 (14.4%) stddev-sans-first:10.92 17:14:02 INFO - PROCESS | 2748 | Values: 83.0 57.0 62.0 84.0 62.0 83.0 83.0 69.0 71.0 59.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.00 stddev:6.87 (8.9%) stddev-sans-first:5.05 17:14:02 INFO - PROCESS | 2748 | Values: 90.0 70.0 81.0 75.0 70.0 71.0 79.0 70.0 71.0 82.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:87.50 stddev:21.44 (24.5%) stddev-sans-first:22.03 17:14:02 INFO - PROCESS | 2748 | Values: 90.0 88.0 89.0 87.0 116.0 131.0 94.0 134.0 136.0 87.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:95.50 stddev:24.03 (25.2%) stddev-sans-first:19.79 17:14:02 INFO - PROCESS | 2748 | Values: 155.0 112.0 99.0 98.0 99.0 156.0 115.0 94.0 96.0 95.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:93.00 stddev:25.01 (26.9%) stddev-sans-first:25.48 17:14:02 INFO - PROCESS | 2748 | Values: 93.0 96.0 92.0 135.0 138.0 94.0 94.0 143.0 150.0 93.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#8] json-parse-financial Cycles:10 Average:70.20 Median:64.50 stddev:16.19 (25.1%) stddev-sans-first:16.55 17:14:02 INFO - PROCESS | 2748 | Values: 58.0 56.0 58.0 61.0 62.0 67.0 84.0 96.0 98.0 62.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#9] json-stringify-tinderbox Cycles:10 Average:83.80 Median:79.00 stddev:16.62 (21.0%) stddev-sans-first:16.61 17:14:02 INFO - PROCESS | 2748 | Values: 68.0 73.0 69.0 86.0 85.0 93.0 61.0 85.0 112.0 106.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:84.50 stddev:8.70 (10.3%) stddev-sans-first:8.86 17:14:02 INFO - PROCESS | 2748 | Values: 79.0 86.0 83.0 86.0 85.0 84.0 80.0 96.0 75.0 105.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:149.00 stddev:15.70 (10.5%) stddev-sans-first:15.71 17:14:02 INFO - PROCESS | 2748 | Values: 133.0 132.0 133.0 150.0 148.0 144.0 155.0 167.0 179.0 137.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:171.00 stddev:31.96 (18.7%) stddev-sans-first:32.84 17:14:02 INFO - PROCESS | 2748 | Values: 158.0 147.0 235.0 177.0 165.0 164.0 163.0 164.0 194.0 238.0 17:14:02 INFO - PROCESS | 2748 | 17:14:02 INFO - PROCESS | 2748 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:72.50 stddev:11.80 (16.3%) stddev-sans-first:12.41 17:14:02 INFO - PROCESS | 2748 | Values: 66.0 69.0 68.0 82.0 76.0 58.0 57.0 55.0 85.0 87.0 17:14:02 INFO - PROCESS | 2748 | -------- Summary: end -------- 17:14:02 INFO - PROCESS | 2748 | 17:14:03 INFO - TEST-INFO | 2748: exit 0 17:14:03 INFO - TEST-OK | kraken | took 60912ms 17:14:03 INFO - SUITE-END | took 1174s 17:14:03 INFO - Completed test suite (00:19:35) 17:14:03 INFO - javascript benchmark 17:14:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7550.8424133129765, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3291.950620740689, 3429.639887811781, 3485.1461391958974, 3485.668861458162, 3492.2840817632386, 5688.11834165705, 5700.287464066992, 5742.569307301958, 5781.797637082703, 5855.707214639272, 5785.363610002896, 5828.184054232411, 5863.237779088723, 5864.560157988147, 5872.7944521941745, 3997.820098095578, 4222.282212023963, 4752.12085764133, 5205.687658740469, 5279.3928698199625, 5203.687778733288, 5284.207368894658, 5308.522232999029, 5309.522143007129, 5310.681359118446, 5102.056119617882, 5143.614228932845, 5203.297554830087, 5204.828913494472, 5238.659487133325, 5131.666441681279, 5146.330976973007, 5166.07010738067, 5210.791568337262, 5236.345456817898, 4660.464046634646, 4792.586187074821, 5156.381234251876, 5162.148245539482, 5173.430922598511, 5532.474414930575, 5582.497575218209, 5658.321001479808, 5713.571482138815, 5777.88444231113, 6174.96912515439, 6242.344553821828, 6256.280527739323, 6266.997280435108, 6306.306306306303, 6268.5298602604535, 6277.183966084425, 6278.340774218687, 6288.465480434169, 6323.810285691437, 5961.195238642771, 5978.252718410199, 5978.282606087298, 5992.191054207669, 6036.2756469223095, 6147.415995480422, 6278.497720182374, 6283.874322513524, 6304.18045654062, 6350.110984462179, 6240.469560087444, 6267.62394256346, 6270.122182894398, 6275.466585340206, 6283.465905398001, 6303.590266632654, 6308.589941653778, 6310.400511951358, 6322.399372059647, 6327.936720632827, 4968.677035992649, 6125.998614523584, 6220.844478888064, 6227.06594010902, 6338.080978258126, 6224.533160013018, 6269.216347956505, 6315.179026726588, 6335.65153916539, 6345.302016778151, 5023.673461225008, 5027.924581131286, 5049.343585333883, 5051.696898186047, 5068.0, 4367.978160109179, 4438.644908407319, 4451.866444006685, 4462.085272519126, 4474.574915383034, 5979.342272350038, 5989.730462129215, 6012.909806352908, 6082.9695851520455, 6115.877682446326, 6224.559023066442, 6276.843078922972, 6281.654509001957, 6290.150829637941, 6299.811005669837, 5869.20765696634, 5903.173555702205, 5995.880082398327, 6107.083937409424, 6119.020956646915, 5460.208269800856, 5517.117261238182, 5544.750486228129, 5571.693556854412, 5591.832245032656, 5530.419305972976, 6445.2265728112925, 6562.704678289393, 6568.507361947873, 6568.967155164193, 6177.876442471035, 6180.351063138306, 6210.378962103753, 6239.251289845248, 6243.96878015607, 6451.967740161269, 6467.773627923, 6511.641859697762, 6588.176477940257, 6606.47148228131, 4951.950480495149, 6256.968715156395, 6285.088662143872, 6287.245530536365, 6297.086922396135, 6128.417800308964, 6155.538334624832, 6250.218722659668, 6255.624662520263, 6269.62382257066, 6118.862843290023, 6139.798803208702, 6145.3407579954055, 6155.1075094112, 6295.685215739286, 6239.625622462667, 6284.654344010941, 6293.402126797947, 6296.874062518816, 6299.370062993664, 5139.997700448376, 6081.209442772411, 6117.84705382369, 6129.295131059806, 6149.969250153899, 6002.819915402545, 6058.0610005449225, 6072.332043475126, 6125.509959203342, 6138.109974053648, 4671.902103005859, 5907.675077870758, 6053.667048312385, 6129.908051379144, 6131.908021379593], "value": 5728.920496537314, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.56041079741834, 454.7248914406784, 466.96497762667803, 505.9930737831716, 542.8100164942269, 41131.0, 43585.34621980669, 43710.562894371, 45870.770646146724, 45988.770056149675, 44368.0, 44955.77522112385, 45101.54898451015, 45154.54845451545, 45840.770796146055, 9433.339666223334, 9502.0, 9703.854442183372, 9709.029097090304, 9723.562439690195, 11528.538858445652, 11776.057915366793, 11788.941055294754, 11818.0, 11832.585859494919, 9777.90222097777, 11640.592579259684, 11783.234089784179, 11808.645740627795, 11833.881661183364, 9989.450580218085, 11337.886621133766, 11629.883701162964, 11712.941435292854, 11749.94125029378, 11383.373914434702, 11423.885761142365, 11457.083433325313, 11484.253523521027, 11583.247088939192, 38967.220655587014, 39247.8037609813, 39297.214055719014, 39328.410073848914, 39386.0, 3592.497050412944, 4092.1815636872448, 4115.259253334399, 4179.435776170209, 4180.874573762792, 29211.70788292111, 29592.70407295921, 29883.40233195324, 29946.25134371658, 30372.78508859643, 3202.1994501374656, 3245.545623612696, 3338.8998330050135, 3346.8509311200187, 3360.512725654766, 508412.0, 509330.0, 510390.0, 510857.0, 510907.4454627703, 60692.0, 60957.695211524544, 61171.941402929675, 61206.38793612096, 61284.38715612876, 64418.677906611105, 64954.0, 65026.67486662536, 65131.67434162894, 65189.67405163038, 17239.482815515556, 17305.0, 17458.301667933396, 17514.912425438048, 17555.73666395005, 12198.146129771008, 12503.624891253277, 13341.66645833862, 13925.234112123728, 14311.499097531745, 21273.68089478659, 22755.88622056879, 22757.431064223194, 22790.772092279196, 22798.31605051851, 8955.955220223857, 9116.4074335168, 9140.771480713036, 9158.633654653873, 9221.446713197229, 895.54327293081, 900.2123142250531, 902.0888902208816, 933.5752232734089, 940.4169414962766, 30788.384232315228, 31223.843880780452, 31592.578333975027, 32057.0, 33351.83268585588, 1054.7626783973699, 1055.6252530351614, 1088.0207812968233, 1102.6195962392962, 1116.7598966222301, 13844.446222151, 14281.78577321341, 14876.0, 15084.0, 15632.567265973365, 637788.0, 692131.0, 693825.0, 694860.0, 704161.0, 57581.56046098881, 57701.13448298279, 57917.710411447675, 58080.0, 58144.709276454196, 1315.81578578999, 1378.9244389376302, 1472.168224952898, 1477.7561702319213, 1497.8801695864304, 29092.709072909, 29821.70178298276, 29970.25074373158, 30280.242993925327, 30362.0, 42017.78991105025, 50788.98422031465, 51374.972500550524, 51546.0, 51571.0, 624717.0, 629495.0, 630060.0, 632717.0, 641021.0, 27343.589846151925, 27634.447311053264, 28232.29419264535, 28412.0, 29053.418931621673, 45605.0, 45769.54230457786, 46528.53471465242, 46852.76573617246, 47373.763131184125, 37580.624193758806, 38376.42435363416, 38425.80787096047, 39029.609703903734, 39608.0, 31206.063818085495, 37936.0, 38192.0, 38839.8348049559, 39233.41149882697, 36697.81651091728, 37339.81330093441, 37644.81177594094, 37852.621473786014, 38014.04964875789, 33014.83492582522, 34463.96608101761, 35018.299634007686, 35077.94766157019, 35225.64774352223, 25548.872255638602, 25664.486710266065, 25713.971441142145, 25811.870940645927, 26062.478750425264], "value": 20951.71811179406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.13503669665255, 408.5812042656279, 414.54400159824195, 418.8303736986521, 436.11903954012894, 20190.798092019093, 21043.789562104375, 21758.564828703416, 22311.442213944618, 22363.55272894545, 22617.208397706083, 22690.54618907621, 22800.631962082247, 22873.4281642959, 23010.53978920425, 6006.339302676702, 6174.166487524194, 6191.752329906798, 6244.0321750128805, 6306.148669929569, 5887.4406931341455, 6158.0, 6186.907196392058, 6267.43593076623, 6299.0, 6007.729652165641, 6191.7926004429155, 6236.095766113911, 6242.843928901769, 6256.124142620014, 6102.786402475915, 6190.0, 6231.937680623227, 6239.65681887496, 6242.001279795257, 5247.291615631879, 5406.135018397077, 5478.424765399615, 5498.1752737089355, 5503.257060296849, 27721.16836494888, 27931.0, 27948.720512794815, 28012.879484820583, 28096.297592560146, 2770.6813716422666, 2818.0841226601233, 2873.454043731684, 2886.1774394297527, 2896.0877323643194, 15635.921820390939, 16587.419440319652, 16616.335346586122, 16698.58253543659, 17268.74096888535, 1558.3065535836556, 1864.114545590847, 1898.1268616436455, 1913.9808601913942, 1915.9425217243504, 112949.0, 135371.0, 140408.29795851058, 141390.0, 143226.13547728988, 9743.561539730586, 11469.598564050386, 11960.940195298968, 11969.64091077269, 12028.0, 15098.924505377403, 15390.230488475756, 15589.376424942875, 15608.219589020504, 15630.0, 7594.544327340267, 9053.547322633842, 9294.90705092954, 9315.39449935752, 9493.145616894513, 7483.32650061497, 7538.906858505596, 9194.63221471147, 9265.073492650681, 9344.392614480037, 7631.885521717179, 7713.807154821062, 7755.146933837386, 7783.0, 7805.804854878674, 1688.7551305060697, 1918.3957053528095, 1996.780354161041, 2025.5138766695886, 2028.8376929845576, 925.0148591749766, 1021.0861279155115, 1075.7687097273968, 1086.4784903246327, 1100.8018556659717, 18699.626007479776, 23205.53588928246, 23641.40896477602, 23683.881580592333, 23720.288391348287, 1109.6670998700358, 1117.463617463622, 1121.265571050963, 1156.988430115705, 1168.8129899216085, 10846.945765271123, 12015.579454719043, 12208.206466579644, 12305.753884922431, 12416.379181040911, 630341.0, 772502.0, 785176.0, 787935.0, 804323.0, 161940.0, 163381.0, 164735.0, 164786.3521364771, 169824.0, 977.1742877268697, 1066.99466502667, 1090.8036553420302, 1134.5631931706187, 1135.8815808517222, 20344.491387715425, 21601.675974860704, 21604.13583456644, 21614.783852161905, 21646.0, 53132.0, 64903.0, 64986.67506662625, 65072.0, 65078.02382964164, 86190.56904715436, 95551.52224238834, 96354.0, 96396.0, 97230.02769972493, 6558.967205164135, 7185.784426467005, 7457.366123879531, 7625.466217364731, 8538.829223415622, 10799.622013229498, 11086.390248536409, 13184.60446186616, 13337.3331333431, 13439.596812095262, 8536.70121545743, 10981.0, 11730.472128754056, 11754.941225293818, 11826.881731182579, 8592.441491303045, 11451.942740286246, 11555.075593952295, 11732.765344693229, 12062.0, 10873.510692019036, 11823.11326650522, 11986.280823150639, 12020.459079341626, 12086.75826483483, 8288.254057134887, 8372.706955256537, 8487.660493580188, 8728.345374097094, 8773.0, 11157.0, 11194.776104477702, 11333.886661133283, 11537.307761534335, 11550.364729939934], "value": 10574.317697956747, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.907316024459, 1474.0197768483958, 1485.14604102641, 1498.2883130513005, 1498.6178524476254, 7725.806854828632, 7991.600419979007, 8304.795804608328, 8711.607977641006, 8734.25758810501, 8183.099859015503, 8496.745097647065, 8526.531040792754, 8604.139586041409, 8607.0, 2391.1152873436854, 2442.3405680466262, 2447.951040979179, 2455.656208130863, 2463.2979600813774, 2408.048820715825, 2421.309926670899, 2437.585610446228, 2447.7919376852988, 2449.2529778417575, 2395.568797616428, 2406.759324067588, 2426.1387207541334, 2431.4407686232175, 2432.0393444589376, 2378.9643155352683, 2382.3924899150693, 2383.1420688552107, 2401.603735383651, 2411.9276421707377, 1872.8595355348402, 2375.7171127591255, 2421.782039616443, 2435.5007223519137, 2523.079076137215, 8540.743777686617, 8619.224269815684, 8630.352723545759, 8666.350023748244, 8667.566621668955, 5216.295800067019, 5369.275147855068, 5384.9730751346, 5397.7031263280505, 5409.918851217235, 3021.47124253257, 3529.699975502085, 3639.7452178347526, 3670.394384926484, 3696.907577310562, 1264.740728150727, 1265.316728966357, 1306.000909304383, 1324.9536266230732, 1331.4274861809515, 11013.669589912235, 11022.44887755609, 11038.944805276002, 11052.226344155912, 11069.280496767684, 4295.689814606512, 4716.0, 4802.231642937113, 4891.559759621651, 4923.769057735566, 9375.765605859908, 9863.802723945624, 9869.851952220723, 9874.456904870302, 9895.604175832887, 4481.798319075584, 5417.59368047398, 5490.258815059916, 5491.039068163135, 5507.476789704971, 4465.37484752135, 4589.472210695746, 4958.528939750718, 5011.223260394645, 5035.345405097314, 4020.537638171589, 4084.611961863618, 4124.1133156371525, 4159.521655009652, 4178.644815190682, 1229.7518019210509, 1247.9812802807965, 1338.3977210255423, 1348.440397235156, 1358.802973568827, 937.4422218779815, 954.289054654287, 962.5764663548157, 972.9367591106698, 996.7786424492269, 5669.0, 5670.518005969457, 5792.391798861143, 5891.734871930774, 5982.73077711504, 588.0679123589159, 590.4892268187987, 594.8839976204686, 979.941663003954, 1066.578701412953, 3135.419947309755, 3949.4668219790533, 3954.9011274718364, 3992.6007399260425, 4071.002604361891, 7724.806879827936, 8212.301954333829, 9979.401235925723, 10240.283180177465, 10256.58973641046, 6964.965175174092, 8724.607392667218, 9962.501874906226, 9981.600735970625, 10092.798144037079, 7098.935159726082, 7127.322904324081, 7388.704451821867, 7943.801404964806, 8115.026196856533, 5881.411858814085, 5887.4406931341455, 5904.645721256738, 5975.760969561169, 6194.566380353513, 9007.414518056305, 9484.383515071499, 9501.572429240563, 9615.2307815376, 9615.51922403863, 9512.714618561455, 9645.758856028655, 9654.0, 9664.130228279488, 9683.951580242336, 6804.523683342119, 6990.825229369307, 7141.64291785419, 7328.083989501312, 7508.774736757906, 7976.282134607681, 8222.095569487472, 8231.547264900488, 8244.258016778518, 8252.09226985019, 7184.748533801292, 7244.384227340735, 7299.05112335393, 7391.852162956819, 7445.702171913063, 7137.357637812622, 7216.386607138452, 7224.169220539705, 7278.053852999076, 7345.3021962913435, 5319.920201196907, 6517.478601711947, 6673.232578253563, 6683.465322774069, 6685.164354455693, 5042.420121685906, 5075.593952483719, 5094.1849304111165, 5220.477952204901, 5280.1815718563685, 4520.4575450946095, 5124.180131178993, 5154.587632989427, 5206.453322401093, 5228.0], "value": 4509.820909919266, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.49200129776125, 360.0530604510136, 367.4910249304714, 373.4696730642488, 381.33457117330255, 5179.430262671103, 5308.336457942757, 5471.288732464779, 5487.204355368469, 5492.862678433036, 4546.931796023062, 5184.792608295673, 5302.045631786277, 5332.946670533293, 5395.946040539593, 3874.6751948830615, 4225.915481690365, 4260.126674031816, 4266.7439953602725, 4278.459754488382, 3729.9440508392395, 3936.1734035852364, 4256.616904478597, 4270.572942705736, 4280.272353699878, 3278.459054256045, 3388.932221355584, 3612.8916132516065, 4163.83344666213, 4170.666346692273, 3702.648818000562, 3785.71607129465, 3993.2463484324644, 4192.4759405074365, 4207.158568286324, 3349.748768842322, 3390.8982730518123, 3780.2628487444686, 3781.0, 3785.8674946376746, 4101.4668093148, 4487.865364039084, 5067.923981140286, 5104.387473503166, 5252.553532949666, 4904.877378065541, 4911.631627627942, 4915.926261106087, 4921.80312787488, 4942.604591632661, 4727.0, 4968.59651138971, 4979.0, 5689.317281926154, 5752.568557358173, 3955.1496428267774, 4101.384792281152, 4126.277901367249, 4176.310908700061, 4179.791010449496, 4734.905301893943, 5537.36320323164, 5823.951688696033, 6056.4851987581105, 6059.787907423263, 4173.895652608649, 4251.80866860992, 4253.723507971972, 4254.893627659271, 4261.952703816786, 3699.8335074921692, 4174.645155161785, 4257.765822879772, 4310.612044915973, 4322.740635561876, 3827.9043023924623, 4110.321796903478, 4552.954470455319, 4843.806247750051, 4871.027233970248, 4511.909761804745, 5065.721385323843, 5310.778520940128, 5626.690532020778, 5779.479846813807, 1158.6466127831093, 1198.9040876729841, 1337.1881101108054, 1375.6835927736477, 1388.8611138886233, 849.2993280543453, 932.8619084716635, 996.028871849955, 998.8651532043082, 1001.6077652063638, 4746.5253474652945, 4808.701650554331, 5220.843374698765, 5236.92144617831, 5253.579713622901, 5266.578673706094, 5363.0, 5413.431589683027, 5545.861353466115, 5604.3835178130375, 4395.571454265678, 4710.4347478302825, 4812.687175333662, 5131.2034940957265, 5224.4139151120435, 2712.186344096763, 2836.305105249227, 2937.2510009947327, 2967.9109626711233, 3008.4506479163247, 5114.43741188469, 5831.0, 5922.970385148133, 5933.3473317934995, 6020.548458865603, 4986.60375094974, 5097.8215762448135, 5456.843283097435, 5803.622764520293, 5919.3192782829665, 5044.798208071709, 5467.469108649584, 5693.829185124453, 5925.37052310618, 5971.223740913653, 4810.6463650396445, 4834.903301933871, 4878.487758785418, 5129.692218466904, 5569.470900264469, 4815.098036275722, 5294.364676238876, 5395.56835453155, 5651.2935883014625, 5682.9147562785765, 5365.6244062916785, 5934.050551911846, 6023.27720673522, 6028.487578555872, 6052.788152414644, 3257.609086909586, 3671.889843304705, 3779.6787273082095, 3782.167923056923, 3783.24335132969, 5008.949910500848, 5052.492782866693, 5637.379888212376, 5647.29408823897, 5680.971595141998, 4977.427595826382, 5024.824131155392, 5138.640295179302, 5505.174223866452, 5778.595498315078, 4799.592034677092, 5712.7714891403875, 5729.140628905698, 5733.7065173929095, 5776.335721391925, 4509.706869053501, 4900.921797204609, 5103.974480127576, 5202.60980426462, 5299.0, 4562.38407814954, 4709.858704238878, 5638.126090455986, 5691.630044047124, 5713.314402271588, 4871.244957031524, 5435.293411856592, 5636.633618814764, 5643.855334972848, 5646.943530564806], "value": 4103.911098343747, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.220703690033, 745.7017193122754, 791.4064451661254, 857.652650676476, 857.6697971281056, 15185.164815935119, 16302.103384313858, 16353.509394718178, 16364.91817540912, 16367.017978921273, 15001.249937503137, 15169.620759481018, 15186.924065379686, 15197.012194207397, 15197.316120774563, 729.2197348836747, 904.3579058868212, 917.4174399256486, 917.9357444978854, 926.2358554192784, 753.6194221917924, 878.8374150782093, 899.0515006667963, 917.454114801692, 923.0169869089432, 970.8349580571285, 971.1842052675751, 980.6028558433845, 984.119213304096, 985.014985014985, 947.1191791633778, 969.6703120938879, 978.7063880835792, 979.2410881566807, 980.4705459052103, 724.6159535446222, 725.0538047847557, 726.2483329753733, 727.6907314391362, 728.4318231779221, 14956.775648365283, 15281.0, 15629.531114066596, 16285.941413808183, 16479.670406591922, 14933.775993360108, 15150.621234469116, 15194.620134496727, 15197.544073677807, 15353.0, 11648.883511164864, 11966.581169659106, 12010.399480025964, 12025.759484810254, 12476.75046499074, 3783.9621603783885, 3906.921861562753, 3911.5892831252595, 3925.862594809168, 3928.292907276689, 110141.44929275382, 112205.87794122036, 112315.87684123137, 114133.0, 116242.41878790637, 10957.671269861758, 10977.689638076352, 11047.558097676163, 11181.72045698864, 11440.599579014695, 21931.67102493464, 21951.780482194972, 22004.77995220027, 22005.22981695633, 22006.229781957554, 8012.79968000793, 8106.837863242702, 8174.468659537111, 8274.586270686441, 8373.832523349622, 4408.977955110204, 4418.0, 4498.685092043525, 4546.272596384629, 4549.567791059844, 3733.831977561016, 3837.9616203838164, 3838.9040273992814, 3842.3275926713272, 3846.211526637033, 668.4084585142087, 676.5331920974512, 681.2234053179379, 704.8695991241534, 706.6678661029308, 676.5703778100941, 689.4381079420257, 712.1596516110935, 725.3689290317458, 751.7894989402976, 9100.544972751468, 10502.842457363146, 10748.193885458466, 10756.623518176826, 10788.838167427495, 691.6334342798325, 707.2821086597108, 716.8351279205708, 729.3435907683128, 735.9879222494827, 4891.095147397743, 4956.430010548761, 5005.189827937211, 5009.724465154379, 5035.924461133086, 71388.21529461804, 107883.46058269659, 112308.43845780882, 114000.0, 116239.0, 80649.0, 87755.12244877598, 95412.52293738487, 97919.51040244896, 101515.0, 56898.57753556194, 75561.6221918898, 86286.0, 88309.0, 89686.0, 10708.464576771286, 11068.5572577096, 11382.332437008201, 11477.311361318347, 11492.770144597229, 51126.488735113664, 52730.472695272554, 53107.0, 53244.0, 54560.72719636376, 62242.0, 69302.0, 70292.64853675904, 72068.27931720615, 73913.6304318475, 13230.66923326886, 13330.466781328638, 13374.264415456853, 13820.032597718466, 13883.375248114062, 14855.851441485447, 15096.320665569856, 15361.459249214144, 15393.999390039611, 15404.46084387041, 13718.794218086538, 13810.861891380957, 14186.219757913414, 14256.073355231876, 14276.500322489079, 13815.585532434043, 14347.641308967359, 14391.640208994859, 14399.49601763975, 14506.637334066732, 13622.727545449234, 13708.794368084287, 14534.200618966059, 14588.416463341347, 14800.48198313054, 11156.575283680024, 11545.422728863356, 11615.361155136548, 11811.940940295244, 12020.579279725167, 10656.733581660521, 11010.019008395515, 11156.944215278872, 11259.155563332619, 11282.041026512838], "value": 7888.977826675874, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 117.0, 119.0, 117.0, 117.0, 119.0, 118.0, 115.0, 119.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 130.0, 147.0, 187.0, 98.0, 147.0, 145.0, 111.0, 132.0, 119.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 283.0, 233.0, 199.0, 261.0, 251.0, 196.0, 195.0, 199.0], "value": 221.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 62.0, 84.0, 62.0, 83.0, 83.0, 69.0, 71.0, 59.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 81.0, 75.0, 70.0, 71.0, 79.0, 70.0, 71.0, 82.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 87.0, 116.0, 131.0, 94.0, 134.0, 136.0, 87.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 112.0, 99.0, 98.0, 99.0, 156.0, 115.0, 94.0, 96.0, 95.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 92.0, 135.0, 138.0, 94.0, 94.0, 143.0, 150.0, 93.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 58.0, 61.0, 62.0, 67.0, 84.0, 96.0, 98.0, 62.0], "value": 70.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 69.0, 86.0, 85.0, 93.0, 61.0, 85.0, 112.0, 106.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 86.0, 83.0, 86.0, 85.0, 84.0, 80.0, 96.0, 75.0, 105.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 132.0, 133.0, 150.0, 148.0, 144.0, 155.0, 167.0, 179.0, 137.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 147.0, 235.0, 177.0, 165.0, 164.0, 163.0, 164.0, 194.0, 238.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 68.0, 82.0, 76.0, 58.0, 57.0, 55.0, 85.0, 87.0], "value": 70.3, "unit": "score"}]}]} 17:14:04 INFO - Return code: 0 17:14:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:14:04 ERROR - # TBPL SUCCESS # 17:14:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:14:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:14:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:14:04 INFO - 2017-03-10 17:14:04.130 screenresolution[2753:48863] starting screenresolution argv=screenresolution get 17:14:04 INFO - 2017-03-10 17:14:04.133 screenresolution[2753:48863] Display 0: 1600x1200x32@60 17:14:04 INFO - 2017-03-10 17:14:04.139 screenresolution[2754:48866] starting screenresolution argv=screenresolution list 17:14:04 INFO - Available Modes on Display 0 17:14:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:14:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:14:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:14:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:14:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:14:04 INFO - Intel Iris: 17:14:04 INFO - Chipset Model: Intel Iris 17:14:04 INFO - Type: GPU 17:14:04 INFO - Bus: Built-In 17:14:04 INFO - VRAM (Dynamic, Max): 1536 MB 17:14:04 INFO - Vendor: Intel (0x8086) 17:14:04 INFO - Device ID: 0x0a2e 17:14:04 INFO - Revision ID: 0x0009 17:14:04 INFO - Displays: 17:14:04 INFO - Display: 17:14:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:14:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:14:04 INFO - Display Serial Number: 1600x1200 60 17:14:04 INFO - Main Display: Yes 17:14:04 INFO - Mirror: Off 17:14:04 INFO - Online: Yes 17:14:04 INFO - Rotation: Supported 17:14:04 INFO - Return code: 0 17:14:04 INFO - Running post-action listener: _resource_record_post_action 17:14:04 INFO - [mozharness: 2017-03-11 01:14:04.374642Z] Finished run-tests step (success) 17:14:04 INFO - Running post-run listener: _resource_record_post_run 17:14:04 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 958464; Write bytes: 423563264; Read time: 9; Write time: 1812 17:14:04 INFO - TinderboxPrint: CPU usage
54.2% 17:14:04 INFO - TinderboxPrint: I/O read bytes / time
958,464 / 9 17:14:04 INFO - TinderboxPrint: I/O write bytes / time
423,563,264 / 1,812 17:14:04 INFO - TinderboxPrint: CPU idle
2,180.4 (45.8%) 17:14:04 INFO - TinderboxPrint: CPU system
965.4 (20.3%) 17:14:04 INFO - TinderboxPrint: CPU user
1,615.9 (33.9%) 17:14:04 INFO - TinderboxPrint: Swap in / out
122,269,696 / 0 17:14:04 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 0; Write bytes: 122687488; Read time: 0; Write time: 250 17:14:04 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 864256; Write bytes: 299474944; Read time: 8; Write time: 1555 17:14:04 INFO - Running post-run listener: _upload_blobber_files 17:14:04 INFO - Blob upload gear active. 17:14:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:14:04 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 17:14:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:14:05 INFO - (blobuploader) - INFO - Open directory for files ... 17:14:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:14:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:14:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:05 INFO - (blobuploader) - INFO - Done attempting. 17:14:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:14:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:14:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:06 INFO - (blobuploader) - INFO - Done attempting. 17:14:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:14:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:14:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:07 INFO - (blobuploader) - INFO - Done attempting. 17:14:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:14:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:14:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:09 INFO - (blobuploader) - INFO - Done attempting. 17:14:09 INFO - (blobuploader) - INFO - Iteration through files over. 17:14:09 INFO - Return code: 0 17:14:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:14:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:14:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f7f45adea33c7208ec915e1a4c0203fea0d795d09c6da7917a5cd458f9552853a585f003c321558535df2e7f6c7b0faf6ff5e9f6f9c87c1328c9fd59cf8dd377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29f8a9bee5cb2bfbdb693331791677f6dfedd47a2a5c2af02ad473e0f8b83fc27b4caa77dc05c1490edd8f467f288c8ce10efbdbe748d877b9a99921c987b0cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e10ad845f8070ece94d047aac1cddee665f428e465348ad355b23334f47522976e1b6076aaed227ce7258bb3195c6adf93c550b710e70e28babc84fc965d4675", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60c42cc7c2795f300b78a93486fbf4a478c4f9ef710efedd39a5f9981a7f9037b0062e7be229e5d1eee00be4ebdc2ad2f75ce0d8666199337a45cdc02be4dea4"} 17:14:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:14:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:14:09 INFO - Contents: 17:14:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f7f45adea33c7208ec915e1a4c0203fea0d795d09c6da7917a5cd458f9552853a585f003c321558535df2e7f6c7b0faf6ff5e9f6f9c87c1328c9fd59cf8dd377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29f8a9bee5cb2bfbdb693331791677f6dfedd47a2a5c2af02ad473e0f8b83fc27b4caa77dc05c1490edd8f467f288c8ce10efbdbe748d877b9a99921c987b0cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e10ad845f8070ece94d047aac1cddee665f428e465348ad355b23334f47522976e1b6076aaed227ce7258bb3195c6adf93c550b710e70e28babc84fc965d4675", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60c42cc7c2795f300b78a93486fbf4a478c4f9ef710efedd39a5f9981a7f9037b0062e7be229e5d1eee00be4ebdc2ad2f75ce0d8666199337a45cdc02be4dea4"} 17:14:09 INFO - Running post-run listener: copy_logs_to_upload_dir 17:14:09 INFO - Copying logs to upload dir... 17:14:09 INFO - mkdir: /builds/slave/test/build/upload/logs 17:14:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.815416 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-10 17:14:10.044643) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-10 17:14:10.047943) ========= 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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f7f45adea33c7208ec915e1a4c0203fea0d795d09c6da7917a5cd458f9552853a585f003c321558535df2e7f6c7b0faf6ff5e9f6f9c87c1328c9fd59cf8dd377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29f8a9bee5cb2bfbdb693331791677f6dfedd47a2a5c2af02ad473e0f8b83fc27b4caa77dc05c1490edd8f467f288c8ce10efbdbe748d877b9a99921c987b0cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e10ad845f8070ece94d047aac1cddee665f428e465348ad355b23334f47522976e1b6076aaed227ce7258bb3195c6adf93c550b710e70e28babc84fc965d4675", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60c42cc7c2795f300b78a93486fbf4a478c4f9ef710efedd39a5f9981a7f9037b0062e7be229e5d1eee00be4ebdc2ad2f75ce0d8666199337a45cdc02be4dea4"} build_url:https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/DrP2yYsiSD6pde-jJvMZvA/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f7f45adea33c7208ec915e1a4c0203fea0d795d09c6da7917a5cd458f9552853a585f003c321558535df2e7f6c7b0faf6ff5e9f6f9c87c1328c9fd59cf8dd377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29f8a9bee5cb2bfbdb693331791677f6dfedd47a2a5c2af02ad473e0f8b83fc27b4caa77dc05c1490edd8f467f288c8ce10efbdbe748d877b9a99921c987b0cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e10ad845f8070ece94d047aac1cddee665f428e465348ad355b23334f47522976e1b6076aaed227ce7258bb3195c6adf93c550b710e70e28babc84fc965d4675", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60c42cc7c2795f300b78a93486fbf4a478c4f9ef710efedd39a5f9981a7f9037b0062e7be229e5d1eee00be4ebdc2ad2f75ce0d8666199337a45cdc02be4dea4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-10 17:14:10.082940) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 17:14:10.083355) ========= 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.OyI4WBLaMO/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.Caz5Iigc5D/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005473 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 17:14:10.132482) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-10 17:14:10.132813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-10 17:14:10.133153) ========= ========= Total master_lag: 0.33 =========