builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0207 starttime: 1487607500.25 results: success (0) buildid: 20170220065356 builduid: 057b554b936845118ecae128afce2f0b revision: d5727ae5c683f17949ce046f45b1c85ee707c523 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:20.254564) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:20.255146) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:20.255472) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:20.281613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:20.282240) ========= 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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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-02-20 08:18:20-- 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% 8.02M=0.001s 2017-02-20 08:18:20 (8.02 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113097 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:20.421191) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:20.421574) ========= 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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.022483 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:20.470784) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-20 08:18:20.471172) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d5727ae5c683f17949ce046f45b1c85ee707c523 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d5727ae5c683f17949ce046f45b1c85ee707c523 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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-02-20 08:18:20,562 truncating revision to first 12 chars 2017-02-20 08:18:20,562 Setting DEBUG logging. 2017-02-20 08:18:20,562 attempt 1/10 2017-02-20 08:18:20,563 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d5727ae5c683?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 08:18:22,546 unpacking tar archive at: mozilla-aurora-d5727ae5c683/testing/mozharness/ program finished with exit code 0 elapsedTime=2.244705 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-20 08:18:22.740931) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:22.741295) ========= script_repo_revision: d5727ae5c683f17949ce046f45b1c85ee707c523 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:22.741713) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:22.742028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 08:18:22.762149) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-20 08:18:22.762496) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:18:22 INFO - MultiFileLogger online at 20170220 08:18:22 in /builds/slave/test 08:18:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:18:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:18:22 INFO - {'append_to_log': False, 08:18:22 INFO - 'base_work_dir': '/builds/slave/test', 08:18:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:18:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:18:22 INFO - 'branch': 'Mozilla-Aurora', 08:18:22 INFO - 'buildbot_json_path': 'buildprops.json', 08:18:22 INFO - 'config_files': ('talos/mac_config.py',), 08:18:22 INFO - 'default_actions': ('clobber', 08:18:22 INFO - 'read-buildbot-config', 08:18:22 INFO - 'download-and-extract', 08:18:22 INFO - 'populate-webroot', 08:18:22 INFO - 'create-virtualenv', 08:18:22 INFO - 'install', 08:18:22 INFO - 'run-tests'), 08:18:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:18:22 INFO - 'download_minidump_stackwalk': True, 08:18:22 INFO - 'download_symbols': 'ondemand', 08:18:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:18:22 INFO - 'tooltool.py': '/tools/tooltool.py', 08:18:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:18:22 INFO - '/tools/misc-python/virtualenv.py')}, 08:18:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:18:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:18:22 INFO - 'installer_path': 'installer.exe', 08:18:22 INFO - 'log_level': 'info', 08:18:22 INFO - 'log_name': 'talos', 08:18:22 INFO - 'log_to_console': True, 08:18:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:18:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:18:22 INFO - 'opt_config_files': (), 08:18:22 INFO - 'pip_index': False, 08:18:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:18:22 INFO - 'cmd': ('bash', 08:18:22 INFO - '-c', 08:18:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:18:22 INFO - 'enabled': True, 08:18:22 INFO - 'halt_on_failure': False, 08:18:22 INFO - 'name': 'check_screen_resolution'},), 08:18:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:18:22 INFO - 'cmd': ('bash', 08:18:22 INFO - '-c', 08:18:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:18:22 INFO - 'enabled': True, 08:18:22 INFO - 'halt_on_failure': False, 08:18:22 INFO - 'name': 'check_screen_resolution'},), 08:18:22 INFO - 'run_cmd_checks_enabled': True, 08:18:22 INFO - 'sps_profile': False, 08:18:22 INFO - 'sps_profile_interval': 0, 08:18:22 INFO - 'suite': 'dromaeojs-e10s', 08:18:22 INFO - 'system_bits': '32', 08:18:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:18:22 INFO - 'title': 't-yosemite-r7-0207', 08:18:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:18:22 INFO - 'use_talos_json': True, 08:18:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:18:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:18:22 INFO - 'work_dir': 'build'} 08:18:22 INFO - [mozharness: 2017-02-20 16:18:22.971510Z] Running clobber step. 08:18:22 INFO - Running pre-action listener: _resource_record_pre_action 08:18:22 INFO - Running main action method: clobber 08:18:22 INFO - rmtree: /builds/slave/test/build 08:18:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:18:23 INFO - Running post-action listener: _resource_record_post_action 08:18:23 INFO - [mozharness: 2017-02-20 16:18:23.388160Z] Finished clobber step (success) 08:18:23 INFO - [mozharness: 2017-02-20 16:18:23.388355Z] Running read-buildbot-config step. 08:18:23 INFO - Running pre-action listener: _resource_record_pre_action 08:18:23 INFO - Running main action method: read_buildbot_config 08:18:23 INFO - Using buildbot properties: 08:18:23 INFO - { 08:18:23 INFO - "project": "", 08:18:23 INFO - "product": "firefox", 08:18:23 INFO - "script_repo_revision": "production", 08:18:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:18:23 INFO - "repository": "", 08:18:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:18:23 INFO - "buildid": "20170220065356", 08:18:23 INFO - "pgo_build": "False", 08:18:23 INFO - "basedir": "/builds/slave/test", 08:18:23 INFO - "buildnumber": 46, 08:18:23 INFO - "slavename": "t-yosemite-r7-0207", 08:18:23 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:18:23 INFO - "platform": "yosemite_r7", 08:18:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:18:23 INFO - "branch": "mozilla-aurora", 08:18:23 INFO - "repo_path": "releases/mozilla-aurora", 08:18:23 INFO - "revision": "d5727ae5c683f17949ce046f45b1c85ee707c523", 08:18:23 INFO - "stage_platform": "macosx64", 08:18:23 INFO - "builduid": "057b554b936845118ecae128afce2f0b", 08:18:23 INFO - "slavebuilddir": "test" 08:18:23 INFO - } 08:18:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 08:18:23 INFO - Running post-action listener: _resource_record_post_action 08:18:23 INFO - [mozharness: 2017-02-20 16:18:23.391816Z] Finished read-buildbot-config step (success) 08:18:23 INFO - [mozharness: 2017-02-20 16:18:23.391908Z] Running download-and-extract step. 08:18:23 INFO - Running pre-action listener: _resource_record_pre_action 08:18:23 INFO - Running main action method: download_and_extract 08:18:23 INFO - mkdir: /builds/slave/test/build/tests 08:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:23 INFO - trying https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 08:18:23 INFO - Downloading https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 08:18:24 INFO - Downloaded 1199 bytes. 08:18:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:18:24 INFO - Using the following test package requirements: 08:18:24 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:18:24 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:18:24 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 08:18:24 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:18:24 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 08:18:24 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:18:24 INFO - u'jsshell-mac.zip'], 08:18:24 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:18:24 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 08:18:24 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:18:24 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:18:24 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 08:18:24 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:18:24 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 08:18:24 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:18:24 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 08:18:24 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:18:24 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 08:18:24 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:18:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:18:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:18:24 INFO - Fetch https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:18:25 INFO - Expected file size: 16359160 08:18:25 INFO - Obtained file size: 16359160 08:18:32 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:18:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:18:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:18:32 INFO - Fetch https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:18:33 INFO - Expected file size: 16359160 08:18:33 INFO - Obtained file size: 16359160 08:18:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 08:18:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:18:39 INFO - Fetch https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 08:18:40 INFO - Expected file size: 14403485 08:18:40 INFO - Obtained file size: 14403485 08:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:40 INFO - trying https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:18:40 INFO - Downloading https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 08:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 08:18:42 INFO - Downloaded 73962346 bytes. 08:18:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:18:42 INFO - mkdir: /builds/slave/test/properties 08:18:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:18:42 INFO - Writing to file /builds/slave/test/properties/build_url 08:18:42 INFO - Contents: 08:18:42 INFO - build_url:https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:18:44 INFO - Running post-action listener: _resource_record_post_action 08:18:44 INFO - Running post-action listener: set_extra_try_arguments 08:18:44 INFO - [mozharness: 2017-02-20 16:18:44.062676Z] Finished download-and-extract step (success) 08:18:44 INFO - [mozharness: 2017-02-20 16:18:44.062832Z] Running populate-webroot step. 08:18:44 INFO - Running pre-action listener: _resource_record_pre_action 08:18:44 INFO - Running main action method: populate_webroot 08:18:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:18:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:18:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:18:44 INFO - '--tppagecycles', 08:18:44 INFO - '7'], 08:18:44 INFO - 'tests': ['tsvgm']}}, 08:18:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:18:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:18:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:18:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:18:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:18:44 INFO - 'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:18:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:18:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:18:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:18:44 INFO - 'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['damp', 'tps']}, 08:18:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:18:44 INFO - 'tests': ['damp', 'tps']}, 08:18:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['dromaeo_dom']}, 08:18:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:18:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['basic_compositor_video']}, 08:18:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:18:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['a11yr', 08:18:44 INFO - 'ts_paint', 08:18:44 INFO - 'tpaint', 08:18:44 INFO - 'sessionrestore', 08:18:44 INFO - 'sessionrestore_no_auto_restore', 08:18:44 INFO - 'tabpaint']}, 08:18:44 INFO - 'other-e10s': {'tests': ['a11yr', 08:18:44 INFO - 'ts_paint', 08:18:44 INFO - 'tpaint', 08:18:44 INFO - 'sessionrestore', 08:18:44 INFO - 'sessionrestore_no_auto_restore', 08:18:44 INFO - 'tabpaint']}, 08:18:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:18:44 INFO - 'ts_paint', 08:18:44 INFO - 'tpaint', 08:18:44 INFO - 'sessionrestore', 08:18:44 INFO - 'sessionrestore_no_auto_restore', 08:18:44 INFO - 'tabpaint']}, 08:18:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:18:44 INFO - 'ts_paint', 08:18:44 INFO - 'tpaint', 08:18:44 INFO - 'sessionrestore', 08:18:44 INFO - 'sessionrestore_no_auto_restore', 08:18:44 INFO - 'tabpaint']}, 08:18:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['a11yr', 08:18:44 INFO - 'ts_paint', 08:18:44 INFO - 'tpaint', 08:18:44 INFO - 'sessionrestore', 08:18:44 INFO - 'sessionrestore_no_auto_restore', 08:18:44 INFO - 'tabpaint']}, 08:18:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['a11yr', 08:18:44 INFO - 'ts_paint', 08:18:44 INFO - 'tpaint', 08:18:44 INFO - 'sessionrestore', 08:18:44 INFO - 'sessionrestore_no_auto_restore', 08:18:44 INFO - 'tabpaint']}, 08:18:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['tsvgx', 08:18:44 INFO - 'tsvgr_opacity', 08:18:44 INFO - 'tart', 08:18:44 INFO - 'tscrollx', 08:18:44 INFO - 'cart', 08:18:44 INFO - 'tsvg_static']}, 08:18:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:18:44 INFO - 'tsvgr_opacity', 08:18:44 INFO - 'tart', 08:18:44 INFO - 'tscrollx', 08:18:44 INFO - 'cart', 08:18:44 INFO - 'tsvg_static']}, 08:18:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:18:44 INFO - 'talos_options': ['--disable-e10s'], 08:18:44 INFO - 'tests': ['tp5o']}, 08:18:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:18:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:18:44 INFO - 'talos_options': ['--disable-e10s', 08:18:44 INFO - '--xperf_path', 08:18:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:18:44 INFO - 'tests': ['tp5n']}, 08:18:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:18:44 INFO - 'talos_options': ['--xperf_path', 08:18:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:18:44 INFO - 'tests': ['tp5n']}}, 08:18:44 INFO - 'talos.zip': {'path': '', 08:18:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:18:44 INFO - Running post-action listener: _resource_record_post_action 08:18:44 INFO - [mozharness: 2017-02-20 16:18:44.081747Z] Finished populate-webroot step (success) 08:18:44 INFO - [mozharness: 2017-02-20 16:18:44.081877Z] Running create-virtualenv step. 08:18:44 INFO - Running pre-action listener: _resource_record_pre_action 08:18:44 INFO - Running main action method: create_virtualenv 08:18:44 INFO - Creating virtualenv /builds/slave/test/build/venv 08:18:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:18:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:18:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:18:44 INFO - Using real prefix '/tools/python27' 08:18:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:18:44 INFO - Installing distribute.............................................................................................................................................................................................done. 08:18:47 INFO - Installing pip.................done. 08:18:47 INFO - Return code: 0 08:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:18:48 INFO - Reading from file tmpfile_stdout 08:18:48 INFO - Output received: 08:18:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:18:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:48 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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:48 INFO - 'HOME': '/Users/cltbld', 08:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:48 INFO - 'LOGNAME': 'cltbld', 08:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:48 INFO - 'MOZ_NO_REMOTE': '1', 08:18:48 INFO - 'NO_EM_RESTART': '1', 08:18:48 INFO - 'PAGER': '/bin/cat', 08:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:48 INFO - 'PWD': '/builds/slave/test', 08:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:48 INFO - 'SHELL': '/bin/bash', 08:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:18:48 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:48 INFO - 'USER': 'cltbld', 08:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:48 INFO - 'XPC_FLAGS': '0x0', 08:18:48 INFO - 'XPC_SERVICE_NAME': '0', 08:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:18:48 INFO - Cleaning up... 08:18:48 INFO - Return code: 0 08:18:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:48 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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:48 INFO - 'HOME': '/Users/cltbld', 08:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:48 INFO - 'LOGNAME': 'cltbld', 08:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:48 INFO - 'MOZ_NO_REMOTE': '1', 08:18:48 INFO - 'NO_EM_RESTART': '1', 08:18:48 INFO - 'PAGER': '/bin/cat', 08:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:48 INFO - 'PWD': '/builds/slave/test', 08:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:48 INFO - 'SHELL': '/bin/bash', 08:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:18:48 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:48 INFO - 'USER': 'cltbld', 08:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:48 INFO - 'XPC_FLAGS': '0x0', 08:18:48 INFO - 'XPC_SERVICE_NAME': '0', 08:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:48 INFO - Downloading/unpacking psutil>=3.1.1 08:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:18:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:18:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:18:52 INFO - Installing collected packages: psutil 08:18:52 INFO - Running setup.py install for psutil 08:18:52 INFO - building 'psutil._psutil_osx' extension 08:18:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:18:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:18:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:18:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:18:52 INFO - building 'psutil._psutil_posix' extension 08:18:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:18:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:18:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:18:52 INFO - ^ 08:18:52 INFO - 1 warning generated. 08:18:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:18:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:18:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:18:52 INFO - Successfully installed psutil 08:18:52 INFO - Cleaning up... 08:18:52 INFO - Return code: 0 08:18:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:18: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')]} 08:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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')]} 08:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:18: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:18: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 mozsystemmonitor==0.3 08:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:52 INFO - 'HOME': '/Users/cltbld', 08:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:52 INFO - 'LOGNAME': 'cltbld', 08:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:52 INFO - 'MOZ_NO_REMOTE': '1', 08:18:52 INFO - 'NO_EM_RESTART': '1', 08:18:52 INFO - 'PAGER': '/bin/cat', 08:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:52 INFO - 'PWD': '/builds/slave/test', 08:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:52 INFO - 'SHELL': '/bin/bash', 08:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:18:52 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:52 INFO - 'USER': 'cltbld', 08:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:52 INFO - 'XPC_FLAGS': '0x0', 08:18:52 INFO - 'XPC_SERVICE_NAME': '0', 08:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:18:55 INFO - Installing collected packages: mozsystemmonitor 08:18:55 INFO - Running setup.py install for mozsystemmonitor 08:18:56 INFO - Successfully installed mozsystemmonitor 08:18:56 INFO - Cleaning up... 08:18:56 INFO - Return code: 0 08:18:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:18: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')]} 08:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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')]} 08:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18: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', '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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:18: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:18: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 jsonschema==2.5.1 08:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:56 INFO - 'HOME': '/Users/cltbld', 08:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:56 INFO - 'LOGNAME': 'cltbld', 08:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:56 INFO - 'MOZ_NO_REMOTE': '1', 08:18:56 INFO - 'NO_EM_RESTART': '1', 08:18:56 INFO - 'PAGER': '/bin/cat', 08:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:56 INFO - 'PWD': '/builds/slave/test', 08:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:56 INFO - 'SHELL': '/bin/bash', 08:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:18:56 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:56 INFO - 'USER': 'cltbld', 08:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:56 INFO - 'XPC_FLAGS': '0x0', 08:18:56 INFO - 'XPC_SERVICE_NAME': '0', 08:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:56 INFO - Downloading/unpacking jsonschema==2.5.1 08:18: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 08:18: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 08:18: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 08:18: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 08:18: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 08:18: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 08:18:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:18:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:18: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 08:18: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 08:18: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 08:18: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 08:18: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 08:18: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 08:18:59 INFO - Downloading functools32-3.2.3-2.tar.gz 08:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:18:59 INFO - warning: no files found matching '*.txt' 08:18:59 INFO - no previously-included directories found matching 'build' 08:18:59 INFO - no previously-included directories found matching 'dist' 08:18:59 INFO - no previously-included directories found matching '.git*' 08:18:59 INFO - Installing collected packages: jsonschema, functools32 08:18:59 INFO - Running setup.py install for functools32 08:18:59 INFO - warning: no files found matching '*.txt' 08:18:59 INFO - no previously-included directories found matching 'build' 08:18:59 INFO - no previously-included directories found matching 'dist' 08:18:59 INFO - no previously-included directories found matching '.git*' 08:18:59 INFO - Successfully installed jsonschema functools32 08:18:59 INFO - Cleaning up... 08:18:59 INFO - Return code: 0 08:18:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:59 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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:18:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:59 INFO - 'HOME': '/Users/cltbld', 08:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:59 INFO - 'LOGNAME': 'cltbld', 08:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:59 INFO - 'MOZ_NO_REMOTE': '1', 08:18:59 INFO - 'NO_EM_RESTART': '1', 08:18:59 INFO - 'PAGER': '/bin/cat', 08:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:59 INFO - 'PWD': '/builds/slave/test', 08:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:59 INFO - 'SHELL': '/bin/bash', 08:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:18:59 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:59 INFO - 'USER': 'cltbld', 08:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:59 INFO - 'XPC_FLAGS': '0x0', 08:18:59 INFO - 'XPC_SERVICE_NAME': '0', 08:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:19:00 INFO - Cleaning up... 08:19:00 INFO - Return code: 0 08:19:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:19:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:19:00 INFO - 'HOME': '/Users/cltbld', 08:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:19:00 INFO - 'LOGNAME': 'cltbld', 08:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:00 INFO - 'MOZ_NO_REMOTE': '1', 08:19:00 INFO - 'NO_EM_RESTART': '1', 08:19:00 INFO - 'PAGER': '/bin/cat', 08:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:00 INFO - 'PWD': '/builds/slave/test', 08:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:19:00 INFO - 'SHELL': '/bin/bash', 08:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:19:00 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:19:00 INFO - 'USER': 'cltbld', 08:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:00 INFO - 'XPC_FLAGS': '0x0', 08:19:00 INFO - 'XPC_SERVICE_NAME': '0', 08:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:00 INFO - Downloading/unpacking blobuploader==1.2.4 08:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:03 INFO - Downloading blobuploader-1.2.4.tar.gz 08:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:19:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:19:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:04 INFO - Downloading docopt-0.6.1.tar.gz 08:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:19:04 INFO - Installing collected packages: blobuploader, requests, docopt 08:19:04 INFO - Running setup.py install for blobuploader 08:19:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:19:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:19:04 INFO - Running setup.py install for requests 08:19:04 INFO - Running setup.py install for docopt 08:19:05 INFO - Successfully installed blobuploader requests docopt 08:19:05 INFO - Cleaning up... 08:19:05 INFO - Return code: 0 08:19:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:19: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')]} 08:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:06 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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:19:06 INFO - 'HOME': '/Users/cltbld', 08:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:19:06 INFO - 'LOGNAME': 'cltbld', 08:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:06 INFO - 'MOZ_NO_REMOTE': '1', 08:19:06 INFO - 'NO_EM_RESTART': '1', 08:19:06 INFO - 'PAGER': '/bin/cat', 08:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:06 INFO - 'PWD': '/builds/slave/test', 08:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:19:06 INFO - 'SHELL': '/bin/bash', 08:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:19:06 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:19:06 INFO - 'USER': 'cltbld', 08:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:06 INFO - 'XPC_FLAGS': '0x0', 08:19:06 INFO - 'XPC_SERVICE_NAME': '0', 08:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:19:06 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-ww3uHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:19:06 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-nhf5yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-DgP571-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-qyVGyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-oTGhrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-K6bKKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-jFiMbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-_BLAQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-EEkDaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Pp6nxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Z1_8Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:19:07 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-9KtWch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:19:08 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-vpOFUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:19:08 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-eVf7LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:19:08 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-ZSVkwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:19:08 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-i3N10V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:19:08 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-VgK5CN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:19:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:19:08 INFO - Running setup.py install for manifestparser 08:19:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:19:08 INFO - Running setup.py install for mozcrash 08:19:08 INFO - Running setup.py install for mozdebug 08:19:09 INFO - Running setup.py install for mozdevice 08:19:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:19:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:19:09 INFO - Running setup.py install for mozfile 08:19:09 INFO - Running setup.py install for mozhttpd 08:19:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:19:09 INFO - Running setup.py install for mozinfo 08:19:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:19:09 INFO - Running setup.py install for mozInstall 08:19:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:19:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:19:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:19:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:19:09 INFO - Running setup.py install for mozleak 08:19:09 INFO - Running setup.py install for mozlog 08:19:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:19:10 INFO - Running setup.py install for moznetwork 08:19:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:19:10 INFO - Running setup.py install for mozprocess 08:19:10 INFO - Running setup.py install for mozprofile 08:19:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:19:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:19:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:19:10 INFO - Running setup.py install for mozrunner 08:19:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:19:10 INFO - Running setup.py install for mozscreenshot 08:19:10 INFO - Running setup.py install for moztest 08:19:11 INFO - Running setup.py install for mozversion 08:19:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:19:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:19:11 INFO - Cleaning up... 08:19:11 INFO - Return code: 0 08:19:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:19: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')]} 08:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19: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')]} 08:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:11 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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:19:11 INFO - 'HOME': '/Users/cltbld', 08:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:19:11 INFO - 'LOGNAME': 'cltbld', 08:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:11 INFO - 'MOZ_NO_REMOTE': '1', 08:19:11 INFO - 'NO_EM_RESTART': '1', 08:19:11 INFO - 'PAGER': '/bin/cat', 08:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:11 INFO - 'PWD': '/builds/slave/test', 08:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:19:11 INFO - 'SHELL': '/bin/bash', 08:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:19:11 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:19:11 INFO - 'USER': 'cltbld', 08:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:11 INFO - 'XPC_FLAGS': '0x0', 08:19:11 INFO - 'XPC_SERVICE_NAME': '0', 08:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:19:11 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-OtDln0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:19:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:19:11 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-dBItOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-81MUq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Eizohz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-C8Mdam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-3B7KUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-GPi6pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-AaMFix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-jqojUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-iEfeQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:19:12 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-gxDgLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:19:13 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-CYLcBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:19:13 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Q9lSh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:19:13 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-snApfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:19:13 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-1UDqrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:19:13 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-4XggDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:19:13 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-vOGhj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:19:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:16 INFO - Downloading blessings-1.6.tar.gz 08:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:19:16 INFO - Installing collected packages: blessings 08:19:16 INFO - Running setup.py install for blessings 08:19:16 INFO - Successfully installed blessings 08:19:16 INFO - Cleaning up... 08:19:16 INFO - Return code: 0 08:19:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:19:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:19:17 INFO - Reading from file tmpfile_stdout 08:19:17 INFO - Current package versions: 08:19:17 INFO - blessings == 1.6 08:19:17 INFO - blobuploader == 1.2.4 08:19:17 INFO - docopt == 0.6.1 08:19:17 INFO - functools32 == 3.2.3-2 08:19:17 INFO - jsonschema == 2.5.1 08:19:17 INFO - manifestparser == 1.1 08:19:17 INFO - mozInstall == 1.12 08:19:17 INFO - mozcrash == 1.0 08:19:17 INFO - mozdebug == 0.1 08:19:17 INFO - mozdevice == 0.49 08:19:17 INFO - mozfile == 1.2 08:19:17 INFO - mozhttpd == 0.7 08:19:17 INFO - mozinfo == 0.9 08:19:17 INFO - mozleak == 0.1 08:19:17 INFO - mozlog == 3.4 08:19:17 INFO - moznetwork == 0.27 08:19:17 INFO - mozprocess == 0.24 08:19:17 INFO - mozprofile == 0.28 08:19:17 INFO - mozrunner == 6.13 08:19:17 INFO - mozscreenshot == 0.1 08:19:17 INFO - mozsystemmonitor == 0.3 08:19:17 INFO - moztest == 0.8 08:19:17 INFO - mozversion == 1.4 08:19:17 INFO - psutil == 3.1.1 08:19:17 INFO - requests == 1.2.3 08:19:17 INFO - wsgiref == 0.1.2 08:19:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:17 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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:19:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:19:17 INFO - 'HOME': '/Users/cltbld', 08:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:19:17 INFO - 'LOGNAME': 'cltbld', 08:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:17 INFO - 'MOZ_NO_REMOTE': '1', 08:19:17 INFO - 'NO_EM_RESTART': '1', 08:19:17 INFO - 'PAGER': '/bin/cat', 08:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:17 INFO - 'PWD': '/builds/slave/test', 08:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:19:17 INFO - 'SHELL': '/bin/bash', 08:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:19:17 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:19:17 INFO - 'USER': 'cltbld', 08:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:17 INFO - 'XPC_FLAGS': '0x0', 08:19:17 INFO - 'XPC_SERVICE_NAME': '0', 08:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:19:17 INFO - Cleaning up... 08:19:17 INFO - Return code: 0 08:19:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:17 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 0x103d2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7785b7df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, '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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:19:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:19:17 INFO - 'HOME': '/Users/cltbld', 08:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:19:17 INFO - 'LOGNAME': 'cltbld', 08:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:17 INFO - 'MOZ_NO_REMOTE': '1', 08:19:17 INFO - 'NO_EM_RESTART': '1', 08:19:17 INFO - 'PAGER': '/bin/cat', 08:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:17 INFO - 'PWD': '/builds/slave/test', 08:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:19:17 INFO - 'SHELL': '/bin/bash', 08:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:19:17 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:19:17 INFO - 'USER': 'cltbld', 08:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:17 INFO - 'XPC_FLAGS': '0x0', 08:19:17 INFO - 'XPC_SERVICE_NAME': '0', 08:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:19:17 INFO - Cleaning up... 08:19:17 INFO - Return code: 0 08:19:17 INFO - Running post-action listener: _resource_record_post_action 08:19:17 INFO - Running post-action listener: _start_resource_monitoring 08:19:17 INFO - Starting resource monitoring. 08:19:17 INFO - [mozharness: 2017-02-20 16:19:17.849567Z] Finished create-virtualenv step (success) 08:19:17 INFO - [mozharness: 2017-02-20 16:19:17.850573Z] Running install step. 08:19:17 INFO - Running pre-action listener: _resource_record_pre_action 08:19:17 INFO - Running main action method: install 08:19:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:19:18 INFO - Reading from file tmpfile_stdout 08:19:18 INFO - Detecting whether we're running mozinstall >=1.0... 08:19:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:19:18 INFO - Reading from file tmpfile_stdout 08:19:18 INFO - Output received: 08:19:18 INFO - Usage: mozinstall [options] installer 08:19:18 INFO - Options: 08:19:18 INFO - -h, --help show this help message and exit 08:19:18 INFO - -d DEST, --destination=DEST 08:19:18 INFO - Directory to install application into. [default: 08:19:18 INFO - "/builds/slave/test"] 08:19:18 INFO - --app=APP Application being installed. [default: firefox] 08:19:18 INFO - mkdir: /builds/slave/test/build/application 08:19:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:19:38 INFO - Reading from file tmpfile_stdout 08:19:38 INFO - Output received: 08:19:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:19:38 INFO - Running post-action listener: _resource_record_post_action 08:19:38 INFO - [mozharness: 2017-02-20 16:19:38.190991Z] Finished install step (success) 08:19:38 INFO - [mozharness: 2017-02-20 16:19:38.191213Z] Running run-tests step. 08:19:38 INFO - Running pre-action listener: _resource_record_pre_action 08:19:38 INFO - Running main action method: run_tests 08:19:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:19:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:19:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:19:38 INFO - 2017-02-20 08:19:38.206 screenresolution[3405:324818] starting screenresolution argv=screenresolution get 08:19:38 INFO - 2017-02-20 08:19:38.208 screenresolution[3405:324818] Display 0: 1600x1200x32@60 08:19:38 INFO - 2017-02-20 08:19:38.214 screenresolution[3406:324821] starting screenresolution argv=screenresolution list 08:19:38 INFO - Available Modes on Display 0 08:19:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:19:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:19:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:19:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:19:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:19:38 INFO - Intel Iris: 08:19:38 INFO - Chipset Model: Intel Iris 08:19:38 INFO - Type: GPU 08:19:38 INFO - Bus: Built-In 08:19:38 INFO - VRAM (Dynamic, Max): 1536 MB 08:19:38 INFO - Vendor: Intel (0x8086) 08:19:38 INFO - Device ID: 0x0a2e 08:19:38 INFO - Revision ID: 0x0009 08:19:38 INFO - Displays: 08:19:38 INFO - Display: 08:19:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:19:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:19:38 INFO - Display Serial Number: 1600x1200 60 08:19:38 INFO - Main Display: Yes 08:19:38 INFO - Mirror: Off 08:19:38 INFO - Online: Yes 08:19:38 INFO - Rotation: Supported 08:19:38 INFO - Return code: 0 08:19:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:19:38 INFO - Python 2.7.3 08:19:38 INFO - Return code: 0 08:19:38 INFO - grabbing minidump binary from tooltool 08:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:38 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 0x103b0bd30>, '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 0x103a32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b00be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:19:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:19:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:19:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:19:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:19:38 INFO - Return code: 0 08:19:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:19:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:19:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:19:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:19:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:19:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0207 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:19:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqr76TDLJ6/Render', 08:19:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:19:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:19:38 INFO - 'HOME': '/Users/cltbld', 08:19:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:19:38 INFO - 'LOGNAME': 'cltbld', 08:19:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:19:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:38 INFO - 'MOZ_NO_REMOTE': '1', 08:19:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:19:38 INFO - 'NO_EM_RESTART': '1', 08:19:38 INFO - 'PAGER': '/bin/cat', 08:19:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:38 INFO - 'PWD': '/builds/slave/test', 08:19:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:19:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:19:38 INFO - 'SHELL': '/bin/bash', 08:19:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xJV6ebJ6B4/Listeners', 08:19:38 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 08:19:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:19:38 INFO - 'USER': 'cltbld', 08:19:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:19:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:38 INFO - 'XPC_FLAGS': '0x0', 08:19:38 INFO - 'XPC_SERVICE_NAME': '0', 08:19:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:19:38 INFO - mozversion application_buildid: 20170220065356 08:19:38 INFO - mozversion application_changeset: d5727ae5c683f17949ce046f45b1c85ee707c523 08:19:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:19:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:19:38 INFO - mozversion application_name: Firefox 08:19:38 INFO - mozversion application_remotingname: firefox-dev 08:19:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:19:38 INFO - mozversion application_vendor: Mozilla 08:19:38 INFO - mozversion application_version: 53.0a2 08:19:38 INFO - mozversion platform_buildid: 20170220065356 08:19:38 INFO - mozversion platform_changeset: d5727ae5c683f17949ce046f45b1c85ee707c523 08:19:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:19:38 INFO - mozversion platform_version: 53.0a2 08:19:38 INFO - using testdate: 1487607578 08:19:38 INFO - actual date: 1487607578 08:19:38 INFO - starting webserver on 'localhost:52482' 08:19:38 INFO - SUITE-START | Running 2 tests 08:19:38 INFO - TEST-START | dromaeo_css 08:19:38 INFO - operating with platform_type : mac_ 08:19:38 INFO - Initialising browser for dromaeo_css test... 08:19:38 INFO - TEST-INFO | started process 3416 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpi2DQMB/profile http://localhost:52482/getInfo.html) 08:19:42 INFO - PROCESS | 3416 | 2017-02-20 08:19:41.999 plugin-container[3419:324989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 08:19:42 INFO - PROCESS | 3416 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:42 INFO - PROCESS | 3416 | 2017-02-20 08:19:42.003 plugin-container[3419:324989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 08:19:42 INFO - PROCESS | 3416 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:42 INFO - PROCESS | 3416 | 2017-02-20 08:19:42.003 plugin-container[3419:324989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:19:42 INFO - PROCESS | 3416 | __metrics Screen width/height:1600/1200 08:19:42 INFO - PROCESS | 3416 | colorDepth:24 08:19:42 INFO - PROCESS | 3416 | Browser inner width/height: 1024/689 08:19:42 INFO - PROCESS | 3416 | __metrics 08:19:42 ERROR - PROCESS | 3416 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 08:19:42 INFO - TEST-INFO | 3416: exit 0 08:19:42 INFO - Browser initialized. 08:19:42 INFO - Running cycle 1/1 for dromaeo_css test... 08:19:42 INFO - TEST-INFO | started process 3420 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpi2DQMB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:19:45 INFO - PROCESS | 3420 | 2017-02-20 08:19:45.338 plugin-container[3421:325120] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 08:19:45 INFO - PROCESS | 3420 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:45 INFO - PROCESS | 3420 | 2017-02-20 08:19:45.338 plugin-container[3421:325120] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450b, name = 'com.apple.CFPasteboardClient' 08:19:45 INFO - PROCESS | 3420 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:45 INFO - PROCESS | 3420 | 2017-02-20 08:19:45.338 plugin-container[3421:325120] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:19:54 INFO - PROCESS | 3420 | RSS: Main: 219082752 08:19:54 INFO - PROCESS | 3420 | 08:22:57 INFO - PROCESS | 3420 | Cycle 1(1): loaded http://localhost:52482/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52482/tests/dromaeo/cssquery-ext.html) 08:22:59 INFO - PROCESS | 3420 | RSS: Main: 239861760 08:22:59 INFO - PROCESS | 3420 | 08:24:43 INFO - PROCESS | 3420 | ************************* 08:24:43 INFO - PROCESS | 3420 | A coding exception was thrown and uncaught in a Task. 08:24:43 INFO - PROCESS | 3420 | 08:24:43 ERROR - PROCESS | 3420 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:24:43 INFO - PROCESS | 3420 | Full stack: 08:24:43 INFO - PROCESS | 3420 | ************************* 08:26:02 INFO - PROCESS | 3420 | Cycle 1(1): loaded http://localhost:52482/tests/dromaeo/cssquery-ext.html (next: http://localhost:52482/tests/dromaeo/cssquery-jquery.html) 08:26:02 INFO - PROCESS | 3420 | RSS: Main: 247226368 08:26:02 INFO - PROCESS | 3420 | 08:29:04 INFO - PROCESS | 3420 | Cycle 1(1): loaded http://localhost:52482/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52482/tests/dromaeo/cssquery-mootools.html) 08:29:05 INFO - PROCESS | 3420 | RSS: Main: 255688704 08:29:05 INFO - PROCESS | 3420 | 08:32:07 INFO - PROCESS | 3420 | Cycle 1(1): loaded http://localhost:52482/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52482/tests/dromaeo/cssquery-prototype.html) 08:32:07 INFO - PROCESS | 3420 | RSS: Main: 252628992 08:32:07 INFO - PROCESS | 3420 | 08:35:10 INFO - PROCESS | 3420 | Cycle 1(1): loaded http://localhost:52482/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52482/tests/dromaeo/cssquery-yui.html) 08:35:11 INFO - PROCESS | 3420 | RSS: Main: 256028672 08:35:11 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | Cycle 1(1): loaded http://localhost:52482/tests/dromaeo/cssquery-yui.html (next: http://localhost:52482/tests/dromaeo/cssquery-dojo.html) 08:38:14 INFO - PROCESS | 3420 | RSS: Main: 258146304 08:38:14 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | __start_tp_report 08:38:14 INFO - PROCESS | 3420 | _x_x_mozilla_page_load 08:38:14 INFO - PROCESS | 3420 | _x_x_mozilla_page_load_details 08:38:14 INFO - PROCESS | 3420 | |i|pagename|runs| 08:38:14 INFO - PROCESS | 3420 | |0;dojo.html;3053.8778448862054;3299.6205436374817;3327.234736010716;3376.6623337666238;3413.1808365992138;5673.602847800661;5683.232763576916;5720.713964301784;5742.081266997281;5744.856378590537;5712.171735098408;5765.596408251424;5800.4489573490555;5801.07182850744;5883.47048765611;5152.51051150142;5183.963207358524;5209.348831396075;5271.15661494161;5297.655652382583;5216.087184742674;5306.6550674206055;5319.228711836781;5322.281491998569;5352.304200453936;5091.363579552556;5145.459726728677;5206.37523497179;5244.973775131138;5291.814786482455;5106.591472682214;5110.8466745997675;5167.844964651066;5195.064888320101;5199.922001169985;5100.540951314362;5146.202338637517;5148.279240906276;5151.995360904625;5191.039657663307;5141.383034035902;5260.368755749297;5522.585806064521;5708.743106560215;5753.424657534255;6118.990366589507;6152.969235153841;6197.946349120615;6239.594426362272;6258.593191442542;6216.036514340284;6229.53278504114;6236.18929539157;6268.467180289681;6273.309935907047;5808.740477576602;5829.854253643693;5844.064949608042;5846.503047240947;5861.091530812688;5412;6154.446099851035;6156.045812899008;6168.1056246843955;6197.1324014637985;4604.861854144381;6107.083937409336;6165.198524191826;6168.8766224675255;6212.099245609361;5433.049216387196;6055.576109672369;6182.258129024546;6209.316975132731;6230;5049.469805670426;5880.676562789087;6104.969475152596;6132.754689812357;6150.692465376803;6235.064740288903;6247.9062814057825;6256.311805701369;6272.780452684134;6282.434580887651;4248.532661407243;4988.72562009093;4998.075356059068;4998.575121114746;5002.324686167393;3882.0100874277446;4284.271673815459;4411.801468933906;4466.062126953311;4484.461864576272;5846.123081537803;5971.611845230046;5972.611780234271;5988.580799344091;6003.189569408161;6147.631142131487;6151.815445536551;6154.1999540059505;6185.597936134137;6253.280872699606;4996.775145118479;5711.789872839718;5874.823755287348;5882.676452795137;5945.16767652529;5465.9726701366235;5480.671159730349;5484.972575137099;5487.862803429866;5506.807261745819;5257.605679574123;6325.05124231369;6356.682165891687;6356.745730170742;6393.840153996187;6151.446369826737;6171.537134715004;6174.907376389448;6208.0998255253635;6224.62652240867;6239.875202496016;6284.371562843679;6287.056941458818;6302.401271879165;6306.054091886253;6121.632702037892;6194.38056194395;6202.844928876814;6208.720607572761;6229.595076320024;6056.242969628796;6135.447809697149;6153.969230153821;6240.656763878031;6269.090981807703;6079.240094988127;6147.231596050494;6150.907736384047;6168.414000669929;6194.721237544411;6129;6135.14108024868;6136.32500424944;6147.508199344131;6153.72308246139;5848.824535263949;5850.356460789395;5851.239338885917;5923.822285331447;6037.45662890342;5654.434556544477;6006.33930267679;6018.849528761816;6037.758489660365;6147.415995480422;5419.160030195326;5961.2846458425265;5987.550933679904;5992.8801423972145;5994.6403215807195 08:38:14 INFO - PROCESS | 3420 | |1;ext.html;464.7351009924343;485.15582885778747;492.23457523550866;499.855042037809;548.7064420535014;37192.62807371926;39516.40725389118;39642.80178599107;39874.60125398745;39900.401493977544;38861.41707874384;40326.588569400075;40800.38799418003;41075.17849643005;41171.794141029255;7501.837215231648;8152.796180095515;9058.637654493828;9314.115159059913;9317.254619630414;9265;11400.543978240861;11421.086313094933;11572.53709851605;11573.942130289379;9933.155681767023;11152.888471115306;11365.431728413587;11385.829212561819;11397.601083962063;9755.951220243924;11315.660530184025;11332.263402878827;11387.316760994367;11390.601328953488;9532.665426840247;10378.532966016548;10774.730631734192;11169.944150279196;11208.327500350006;32765.83617081923;39390.60609393898;39495.60504394948;39525.20949581021;39777;3070.9846450767604;3669.7614655047337;3720.572134204574;3751.118487155516;3770.7549009314307;24509.509809803883;28484.430311393655;28525.857370713013;29571.852140739375;29782.404351913057;3228.8547015384365;3248.5614442050255;3353.295807880348;3371.612264589579;3374;451945;461902.69048654544;463069;463473;464680.73617423227;50953;55548.16677749837;58640;59062.704686477155;59116;61837;61878.69060654758;61926;62213.68893155596;62345.37654623396;16073.758893616605;16356.509304720877;16360.263788129563;16390.344386224657;16393.83606163923;10903.727406814893;11780.293182408912;12432.502699891904;12721.173123746927;12769.233845969271;14264.858811295071;14594;15686.76469852953;15852.207389630703;16466.847320687677;6172.382761723881;6229.875402491924;7092.007119003344;7226.927730722626;7295.343419092201;819.8647223208104;834.6703052294309;837.5937670229933;854.6538651846013;873;26304.473910521683;27455.17634470969;29613;29755.99524575245;29959.850200748857;874.2568816505919;1019.8475722433751;1030.747641615426;1056.1392465140887;1178.7288923547633;12901.838834504752;14855.405783768529;15194.088354698753;15976.600584985468;15999.040057596581;523306;527834;529661;529842;532750;57478;57647.71176144092;57656.84686306251;57718.7114064427;57839;1380.8342998840203;1394.2958805803207;1415.5257988573737;1424.3732757586836;1424.6580820603187;28043.298917527223;28920.855395722887;29015.11401758879;29430.117096487138;29592.556111657912;47760.761196193795;48353.5164648349;48406.27390589214;48412.03175936391;48586;502910;503509;504695.4295685641;505284;506340;32717.672823271463;34492.482612761334;34514.30971380508;34653.48019779655;34815.9555213344;43614.56385436105;44962.3255651159;45404.77297613491;46453.83865403257;46707.29939050849;32123;37058.44412333871;37092.88721338364;37129.81435092807;37408.877733668036;37432.625673742914;37632;38331.61668383392;38595.03512412212;38639.6136038636;35119.12202194965;36124;36413;36965.44551831671;37015.44476832796;28205.294867628472;30951.84524077365;32901.83549082335;33117.17207069843;33304;18079;24457.38856528601;24711.38221544476;25030.62454063154;25126.748732513173 08:38:14 INFO - PROCESS | 3420 | |2;jquery.html;362.1543695471075;364.55888375066166;386.6095243803757;401.2636811450987;417.20314199878237;19182.232710691573;20080.799192008075;20985.47536311593;21191.788082119194;21242.57514849702;9400.529973501332;11822.349770762605;12322.445489952952;12376.381180940962;19523.5119122022;5148.536631703156;5410.08028635133;5413.998410294089;5418.241446197536;5419.078756611386;3943.152222272211;4431.446069241345;5289.523942845143;6072.392760723915;6131.662758548278;6100.1154832549255;6114.3579924108;6118.724657390406;6123.020316749321;6128.38716128388;5866.823995280149;6005.069214271794;6014.879702405928;6052.848678783022;6072;5732.168835518826;5918.171455996164;5949.286085669747;5963.105534169866;5985.072313791369;25192.866321015415;25909.870450647628;26078.86960565185;26133.693315334156;26230.86884565565;2488.3157131788903;2563.6410902473667;2590.7020692620213;2638.1162310625964;2639.5644718621406;9492.85760713583;10022.298439109265;10129;10455.947720261425;10469.214808889288;1798.4514723009477;1809.7647305850155;1813.120636491314;1841.1898764543691;1841.9631607367912;132100;132880;139184;142139.28930355283;143212;9641.855372169422;11998.040156787436;12038;12083.275003499819;12212.90083892454;15890;15914.0451572906;15938.362465501483;15938.681226375407;15991.200439977954;7605.163432022473;8658.39391242607;8897.110288971051;8938.86591701125;9044;8529.189726975927;8569.057403685589;8602.39783215169;8754.424203643406;8760;4731.273085323832;4839.733814640158;4851.126797176472;4858.1012512685265;4946.3817022872145;1676.5808547863035;1770.5927636643644;1775.2987569909812;2013.2349707111496;2072.1814883121083;990.8711867457184;1003.2174903575223;1009.7576581620505;1010.8736407949007;1054.46222426563;19510.90244548768;21166.78833211648;21515.709057456603;22134.446638833837;22189.667154992687;938.7277689470113;1024.7028361775;1035.5754140802385;1074.8656417947757;1078.9676309710721;9232.8615070774;11369.431528423545;11492.908173723483;11503.53985840557;11510.366929818772;733467;744513;746446;747570;761323;108819;139133;151505.24247378693;151946.7207991881;157386;958.5686441101532;966.4974213408988;984.3454103021554;992.4243938515788;993.9503024848729;13811.516596919462;15591.688166236385;19636.214551417783;19733.42132629415;19794.40616781499;54366.72816635892;57435.71282143563;57496;58209;61488;81194;81683.59158204171;84022;84945.1505484962;85224;7403.7408690695565;7567.205443428361;7635.198304177981;7889.171636978264;7904.565248911365;12515.186512876633;12562.183458075197;13104.951603871858;13268.53560125391;13454;10873.532073170021;11226.326420414802;11363.30822425901;11519;11703.180777345844;5694.402087780723;8703.86944195825;11236.370726244812;11650.242734222249;11759.706007349885;10780.784384312114;11016.779664406828;11047.171462140532;11621.825672614748;11936.343501107522;6369.267534233622;7549.09410870677;7663.233676632292;7798.649060792164;8651.87022194655;9626.84477862661;10062.949685251528;11503.424828758696;11749.29504229749;11845.5854045108 08:38:14 INFO - PROCESS | 3420 | |3;mootools.html;1194.4386138514894;1355.2478374502148;1357.0907491980372;1357.8710022547862;1364.5292374130922;7599;7883.605819709021;8153.592320383979;8642.265407440367;8644.351673624471;8148.5518296493665;8356.289715374185;8430.873536896952;8539.146085391458;8550.957245213765;2299.321700098471;2345.9677741793557;2346.002948746784;2352.7411984681758;2364.6925899633025;1649.2578339747101;2135.9252426165085;2350.2244259394442;2390.426297688551;2400.855948643078;2240.4510894830787;2327.1622216002224;2338.7661233876647;2339.94150146246;2342.9648555271683;2310.145246258878;2321.9071237150492;2337.0301324950124;2338;2341;2281.315605318398;2292.5071109711325;2348.283773449097;2358.8230882683865;2399.988000060006;7288.453365997519;8583.95708021462;8603.5698215089;8612.48325100496;8618.26744726699;4438.378626992224;5025.949740502585;5160.045391602528;5313.840584782424;5316.255724198615;3307.9173020674434;3383.8664047544103;3516.9120771980906;3590.622984586633;3597.388443964532;1133.410626474237;1186.8753780852971;1199.8020326646092;1259.0494176896384;1280.910336276461;10906.781864362747;11064.336139831636;11071.335719856754;11081.168912331526;11132.554697812078;4560.270356742905;4658.324542941323;4684.508126646721;4702.4592171899985;4741.668083234141;9516.666916657883;9672.419654820733;9682;9682.90317096834;9687.564059617334;5267.446918073624;5276.788928442896;5277.208418737144;5279.762410691528;5284.7093409862055;4529;4617.399738034034;4816.253480710496;4857.1742803723455;4875.219964805614;3870.59358767331;3919.3141200289833;3954.070793363557;3995.20095980805;4019.7789121598007;970.262600423669;1233.4511142541662;1237.4555195713858;1248.5879659712273;1262.1543565810928;799.6281728996045;889.8531742262584;905.6015353244551;913.6619450803246;916.4684482999844;5445.891082178334;5533.257881327617;5618.775248990077;5656.547476201894;5735.11105778605;966.8308046091905;967.5549247346158;980.3872579637726;992.9205663546899;1004.487711267259;3709;3723.1250656095795;3756.079760458705;3859.421086836997;3877.4571559981623;9839.901600984042;10192.13366863825;10224.897751022394;10229.130523905551;10241.539130739135;10165.186785057178;10171.389716617026;10188.796224075477;10189.643362482277;10226.53980570876;7471.252874712594;7869.331106855867;7964.402669799788;8055.073666528424;8058.959705201437;5694.544436445157;5727.341355744143;5821.912671310019;5927.229460170151;5962.940370596239;8786.472811631322;9133.77165570866;9242.491662958602;9406.153446189875;9413.435193888388;9390.906090939003;9448.725372292132;9450.338476306868;9458.148766611039;9543.33196676226;7214.819629509304;7220.89168662481;7343.48595598303;7345.816354590964;7381.852362952818;7845.215478452109;7880.448368614142;7935.1668074853615;7957.084935232522;7979.640916158671;7089.468289878177;7154;7192.352688258082;7209.567425954459;7325.597092159983;7031.683574239274;7056.717731290691;7083.150021997393;7109.893351599833;7111.9687645290105;6240.968795155995;6367.93632063692;6386;6441.1626488556185;6506.869862602627;4512.729236245836;4993.500649934977;5005.424376196638;5017.096922553829;5034.395872495324;4949.109160351171;4955.2567114933045;4983.426905905867;5062.645614806928;5137.383513978347 08:38:14 INFO - PROCESS | 3420 | |4;prototype.html;299.95650630658554;353.4237301634709;365.7582507387418;368.2101891442855;375.82711952501865;4784.411691231577;5272.130098533747;5322.361316642004;5502.724863756811;5531.308586426701;4896.657233993622;5188.766505507252;5276.762545685444;5321.973390133054;5339.199120131972;3543.982280088596;4185.012119243358;4195.307774217251;4250.872473825791;4285.9571404286025;3696.4085746280603;4099.631033207011;4238.936415953764;4239.47006624172;4286.76422796746;4121.773302468363;4153.7507749535125;4168.395167860381;4179.352200408942;4207.179599978005;3489.9302013959705;3855.6890657176696;4164.10471748574;4196.055887425336;4215.241256573816;3723.2181241939224;3736.40217565191;3742.8689995850286;3753.4932784074344;3765.76351604445;3686.207465394953;5031.949680503221;5163.896722065538;5206.68759874405;5241.659292145999;4830.29960655703;4837.685550439211;4850.223964165716;4857.271409288635;4883.5604795568215;5204.375474942994;5684.772609095631;5723.313202415695;5727.914081288784;5751.741171647286;3816.2367526494813;4011.3782363733667;4028.5568587455355;4090.2228576570355;4091.9590804091877;5740.512056475204;5987.850303742398;6004.939950600526;6036.072504835151;6037.396260373971;3772.8113594320175;4202.3696445533415;4215.725977811494;4226.281532139544;4232.555581663943;3922.764634121962;4097.631213190827;4115.34154535273;4254.340577210537;4290.163418133497;4325.52419233884;4358.934615980763;4591.977040114778;4841.540053694964;4887.3890763654545;4650.4186976627925;5342.839714808562;5550.361708403505;5730.799422020209;5738.561111527584;1262.3877419451558;1322.90860040467;1358.8097666326526;1373.0182919212812;1383.5710929611853;897.6140259688266;948.7002806155612;950.8589692369046;956.7799406136488;994.5425104452033;4558.495696420174;5009.5491405773655;5053.267276244924;5069.757909312242;5106.234064890222;4876.390451193601;5263.6578622389425;5318.574514038867;5351.625386222927;5552.91670624941;4289.978550107229;4479.641628669699;4535.750533720677;4825.396825396825;5053.46938571452;2665.200989332193;2770.4043630619512;2777.861106944685;2927.15589178816;2962.0302498838464;5091.762590826283;5726.856828579319;5727.484526392562;5880.8235752927485;5903.793367232126;5497.39528651848;5558.861028474321;5779.248697669276;6022.247219097613;6156.353582873823;5829.0090684584575;6081.665508397081;6108.969455152695;6132.908006379818;6147.293061297919;5238.921416178837;5320.388155362183;5550.613236308426;5878.735456904364;5894.882102358015;5086.720230387365;5287.814926477555;5841.766329346949;5848.005839007464;5850.94149058521;5822.720300579155;6115.755369785159;6127.571070025234;6141.416565426277;6163.630182188904;3358.4458564337588;3382.9661703383636;3726.9813650931574;3906.374980003186;3966.900827479336;4660.6970546914345;5313.362396512289;5426.348838139291;5826.533877289722;5896.7936122235515;5042.4705405931845;5068.504791086712;5155.845324640267;5691.7438715257085;5764.625299355528;4950.554450099508;5464.26232458613;5663.943360566342;5728.656280623176;5751.568632352674;4934;5428.037532601144;5520.696361700145;5620.016497112907;5690.918725442153;4586.357909892685;5037.949620503895;5664.546836253172;5700.714964251688;5738.196652468574;5072.239164125411;5343.7060961647485;5575.219469274386;5586.413426590312;5654.491095801398 08:38:14 INFO - PROCESS | 3420 | |5;yui.html;641.2977789320693;667.2526770017585;733.3290039613754;786.7089177004508;806.3025482957241;14422.134671919679;15117.24413779312;15150.848491515082;15517.844821551782;15622.84377156228;13934.721305573883;14068.155910645368;14174.50389236377;14307.998440109197;14361.784573231409;863.9308855291562;873.8033942362949;880.6081293824251;882.1002577371078;882.2633101360371;693.7467824244139;721.4480922094601;853.2022558907411;872.8777971084053;881.2729498164008;922.4880191493718;932.9346945713803;933.7198840347902;934.3272843552631;938.3712912348743;900.8153328567628;921.065118904316;924.2836801478841;926.3700683535193;928.3223247029672;659.8977158540434;687.3264201082917;688.5042769206163;694.0699462719929;694.5034300475133;13308.534201303004;14518.274086295749;14798.70402591953;14907.254637268094;15301.693966120727;14080;14464.783028254584;14514.564563063124;14517;14538.854611453857;11421.486033128529;11651.359175245443;11693.114077103744;11702.824457633142;11771.94114029433;3610.711143108545;3656.341858465475;3667.0832291927018;3693.630636936312;3710.703143748494;96199;97990.51004745002;98059.50970245175;102486;106408.93591064145;10557.841632375521;10931;11389;11401.087912966943;11606;22416.77583224147;22773.31680049601;22806.65790013151;22842.885785570965;23443.531129377316;7588.430867684946;7728.574928378993;7741.109772376249;7815.452918295665;7998.080220774686;3652.433872749728;4237.63980061692;4263.87208383749;4296.806643701041;4338.848140315074;3612.205314830733;3711.6473934976134;3716.0152559571734;3716.832742526593;3728.9067773305883;631.409631994087;652.6051738698114;654.4077609763171;668.3650531994527;674.9426298764562;656.5305806348483;660.531022973694;680.5644387591946;687.3573209049555;713.2332742302046;10106.191504679755;10142.087212150942;10210.846837297446;10469.68590942273;10490.527926243338;633.77273585186;690.963554667998;695.5235663570369;699.6746512871438;708.5075872268724;4680.180968330596;4907.7300748458465;4924.507549245119;4928.433230178575;4947.158982972904;99638;100977.05926563639;101308.98691013143;101769;102563;75822.86265706019;81641.59179204066;91205.54397227972;92382;92635;72354.63822680853;72922.27077729155;73382.26617733754;75325.49349012988;80014;9359.85960210611;9789.755256118655;10802.729931751455;11071;11083.279586826831;43386.13227735491;46060;49078.26382604335;50897;51147.48852511427;51491.71270718112;56880.71559642341;65815.67092164508;65910.34089659042;66058;12843.871561284268;12856.742865142833;12977.675558111123;13125.803112953505;13132.934335328262;12301.507939682671;13671.248081355621;14131.364088615832;14467.855321447072;14703.264836758333;10818.783624327627;13082.672933176747;13392.995525335833;13478.79781803254;13497;13448.26034568069;13608.115472494255;13660.521881734488;13729.519466818614;14050.929745351617;12801.679958001125;12816.743665126458;13339.466421343037;13445.260510672008;13816;10845.403502807105;11089.445527723421;11119.888801112209;11342.969463527232;11485.368304743319;10354.792904142025;11062.668119956414;11072.501737421675;11078.501467433824;11092.112630989663 08:38:14 INFO - PROCESS | 3420 | __end_tp_report 08:38:14 INFO - PROCESS | 3420 | __start_cc_report 08:38:14 INFO - PROCESS | 3420 | _x_x_mozilla_cycle_collect,386 08:38:14 INFO - PROCESS | 3420 | __end_cc_report 08:38:14 INFO - PROCESS | 3420 | __startTimestamp1487608694530__endTimestamp 08:38:14 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | ------- Summary: start ------- 08:38:14 INFO - PROCESS | 3420 | Number of tests: 6 08:38:14 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | [#0] dojo.html Cycles:165 Average:5698.60 Median:5945.17 stddev:662.48 (11.1%) stddev-sans-first:631.19 08:38:14 INFO - PROCESS | 3420 | Values: 3053.9 3299.6 3327.2 3376.7 3413.2 5673.6 5683.2 5720.7 5742.1 5744.9 5712.2 5765.6 5800.4 5801.1 5883.5 5152.5 5184.0 5209.3 5271.2 5297.7 5216.1 5306.7 5319.2 5322.3 5352.3 5091.4 5145.5 5206.4 5245.0 5291.8 5106.6 5110.8 5167.8 5195.1 5199.9 5100.5 5146.2 5148.3 5152.0 5191.0 5141.4 5260.4 5522.6 5708.7 5753.4 6119.0 6153.0 6197.9 6239.6 6258.6 6216.0 6229.5 6236.2 6268.5 6273.3 5808.7 5829.9 5844.1 5846.5 5861.1 5412.0 6154.4 6156.0 6168.1 6197.1 4604.9 6107.1 6165.2 6168.9 6212.1 5433.0 6055.6 6182.3 6209.3 6230.0 5049.5 5880.7 6105.0 6132.8 6150.7 6235.1 6247.9 6256.3 6272.8 6282.4 4248.5 4988.7 4998.1 4998.6 5002.3 3882.0 4284.3 4411.8 4466.1 4484.5 5846.1 5971.6 5972.6 5988.6 6003.2 6147.6 6151.8 6154.2 6185.6 6253.3 4996.8 5711.8 5874.8 5882.7 5945.2 5466.0 5480.7 5485.0 5487.9 5506.8 5257.6 6325.1 6356.7 6356.7 6393.8 6151.4 6171.5 6174.9 6208.1 6224.6 6239.9 6284.4 6287.1 6302.4 6306.1 6121.6 6194.4 6202.8 6208.7 6229.6 6056.2 6135.4 6154.0 6240.7 6269.1 6079.2 6147.2 6150.9 6168.4 6194.7 6129.0 6135.1 6136.3 6147.5 6153.7 5848.8 5850.4 5851.2 5923.8 6037.5 5654.4 6006.3 6018.8 6037.8 6147.4 5419.2 5961.3 5987.6 5992.9 5994.6 08:38:14 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | [#1] ext.html Cycles:180 Average:63798.24 Median:37111.35 stddev:132692.04 (357.6%) stddev-sans-first:132979.08 08:38:14 INFO - PROCESS | 3420 | Values: 464.7 485.2 492.2 499.9 548.7 37192.6 39516.4 39642.8 39874.6 39900.4 38861.4 40326.6 40800.4 41075.2 41171.8 7501.8 8152.8 9058.6 9314.1 9317.3 9265.0 11400.5 11421.1 11572.5 11573.9 9933.2 11152.9 11365.4 11385.8 11397.6 9756.0 11315.7 11332.3 11387.3 11390.6 9532.7 10378.5 10774.7 11169.9 11208.3 32765.8 39390.6 39495.6 39525.2 39777.0 3071.0 3669.8 3720.6 3751.1 3770.8 24509.5 28484.4 28525.9 29571.9 29782.4 3228.9 3248.6 3353.3 3371.6 3374.0 451945.0 461902.7 463069.0 463473.0 464680.7 50953.0 55548.2 58640.0 59062.7 59116.0 61837.0 61878.7 61926.0 62213.7 62345.4 16073.8 16356.5 16360.3 16390.3 16393.8 10903.7 11780.3 12432.5 12721.2 12769.2 14264.9 14594.0 15686.8 15852.2 16466.8 6172.4 6229.9 7092.0 7226.9 7295.3 819.9 834.7 837.6 854.7 873.0 26304.5 27455.2 29613.0 29756.0 29959.9 874.3 1019.8 1030.7 1056.1 1178.7 12901.8 14855.4 15194.1 15976.6 15999.0 523306.0 527834.0 529661.0 529842.0 532750.0 57478.0 57647.7 57656.8 57718.7 57839.0 1380.8 1394.3 1415.5 1424.4 1424.7 28043.3 28920.9 29015.1 29430.1 29592.6 47760.8 48353.5 48406.3 48412.0 48586.0 502910.0 503509.0 504695.4 505284.0 506340.0 32717.7 34492.5 34514.3 34653.5 34816.0 43614.6 44962.3 45404.8 46453.8 46707.3 32123.0 37058.4 37092.9 37129.8 37408.9 37432.6 37632.0 38331.6 38595.0 38639.6 35119.1 36124.0 36413.0 36965.4 37015.4 28205.3 30951.8 32901.8 33117.2 33304.0 18079.0 24457.4 24711.4 25030.6 25126.7 08:38:14 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | [#2] jquery.html Cycles:180 Average:40187.89 Median:25994.37 stddev:124282.90 (478.1%) stddev-sans-first:124595.57 08:38:14 INFO - PROCESS | 3420 | Values: 362.2 364.6 386.6 401.3 417.2 19182.2 20080.8 20985.5 21191.8 21242.6 9400.5 11822.3 12322.4 12376.4 19523.5 5148.5 5410.1 5414.0 5418.2 5419.1 3943.2 4431.4 5289.5 6072.4 6131.7 6100.1 6114.4 6118.7 6123.0 6128.4 5866.8 6005.1 6014.9 6052.8 6072.0 5732.2 5918.2 5949.3 5963.1 5985.1 25192.9 25909.9 26078.9 26133.7 26230.9 2488.3 2563.6 2590.7 2638.1 2639.6 9492.9 10022.3 10129.0 10455.9 10469.2 1798.5 1809.8 1813.1 1841.2 1842.0 132100.0 132880.0 139184.0 142139.3 143212.0 9641.9 11998.0 12038.0 12083.3 12212.9 15890.0 15914.0 15938.4 15938.7 15991.2 7605.2 8658.4 8897.1 8938.9 9044.0 8529.2 8569.1 8602.4 8754.4 8760.0 4731.3 4839.7 4851.1 4858.1 4946.4 1676.6 1770.6 1775.3 2013.2 2072.2 990.9 1003.2 1009.8 1010.9 1054.5 19510.9 21166.8 21515.7 22134.4 22189.7 938.7 1024.7 1035.6 1074.9 1079.0 9232.9 11369.4 11492.9 11503.5 11510.4 733467.0 744513.0 746446.0 747570.0 761323.0 108819.0 139133.0 151505.2 151946.7 157386.0 958.6 966.5 984.3 992.4 994.0 13811.5 15591.7 19636.2 19733.4 19794.4 54366.7 57435.7 57496.0 58209.0 61488.0 81194.0 81683.6 84022.0 84945.2 85224.0 7403.7 7567.2 7635.2 7889.2 7904.6 12515.2 12562.2 13105.0 13268.5 13454.0 10873.5 11226.3 11363.3 11519.0 11703.2 5694.4 8703.9 11236.4 11650.2 11759.7 10780.8 11016.8 11047.2 11621.8 11936.3 6369.3 7549.1 7663.2 7798.6 8651.9 9626.8 10062.9 11503.4 11749.3 11845.6 08:38:14 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | [#3] mootools.html Cycles:180 Average:5494.43 Median:5148.71 stddev:3014.84 (58.6%) stddev-sans-first:3005.97 08:38:14 INFO - PROCESS | 3420 | Values: 1194.4 1355.2 1357.1 1357.9 1364.5 7599.0 7883.6 8153.6 8642.3 8644.4 8148.6 8356.3 8430.9 8539.1 8551.0 2299.3 2346.0 2346.0 2352.7 2364.7 1649.3 2135.9 2350.2 2390.4 2400.9 2240.5 2327.2 2338.8 2339.9 2343.0 2310.1 2321.9 2337.0 2338.0 2341.0 2281.3 2292.5 2348.3 2358.8 2400.0 7288.5 8584.0 8603.6 8612.5 8618.3 4438.4 5025.9 5160.0 5313.8 5316.3 3307.9 3383.9 3516.9 3590.6 3597.4 1133.4 1186.9 1199.8 1259.0 1280.9 10906.8 11064.3 11071.3 11081.2 11132.6 4560.3 4658.3 4684.5 4702.5 4741.7 9516.7 9672.4 9682.0 9682.9 9687.6 5267.4 5276.8 5277.2 5279.8 5284.7 4529.0 4617.4 4816.3 4857.2 4875.2 3870.6 3919.3 3954.1 3995.2 4019.8 970.3 1233.5 1237.5 1248.6 1262.2 799.6 889.9 905.6 913.7 916.5 5445.9 5533.3 5618.8 5656.5 5735.1 966.8 967.6 980.4 992.9 1004.5 3709.0 3723.1 3756.1 3859.4 3877.5 9839.9 10192.1 10224.9 10229.1 10241.5 10165.2 10171.4 10188.8 10189.6 10226.5 7471.3 7869.3 7964.4 8055.1 8059.0 5694.5 5727.3 5821.9 5927.2 5962.9 8786.5 9133.8 9242.5 9406.2 9413.4 9390.9 9448.7 9450.3 9458.1 9543.3 7214.8 7220.9 7343.5 7345.8 7381.9 7845.2 7880.4 7935.2 7957.1 7979.6 7089.5 7154.0 7192.4 7209.6 7325.6 7031.7 7056.7 7083.2 7109.9 7112.0 6241.0 6367.9 6386.0 6441.2 6506.9 4512.7 4993.5 5005.4 5017.1 5034.4 4949.1 4955.3 4983.4 5062.6 5137.4 08:38:14 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | [#4] prototype.html Cycles:180 Average:4595.46 Median:5069.13 stddev:1386.81 (27.4%) stddev-sans-first:1352.70 08:38:14 INFO - PROCESS | 3420 | Values: 300.0 353.4 365.8 368.2 375.8 4784.4 5272.1 5322.4 5502.7 5531.3 4896.7 5188.8 5276.8 5322.0 5339.2 3544.0 4185.0 4195.3 4250.9 4286.0 3696.4 4099.6 4238.9 4239.5 4286.8 4121.8 4153.8 4168.4 4179.4 4207.2 3489.9 3855.7 4164.1 4196.1 4215.2 3723.2 3736.4 3742.9 3753.5 3765.8 3686.2 5031.9 5163.9 5206.7 5241.7 4830.3 4837.7 4850.2 4857.3 4883.6 5204.4 5684.8 5723.3 5727.9 5751.7 3816.2 4011.4 4028.6 4090.2 4092.0 5740.5 5987.9 6004.9 6036.1 6037.4 3772.8 4202.4 4215.7 4226.3 4232.6 3922.8 4097.6 4115.3 4254.3 4290.2 4325.5 4358.9 4592.0 4841.5 4887.4 4650.4 5342.8 5550.4 5730.8 5738.6 1262.4 1322.9 1358.8 1373.0 1383.6 897.6 948.7 950.9 956.8 994.5 4558.5 5009.5 5053.3 5069.8 5106.2 4876.4 5263.7 5318.6 5351.6 5552.9 4290.0 4479.6 4535.8 4825.4 5053.5 2665.2 2770.4 2777.9 2927.2 2962.0 5091.8 5726.9 5727.5 5880.8 5903.8 5497.4 5558.9 5779.2 6022.2 6156.4 5829.0 6081.7 6109.0 6132.9 6147.3 5238.9 5320.4 5550.6 5878.7 5894.9 5086.7 5287.8 5841.8 5848.0 5850.9 5822.7 6115.8 6127.6 6141.4 6163.6 3358.4 3383.0 3727.0 3906.4 3966.9 4660.7 5313.4 5426.3 5826.5 5896.8 5042.5 5068.5 5155.8 5691.7 5764.6 4950.6 5464.3 5663.9 5728.7 5751.6 4934.0 5428.0 5520.7 5620.0 5690.9 4586.4 5037.9 5664.5 5700.7 5738.2 5072.2 5343.7 5575.2 5586.4 5654.5 08:38:14 INFO - PROCESS | 3420 | 08:38:14 INFO - PROCESS | 3420 | [#5] yui.html Cycles:180 Average:19915.69 Median:3711.18 stddev:28182.75 (759.4%) stddev-sans-first:28224.65 08:38:14 INFO - PROCESS | 3420 | Values: 641.3 667.3 733.3 786.7 806.3 14422.1 15117.2 15150.8 15517.8 15622.8 13934.7 14068.2 14174.5 14308.0 14361.8 863.9 873.8 880.6 882.1 882.3 693.7 721.4 853.2 872.9 881.3 922.5 932.9 933.7 934.3 938.4 900.8 921.1 924.3 926.4 928.3 659.9 687.3 688.5 694.1 694.5 13308.5 14518.3 14798.7 14907.3 15301.7 14080.0 14464.8 14514.6 14517.0 14538.9 11421.5 11651.4 11693.1 11702.8 11771.9 3610.7 3656.3 3667.1 3693.6 3710.7 96199.0 97990.5 98059.5 102486.0 106408.9 10557.8 10931.0 11389.0 11401.1 11606.0 22416.8 22773.3 22806.7 22842.9 23443.5 7588.4 7728.6 7741.1 7815.5 7998.1 3652.4 4237.6 4263.9 4296.8 4338.8 3612.2 3711.6 3716.0 3716.8 3728.9 631.4 652.6 654.4 668.4 674.9 656.5 660.5 680.6 687.4 713.2 10106.2 10142.1 10210.8 10469.7 10490.5 633.8 691.0 695.5 699.7 708.5 4680.2 4907.7 4924.5 4928.4 4947.2 99638.0 100977.1 101309.0 101769.0 102563.0 75822.9 81641.6 91205.5 92382.0 92635.0 72354.6 72922.3 73382.3 75325.5 80014.0 9359.9 9789.8 10802.7 11071.0 11083.3 43386.1 46060.0 49078.3 50897.0 51147.5 51491.7 56880.7 65815.7 65910.3 66058.0 12843.9 12856.7 12977.7 13125.8 13132.9 12301.5 13671.2 14131.4 14467.9 14703.3 10818.8 13082.7 13393.0 13478.8 13497.0 13448.3 13608.1 13660.5 13729.5 14050.9 12801.7 12816.7 13339.5 13445.3 13816.0 10845.4 11089.4 11119.9 11343.0 11485.4 10354.8 11062.7 11072.5 11078.5 11092.1 08:38:14 INFO - PROCESS | 3420 | -------- Summary: end -------- 08:38:14 INFO - PROCESS | 3420 | 08:38:15 INFO - TEST-INFO | 3420: exit 0 08:38:15 INFO - TEST-OK | dromaeo_css | took 1116395ms 08:38:15 INFO - TEST-START | kraken 08:38:15 INFO - operating with platform_type : mac_ 08:38:15 INFO - Initialising browser for kraken test... 08:38:15 INFO - TEST-INFO | started process 3425 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpewWpRs/profile http://localhost:52482/getInfo.html) 08:38:18 INFO - PROCESS | 3425 | 2017-02-20 08:38:18.014 plugin-container[3426:327098] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 08:38:18 INFO - PROCESS | 3425 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:18 INFO - PROCESS | 3425 | 2017-02-20 08:38:18.017 plugin-container[3426:327098] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 08:38:18 INFO - PROCESS | 3425 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:18 INFO - PROCESS | 3425 | 2017-02-20 08:38:18.017 plugin-container[3426:327098] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:38:18 INFO - PROCESS | 3425 | __metrics Screen width/height:1600/1200 08:38:18 INFO - PROCESS | 3425 | colorDepth:24 08:38:18 INFO - PROCESS | 3425 | Browser inner width/height: 1024/689 08:38:18 INFO - PROCESS | 3425 | __metrics 08:38:18 ERROR - PROCESS | 3425 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 08:38:18 INFO - TEST-INFO | 3425: exit 0 08:38:18 INFO - Browser initialized. 08:38:18 INFO - Running cycle 1/1 for kraken test... 08:38:18 INFO - TEST-INFO | started process 3427 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpewWpRs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:38:21 INFO - PROCESS | 3427 | 2017-02-20 08:38:21.504 plugin-container[3428:327228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:38:21 INFO - PROCESS | 3427 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:21 INFO - PROCESS | 3427 | 2017-02-20 08:38:21.504 plugin-container[3428:327228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:38:21 INFO - PROCESS | 3427 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:21 INFO - PROCESS | 3427 | 2017-02-20 08:38:21.504 plugin-container[3428:327228] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:38:31 INFO - PROCESS | 3427 | RSS: Main: 219742208 08:38:31 INFO - PROCESS | 3427 | 08:38:34 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52482/tests/kraken/driver.html?testName=audio-beat-detection) 08:38:35 INFO - PROCESS | 3427 | RSS: Main: 233766912 08:38:35 INFO - PROCESS | 3427 | 08:38:37 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52482/tests/kraken/driver.html?testName=audio-dft) 08:38:38 INFO - PROCESS | 3427 | RSS: Main: 241901568 08:38:38 INFO - PROCESS | 3427 | 08:38:41 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52482/tests/kraken/driver.html?testName=audio-fft) 08:38:42 INFO - PROCESS | 3427 | RSS: Main: 245551104 08:38:42 INFO - PROCESS | 3427 | 08:38:44 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52482/tests/kraken/driver.html?testName=audio-oscillator) 08:38:45 INFO - PROCESS | 3427 | RSS: Main: 246812672 08:38:45 INFO - PROCESS | 3427 | 08:38:47 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52482/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:38:47 INFO - PROCESS | 3427 | RSS: Main: 247128064 08:38:47 INFO - PROCESS | 3427 | 08:38:50 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52482/tests/kraken/driver.html?testName=imaging-darkroom) 08:38:51 INFO - PROCESS | 3427 | RSS: Main: 251666432 08:38:51 INFO - PROCESS | 3427 | 08:38:54 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52482/tests/kraken/driver.html?testName=imaging-desaturate) 08:38:55 INFO - PROCESS | 3427 | RSS: Main: 250322944 08:38:55 INFO - PROCESS | 3427 | 08:38:58 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52482/tests/kraken/driver.html?testName=json-parse-financial) 08:38:58 INFO - PROCESS | 3427 | RSS: Main: 250372096 08:38:58 INFO - PROCESS | 3427 | 08:39:00 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52482/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:39:01 INFO - PROCESS | 3427 | RSS: Main: 249712640 08:39:01 INFO - PROCESS | 3427 | 08:39:03 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52482/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:39:03 INFO - PROCESS | 3427 | RSS: Main: 249667584 08:39:03 INFO - PROCESS | 3427 | 08:39:05 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52482/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:39:06 INFO - PROCESS | 3427 | RSS: Main: 249679872 08:39:06 INFO - PROCESS | 3427 | 08:39:09 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52482/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:39:10 INFO - PROCESS | 3427 | RSS: Main: 250109952 08:39:10 INFO - PROCESS | 3427 | 08:39:13 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52482/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:39:13 INFO - PROCESS | 3427 | RSS: Main: 249905152 08:39:13 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | Cycle 1(1): loaded http://localhost:52482/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52482/tests/kraken/driver.html?testName=ai-astar) 08:39:15 INFO - PROCESS | 3427 | RSS: Main: 251777024 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | __start_tp_report 08:39:15 INFO - PROCESS | 3427 | _x_x_mozilla_page_load 08:39:15 INFO - PROCESS | 3427 | _x_x_mozilla_page_load_details 08:39:15 INFO - PROCESS | 3427 | |i|pagename|runs| 08:39:15 INFO - PROCESS | 3427 | |0;ai-astar;105;119;121;119;120;122;120;99;179;176 08:39:15 INFO - PROCESS | 3427 | |1;audio-beat-detection;143;113;113;148;112;114;113;121;157;147 08:39:15 INFO - PROCESS | 3427 | |2;audio-dft;195;195;198;198;198;197;201;231;285;198 08:39:15 INFO - PROCESS | 3427 | |3;audio-fft;85;59;60;89;60;60;60;60;70;53 08:39:15 INFO - PROCESS | 3427 | |4;audio-oscillator;82;71;86;71;71;70;84;70;73;83 08:39:15 INFO - PROCESS | 3427 | |5;imaging-gaussian-blur;135;132;89;110;91;128;85;81;134;129 08:39:15 INFO - PROCESS | 3427 | |6;imaging-darkroom;153;108;97;98;98;96;97;97;99;99 08:39:15 INFO - PROCESS | 3427 | |7;imaging-desaturate;91;104;101;84;133;88;128;94;109;94 08:39:15 INFO - PROCESS | 3427 | |8;json-parse-financial;67;92;60;64;65;64;62;65;65;64 08:39:15 INFO - PROCESS | 3427 | |9;json-stringify-tinderbox;69;69;78;80;70;67;72;71;72;72 08:39:15 INFO - PROCESS | 3427 | |10;stanford-crypto-aes;86;83;85;83;87;84;82;85;83;85 08:39:15 INFO - PROCESS | 3427 | |11;stanford-crypto-ccm;138;139;140;138;139;141;150;156;208;148 08:39:15 INFO - PROCESS | 3427 | |12;stanford-crypto-pbkdf2;177;201;176;169;169;173;183;227;214;170 08:39:15 INFO - PROCESS | 3427 | |13;stanford-crypto-sha256-iterative;66;70;67;67;67;66;69;68;68;68 08:39:15 INFO - PROCESS | 3427 | __end_tp_report 08:39:15 INFO - PROCESS | 3427 | __start_cc_report 08:39:15 INFO - PROCESS | 3427 | _x_x_mozilla_cycle_collect,1002 08:39:15 INFO - PROCESS | 3427 | __end_cc_report 08:39:15 INFO - PROCESS | 3427 | __startTimestamp1487608755798__endTimestamp 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | ------- Summary: start ------- 08:39:15 INFO - PROCESS | 3427 | Number of tests: 14 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#0] ai-astar Cycles:10 Average:128.00 Median:121.50 stddev:27.18 (22.4%) stddev-sans-first:27.53 08:39:15 INFO - PROCESS | 3427 | Values: 105.0 119.0 121.0 119.0 120.0 122.0 120.0 99.0 179.0 176.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:132.00 stddev:18.27 (13.8%) stddev-sans-first:18.56 08:39:15 INFO - PROCESS | 3427 | Values: 143.0 113.0 113.0 148.0 112.0 114.0 113.0 121.0 157.0 147.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#2] audio-dft Cycles:10 Average:209.60 Median:198.00 stddev:28.56 (14.4%) stddev-sans-first:29.80 08:39:15 INFO - PROCESS | 3427 | Values: 195.0 195.0 198.0 198.0 198.0 197.0 201.0 231.0 285.0 198.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#3] audio-fft Cycles:10 Average:65.60 Median:60.00 stddev:12.03 (20.0%) stddev-sans-first:10.51 08:39:15 INFO - PROCESS | 3427 | Values: 85.0 59.0 60.0 89.0 60.0 60.0 60.0 60.0 70.0 53.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.50 stddev:6.71 (8.7%) stddev-sans-first:6.77 08:39:15 INFO - PROCESS | 3427 | Values: 82.0 71.0 86.0 71.0 71.0 70.0 84.0 70.0 73.0 83.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#5] imaging-gaussian-blur Cycles:10 Average:111.40 Median:108.00 stddev:22.65 (21.0%) stddev-sans-first:22.36 08:39:15 INFO - PROCESS | 3427 | Values: 135.0 132.0 89.0 110.0 91.0 128.0 85.0 81.0 134.0 129.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:97.50 stddev:17.48 (17.9%) stddev-sans-first:3.60 08:39:15 INFO - PROCESS | 3427 | Values: 153.0 108.0 97.0 98.0 98.0 96.0 97.0 97.0 99.0 99.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#7] imaging-desaturate Cycles:10 Average:102.60 Median:86.00 stddev:16.52 (19.2%) stddev-sans-first:16.98 08:39:15 INFO - PROCESS | 3427 | Values: 91.0 104.0 101.0 84.0 133.0 88.0 128.0 94.0 109.0 94.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:65.00 stddev:9.05 (13.9%) stddev-sans-first:9.60 08:39:15 INFO - PROCESS | 3427 | Values: 67.0 92.0 60.0 64.0 65.0 64.0 62.0 65.0 65.0 64.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:4.06 (5.6%) stddev-sans-first:4.15 08:39:15 INFO - PROCESS | 3427 | Values: 69.0 69.0 78.0 80.0 70.0 67.0 72.0 71.0 72.0 72.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:1.57 (1.8%) stddev-sans-first:1.54 08:39:15 INFO - PROCESS | 3427 | Values: 86.0 83.0 85.0 83.0 87.0 84.0 82.0 85.0 83.0 85.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:144.50 stddev:21.38 (14.8%) stddev-sans-first:22.25 08:39:15 INFO - PROCESS | 3427 | Values: 138.0 139.0 140.0 138.0 139.0 141.0 150.0 156.0 208.0 148.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.90 Median:180.00 stddev:20.77 (11.5%) stddev-sans-first:21.78 08:39:15 INFO - PROCESS | 3427 | Values: 177.0 201.0 176.0 169.0 169.0 173.0 183.0 227.0 214.0 170.0 08:39:15 INFO - PROCESS | 3427 | 08:39:15 INFO - PROCESS | 3427 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.26 (1.9%) stddev-sans-first:1.20 08:39:15 INFO - PROCESS | 3427 | Values: 66.0 70.0 67.0 67.0 67.0 66.0 69.0 68.0 68.0 68.0 08:39:15 INFO - PROCESS | 3427 | -------- Summary: end -------- 08:39:15 INFO - PROCESS | 3427 | 08:39:16 INFO - TEST-INFO | 3427: exit 0 08:39:16 INFO - TEST-OK | kraken | took 61480ms 08:39:16 INFO - SUITE-END | took 1177s 08:39:16 INFO - Completed test suite (00:19:38) 08:39:16 INFO - javascript benchmark 08:39:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7272.671787089294, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3053.8778448862054, 3299.6205436374817, 3327.234736010716, 3376.6623337666238, 3413.1808365992138, 5673.602847800661, 5683.232763576916, 5720.713964301784, 5742.081266997281, 5744.856378590537, 5712.171735098408, 5765.596408251424, 5800.4489573490555, 5801.07182850744, 5883.47048765611, 5152.51051150142, 5183.963207358524, 5209.348831396075, 5271.15661494161, 5297.655652382583, 5216.087184742674, 5306.6550674206055, 5319.228711836781, 5322.281491998569, 5352.304200453936, 5091.363579552556, 5145.459726728677, 5206.37523497179, 5244.973775131138, 5291.814786482455, 5106.591472682214, 5110.8466745997675, 5167.844964651066, 5195.064888320101, 5199.922001169985, 5100.540951314362, 5146.202338637517, 5148.279240906276, 5151.995360904625, 5191.039657663307, 5141.383034035902, 5260.368755749297, 5522.585806064521, 5708.743106560215, 5753.424657534255, 6118.990366589507, 6152.969235153841, 6197.946349120615, 6239.594426362272, 6258.593191442542, 6216.036514340284, 6229.53278504114, 6236.18929539157, 6268.467180289681, 6273.309935907047, 5808.740477576602, 5829.854253643693, 5844.064949608042, 5846.503047240947, 5861.091530812688, 5412.0, 6154.446099851035, 6156.045812899008, 6168.1056246843955, 6197.1324014637985, 4604.861854144381, 6107.083937409336, 6165.198524191826, 6168.8766224675255, 6212.099245609361, 5433.049216387196, 6055.576109672369, 6182.258129024546, 6209.316975132731, 6230.0, 5049.469805670426, 5880.676562789087, 6104.969475152596, 6132.754689812357, 6150.692465376803, 6235.064740288903, 6247.9062814057825, 6256.311805701369, 6272.780452684134, 6282.434580887651, 4248.532661407243, 4988.72562009093, 4998.075356059068, 4998.575121114746, 5002.324686167393, 3882.0100874277446, 4284.271673815459, 4411.801468933906, 4466.062126953311, 4484.461864576272, 5846.123081537803, 5971.611845230046, 5972.611780234271, 5988.580799344091, 6003.189569408161, 6147.631142131487, 6151.815445536551, 6154.1999540059505, 6185.597936134137, 6253.280872699606, 4996.775145118479, 5711.789872839718, 5874.823755287348, 5882.676452795137, 5945.16767652529, 5465.9726701366235, 5480.671159730349, 5484.972575137099, 5487.862803429866, 5506.807261745819, 5257.605679574123, 6325.05124231369, 6356.682165891687, 6356.745730170742, 6393.840153996187, 6151.446369826737, 6171.537134715004, 6174.907376389448, 6208.0998255253635, 6224.62652240867, 6239.875202496016, 6284.371562843679, 6287.056941458818, 6302.401271879165, 6306.054091886253, 6121.632702037892, 6194.38056194395, 6202.844928876814, 6208.720607572761, 6229.595076320024, 6056.242969628796, 6135.447809697149, 6153.969230153821, 6240.656763878031, 6269.090981807703, 6079.240094988127, 6147.231596050494, 6150.907736384047, 6168.414000669929, 6194.721237544411, 6129.0, 6135.14108024868, 6136.32500424944, 6147.508199344131, 6153.72308246139, 5848.824535263949, 5850.356460789395, 5851.239338885917, 5923.822285331447, 6037.45662890342, 5654.434556544477, 6006.33930267679, 6018.849528761816, 6037.758489660365, 6147.415995480422, 5419.160030195326, 5961.2846458425265, 5987.550933679904, 5992.8801423972145, 5994.6403215807195], "value": 5656.772207135161, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.7351009924343, 485.15582885778747, 492.23457523550866, 499.855042037809, 548.7064420535014, 37192.62807371926, 39516.40725389118, 39642.80178599107, 39874.60125398745, 39900.401493977544, 38861.41707874384, 40326.588569400075, 40800.38799418003, 41075.17849643005, 41171.794141029255, 7501.837215231648, 8152.796180095515, 9058.637654493828, 9314.115159059913, 9317.254619630414, 9265.0, 11400.543978240861, 11421.086313094933, 11572.53709851605, 11573.942130289379, 9933.155681767023, 11152.888471115306, 11365.431728413587, 11385.829212561819, 11397.601083962063, 9755.951220243924, 11315.660530184025, 11332.263402878827, 11387.316760994367, 11390.601328953488, 9532.665426840247, 10378.532966016548, 10774.730631734192, 11169.944150279196, 11208.327500350006, 32765.83617081923, 39390.60609393898, 39495.60504394948, 39525.20949581021, 39777.0, 3070.9846450767604, 3669.7614655047337, 3720.572134204574, 3751.118487155516, 3770.7549009314307, 24509.509809803883, 28484.430311393655, 28525.857370713013, 29571.852140739375, 29782.404351913057, 3228.8547015384365, 3248.5614442050255, 3353.295807880348, 3371.612264589579, 3374.0, 451945.0, 461902.69048654544, 463069.0, 463473.0, 464680.73617423227, 50953.0, 55548.16677749837, 58640.0, 59062.704686477155, 59116.0, 61837.0, 61878.69060654758, 61926.0, 62213.68893155596, 62345.37654623396, 16073.758893616605, 16356.509304720877, 16360.263788129563, 16390.344386224657, 16393.83606163923, 10903.727406814893, 11780.293182408912, 12432.502699891904, 12721.173123746927, 12769.233845969271, 14264.858811295071, 14594.0, 15686.76469852953, 15852.207389630703, 16466.847320687677, 6172.382761723881, 6229.875402491924, 7092.007119003344, 7226.927730722626, 7295.343419092201, 819.8647223208104, 834.6703052294309, 837.5937670229933, 854.6538651846013, 873.0, 26304.473910521683, 27455.17634470969, 29613.0, 29755.99524575245, 29959.850200748857, 874.2568816505919, 1019.8475722433751, 1030.747641615426, 1056.1392465140887, 1178.7288923547633, 12901.838834504752, 14855.405783768529, 15194.088354698753, 15976.600584985468, 15999.040057596581, 523306.0, 527834.0, 529661.0, 529842.0, 532750.0, 57478.0, 57647.71176144092, 57656.84686306251, 57718.7114064427, 57839.0, 1380.8342998840203, 1394.2958805803207, 1415.5257988573737, 1424.3732757586836, 1424.6580820603187, 28043.298917527223, 28920.855395722887, 29015.11401758879, 29430.117096487138, 29592.556111657912, 47760.761196193795, 48353.5164648349, 48406.27390589214, 48412.03175936391, 48586.0, 502910.0, 503509.0, 504695.4295685641, 505284.0, 506340.0, 32717.672823271463, 34492.482612761334, 34514.30971380508, 34653.48019779655, 34815.9555213344, 43614.56385436105, 44962.3255651159, 45404.77297613491, 46453.83865403257, 46707.29939050849, 32123.0, 37058.44412333871, 37092.88721338364, 37129.81435092807, 37408.877733668036, 37432.625673742914, 37632.0, 38331.61668383392, 38595.03512412212, 38639.6136038636, 35119.12202194965, 36124.0, 36413.0, 36965.44551831671, 37015.44476832796, 28205.294867628472, 30951.84524077365, 32901.83549082335, 33117.17207069843, 33304.0, 18079.0, 24457.38856528601, 24711.38221544476, 25030.62454063154, 25126.748732513173], "value": 19503.641289834115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.1543695471075, 364.55888375066166, 386.6095243803757, 401.2636811450987, 417.20314199878237, 19182.232710691573, 20080.799192008075, 20985.47536311593, 21191.788082119194, 21242.57514849702, 9400.529973501332, 11822.349770762605, 12322.445489952952, 12376.381180940962, 19523.5119122022, 5148.536631703156, 5410.08028635133, 5413.998410294089, 5418.241446197536, 5419.078756611386, 3943.152222272211, 4431.446069241345, 5289.523942845143, 6072.392760723915, 6131.662758548278, 6100.1154832549255, 6114.3579924108, 6118.724657390406, 6123.020316749321, 6128.38716128388, 5866.823995280149, 6005.069214271794, 6014.879702405928, 6052.848678783022, 6072.0, 5732.168835518826, 5918.171455996164, 5949.286085669747, 5963.105534169866, 5985.072313791369, 25192.866321015415, 25909.870450647628, 26078.86960565185, 26133.693315334156, 26230.86884565565, 2488.3157131788903, 2563.6410902473667, 2590.7020692620213, 2638.1162310625964, 2639.5644718621406, 9492.85760713583, 10022.298439109265, 10129.0, 10455.947720261425, 10469.214808889288, 1798.4514723009477, 1809.7647305850155, 1813.120636491314, 1841.1898764543691, 1841.9631607367912, 132100.0, 132880.0, 139184.0, 142139.28930355283, 143212.0, 9641.855372169422, 11998.040156787436, 12038.0, 12083.275003499819, 12212.90083892454, 15890.0, 15914.0451572906, 15938.362465501483, 15938.681226375407, 15991.200439977954, 7605.163432022473, 8658.39391242607, 8897.110288971051, 8938.86591701125, 9044.0, 8529.189726975927, 8569.057403685589, 8602.39783215169, 8754.424203643406, 8760.0, 4731.273085323832, 4839.733814640158, 4851.126797176472, 4858.1012512685265, 4946.3817022872145, 1676.5808547863035, 1770.5927636643644, 1775.2987569909812, 2013.2349707111496, 2072.1814883121083, 990.8711867457184, 1003.2174903575223, 1009.7576581620505, 1010.8736407949007, 1054.46222426563, 19510.90244548768, 21166.78833211648, 21515.709057456603, 22134.446638833837, 22189.667154992687, 938.7277689470113, 1024.7028361775, 1035.5754140802385, 1074.8656417947757, 1078.9676309710721, 9232.8615070774, 11369.431528423545, 11492.908173723483, 11503.53985840557, 11510.366929818772, 733467.0, 744513.0, 746446.0, 747570.0, 761323.0, 108819.0, 139133.0, 151505.24247378693, 151946.7207991881, 157386.0, 958.5686441101532, 966.4974213408988, 984.3454103021554, 992.4243938515788, 993.9503024848729, 13811.516596919462, 15591.688166236385, 19636.214551417783, 19733.42132629415, 19794.40616781499, 54366.72816635892, 57435.71282143563, 57496.0, 58209.0, 61488.0, 81194.0, 81683.59158204171, 84022.0, 84945.1505484962, 85224.0, 7403.7408690695565, 7567.205443428361, 7635.198304177981, 7889.171636978264, 7904.565248911365, 12515.186512876633, 12562.183458075197, 13104.951603871858, 13268.53560125391, 13454.0, 10873.532073170021, 11226.326420414802, 11363.30822425901, 11519.0, 11703.180777345844, 5694.402087780723, 8703.86944195825, 11236.370726244812, 11650.242734222249, 11759.706007349885, 10780.784384312114, 11016.779664406828, 11047.171462140532, 11621.825672614748, 11936.343501107522, 6369.267534233622, 7549.09410870677, 7663.233676632292, 7798.649060792164, 8651.87022194655, 9626.84477862661, 10062.949685251528, 11503.424828758696, 11749.29504229749, 11845.5854045108], "value": 9671.797917956785, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1194.4386138514894, 1355.2478374502148, 1357.0907491980372, 1357.8710022547862, 1364.5292374130922, 7599.0, 7883.605819709021, 8153.592320383979, 8642.265407440367, 8644.351673624471, 8148.5518296493665, 8356.289715374185, 8430.873536896952, 8539.146085391458, 8550.957245213765, 2299.321700098471, 2345.9677741793557, 2346.002948746784, 2352.7411984681758, 2364.6925899633025, 1649.2578339747101, 2135.9252426165085, 2350.2244259394442, 2390.426297688551, 2400.855948643078, 2240.4510894830787, 2327.1622216002224, 2338.7661233876647, 2339.94150146246, 2342.9648555271683, 2310.145246258878, 2321.9071237150492, 2337.0301324950124, 2338.0, 2341.0, 2281.315605318398, 2292.5071109711325, 2348.283773449097, 2358.8230882683865, 2399.988000060006, 7288.453365997519, 8583.95708021462, 8603.5698215089, 8612.48325100496, 8618.26744726699, 4438.378626992224, 5025.949740502585, 5160.045391602528, 5313.840584782424, 5316.255724198615, 3307.9173020674434, 3383.8664047544103, 3516.9120771980906, 3590.622984586633, 3597.388443964532, 1133.410626474237, 1186.8753780852971, 1199.8020326646092, 1259.0494176896384, 1280.910336276461, 10906.781864362747, 11064.336139831636, 11071.335719856754, 11081.168912331526, 11132.554697812078, 4560.270356742905, 4658.324542941323, 4684.508126646721, 4702.4592171899985, 4741.668083234141, 9516.666916657883, 9672.419654820733, 9682.0, 9682.90317096834, 9687.564059617334, 5267.446918073624, 5276.788928442896, 5277.208418737144, 5279.762410691528, 5284.7093409862055, 4529.0, 4617.399738034034, 4816.253480710496, 4857.1742803723455, 4875.219964805614, 3870.59358767331, 3919.3141200289833, 3954.070793363557, 3995.20095980805, 4019.7789121598007, 970.262600423669, 1233.4511142541662, 1237.4555195713858, 1248.5879659712273, 1262.1543565810928, 799.6281728996045, 889.8531742262584, 905.6015353244551, 913.6619450803246, 916.4684482999844, 5445.891082178334, 5533.257881327617, 5618.775248990077, 5656.547476201894, 5735.11105778605, 966.8308046091905, 967.5549247346158, 980.3872579637726, 992.9205663546899, 1004.487711267259, 3709.0, 3723.1250656095795, 3756.079760458705, 3859.421086836997, 3877.4571559981623, 9839.901600984042, 10192.13366863825, 10224.897751022394, 10229.130523905551, 10241.539130739135, 10165.186785057178, 10171.389716617026, 10188.796224075477, 10189.643362482277, 10226.53980570876, 7471.252874712594, 7869.331106855867, 7964.402669799788, 8055.073666528424, 8058.959705201437, 5694.544436445157, 5727.341355744143, 5821.912671310019, 5927.229460170151, 5962.940370596239, 8786.472811631322, 9133.77165570866, 9242.491662958602, 9406.153446189875, 9413.435193888388, 9390.906090939003, 9448.725372292132, 9450.338476306868, 9458.148766611039, 9543.33196676226, 7214.819629509304, 7220.89168662481, 7343.48595598303, 7345.816354590964, 7381.852362952818, 7845.215478452109, 7880.448368614142, 7935.1668074853615, 7957.084935232522, 7979.640916158671, 7089.468289878177, 7154.0, 7192.352688258082, 7209.567425954459, 7325.597092159983, 7031.683574239274, 7056.717731290691, 7083.150021997393, 7109.893351599833, 7111.9687645290105, 6240.968795155995, 6367.93632063692, 6386.0, 6441.1626488556185, 6506.869862602627, 4512.729236245836, 4993.500649934977, 5005.424376196638, 5017.096922553829, 5034.395872495324, 4949.109160351171, 4955.2567114933045, 4983.426905905867, 5062.645614806928, 5137.383513978347], "value": 4448.6114015901485, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.95650630658554, 353.4237301634709, 365.7582507387418, 368.2101891442855, 375.82711952501865, 4784.411691231577, 5272.130098533747, 5322.361316642004, 5502.724863756811, 5531.308586426701, 4896.657233993622, 5188.766505507252, 5276.762545685444, 5321.973390133054, 5339.199120131972, 3543.982280088596, 4185.012119243358, 4195.307774217251, 4250.872473825791, 4285.9571404286025, 3696.4085746280603, 4099.631033207011, 4238.936415953764, 4239.47006624172, 4286.76422796746, 4121.773302468363, 4153.7507749535125, 4168.395167860381, 4179.352200408942, 4207.179599978005, 3489.9302013959705, 3855.6890657176696, 4164.10471748574, 4196.055887425336, 4215.241256573816, 3723.2181241939224, 3736.40217565191, 3742.8689995850286, 3753.4932784074344, 3765.76351604445, 3686.207465394953, 5031.949680503221, 5163.896722065538, 5206.68759874405, 5241.659292145999, 4830.29960655703, 4837.685550439211, 4850.223964165716, 4857.271409288635, 4883.5604795568215, 5204.375474942994, 5684.772609095631, 5723.313202415695, 5727.914081288784, 5751.741171647286, 3816.2367526494813, 4011.3782363733667, 4028.5568587455355, 4090.2228576570355, 4091.9590804091877, 5740.512056475204, 5987.850303742398, 6004.939950600526, 6036.072504835151, 6037.396260373971, 3772.8113594320175, 4202.3696445533415, 4215.725977811494, 4226.281532139544, 4232.555581663943, 3922.764634121962, 4097.631213190827, 4115.34154535273, 4254.340577210537, 4290.163418133497, 4325.52419233884, 4358.934615980763, 4591.977040114778, 4841.540053694964, 4887.3890763654545, 4650.4186976627925, 5342.839714808562, 5550.361708403505, 5730.799422020209, 5738.561111527584, 1262.3877419451558, 1322.90860040467, 1358.8097666326526, 1373.0182919212812, 1383.5710929611853, 897.6140259688266, 948.7002806155612, 950.8589692369046, 956.7799406136488, 994.5425104452033, 4558.495696420174, 5009.5491405773655, 5053.267276244924, 5069.757909312242, 5106.234064890222, 4876.390451193601, 5263.6578622389425, 5318.574514038867, 5351.625386222927, 5552.91670624941, 4289.978550107229, 4479.641628669699, 4535.750533720677, 4825.396825396825, 5053.46938571452, 2665.200989332193, 2770.4043630619512, 2777.861106944685, 2927.15589178816, 2962.0302498838464, 5091.762590826283, 5726.856828579319, 5727.484526392562, 5880.8235752927485, 5903.793367232126, 5497.39528651848, 5558.861028474321, 5779.248697669276, 6022.247219097613, 6156.353582873823, 5829.0090684584575, 6081.665508397081, 6108.969455152695, 6132.908006379818, 6147.293061297919, 5238.921416178837, 5320.388155362183, 5550.613236308426, 5878.735456904364, 5894.882102358015, 5086.720230387365, 5287.814926477555, 5841.766329346949, 5848.005839007464, 5850.94149058521, 5822.720300579155, 6115.755369785159, 6127.571070025234, 6141.416565426277, 6163.630182188904, 3358.4458564337588, 3382.9661703383636, 3726.9813650931574, 3906.374980003186, 3966.900827479336, 4660.6970546914345, 5313.362396512289, 5426.348838139291, 5826.533877289722, 5896.7936122235515, 5042.4705405931845, 5068.504791086712, 5155.845324640267, 5691.7438715257085, 5764.625299355528, 4950.554450099508, 5464.26232458613, 5663.943360566342, 5728.656280623176, 5751.568632352674, 4934.0, 5428.037532601144, 5520.696361700145, 5620.016497112907, 5690.918725442153, 4586.357909892685, 5037.949620503895, 5664.546836253172, 5700.714964251688, 5738.196652468574, 5072.239164125411, 5343.7060961647485, 5575.219469274386, 5586.413426590312, 5654.491095801398], "value": 4168.369237340039, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.2977789320693, 667.2526770017585, 733.3290039613754, 786.7089177004508, 806.3025482957241, 14422.134671919679, 15117.24413779312, 15150.848491515082, 15517.844821551782, 15622.84377156228, 13934.721305573883, 14068.155910645368, 14174.50389236377, 14307.998440109197, 14361.784573231409, 863.9308855291562, 873.8033942362949, 880.6081293824251, 882.1002577371078, 882.2633101360371, 693.7467824244139, 721.4480922094601, 853.2022558907411, 872.8777971084053, 881.2729498164008, 922.4880191493718, 932.9346945713803, 933.7198840347902, 934.3272843552631, 938.3712912348743, 900.8153328567628, 921.065118904316, 924.2836801478841, 926.3700683535193, 928.3223247029672, 659.8977158540434, 687.3264201082917, 688.5042769206163, 694.0699462719929, 694.5034300475133, 13308.534201303004, 14518.274086295749, 14798.70402591953, 14907.254637268094, 15301.693966120727, 14080.0, 14464.783028254584, 14514.564563063124, 14517.0, 14538.854611453857, 11421.486033128529, 11651.359175245443, 11693.114077103744, 11702.824457633142, 11771.94114029433, 3610.711143108545, 3656.341858465475, 3667.0832291927018, 3693.630636936312, 3710.703143748494, 96199.0, 97990.51004745002, 98059.50970245175, 102486.0, 106408.93591064145, 10557.841632375521, 10931.0, 11389.0, 11401.087912966943, 11606.0, 22416.77583224147, 22773.31680049601, 22806.65790013151, 22842.885785570965, 23443.531129377316, 7588.430867684946, 7728.574928378993, 7741.109772376249, 7815.452918295665, 7998.080220774686, 3652.433872749728, 4237.63980061692, 4263.87208383749, 4296.806643701041, 4338.848140315074, 3612.205314830733, 3711.6473934976134, 3716.0152559571734, 3716.832742526593, 3728.9067773305883, 631.409631994087, 652.6051738698114, 654.4077609763171, 668.3650531994527, 674.9426298764562, 656.5305806348483, 660.531022973694, 680.5644387591946, 687.3573209049555, 713.2332742302046, 10106.191504679755, 10142.087212150942, 10210.846837297446, 10469.68590942273, 10490.527926243338, 633.77273585186, 690.963554667998, 695.5235663570369, 699.6746512871438, 708.5075872268724, 4680.180968330596, 4907.7300748458465, 4924.507549245119, 4928.433230178575, 4947.158982972904, 99638.0, 100977.05926563639, 101308.98691013143, 101769.0, 102563.0, 75822.86265706019, 81641.59179204066, 91205.54397227972, 92382.0, 92635.0, 72354.63822680853, 72922.27077729155, 73382.26617733754, 75325.49349012988, 80014.0, 9359.85960210611, 9789.755256118655, 10802.729931751455, 11071.0, 11083.279586826831, 43386.13227735491, 46060.0, 49078.26382604335, 50897.0, 51147.48852511427, 51491.71270718112, 56880.71559642341, 65815.67092164508, 65910.34089659042, 66058.0, 12843.871561284268, 12856.742865142833, 12977.675558111123, 13125.803112953505, 13132.934335328262, 12301.507939682671, 13671.248081355621, 14131.364088615832, 14467.855321447072, 14703.264836758333, 10818.783624327627, 13082.672933176747, 13392.995525335833, 13478.79781803254, 13497.0, 13448.26034568069, 13608.115472494255, 13660.521881734488, 13729.519466818614, 14050.929745351617, 12801.679958001125, 12816.743665126458, 13339.466421343037, 13445.260510672008, 13816.0, 10845.403502807105, 11089.445527723421, 11119.888801112209, 11342.969463527232, 11485.368304743319, 10354.792904142025, 11062.668119956414, 11072.501737421675, 11078.501467433824, 11092.112630989663], "value": 7477.150815847027, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 119.0, 121.0, 119.0, 120.0, 122.0, 120.0, 99.0, 179.0, 176.0], "value": 128.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 113.0, 148.0, 112.0, 114.0, 113.0, 121.0, 157.0, 147.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 195.0, 198.0, 198.0, 198.0, 197.0, 201.0, 231.0, 285.0, 198.0], "value": 209.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 59.0, 60.0, 89.0, 60.0, 60.0, 60.0, 60.0, 70.0, 53.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 86.0, 71.0, 71.0, 70.0, 84.0, 70.0, 73.0, 83.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 132.0, 89.0, 110.0, 91.0, 128.0, 85.0, 81.0, 134.0, 129.0], "value": 111.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 108.0, 97.0, 98.0, 98.0, 96.0, 97.0, 97.0, 99.0, 99.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 104.0, 101.0, 84.0, 133.0, 88.0, 128.0, 94.0, 109.0, 94.0], "value": 102.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 92.0, 60.0, 64.0, 65.0, 64.0, 62.0, 65.0, 65.0, 64.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 78.0, 80.0, 70.0, 67.0, 72.0, 71.0, 72.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 83.0, 87.0, 84.0, 82.0, 85.0, 83.0, 85.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 140.0, 138.0, 139.0, 141.0, 150.0, 156.0, 208.0, 148.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 201.0, 176.0, 169.0, 169.0, 173.0, 183.0, 227.0, 214.0, 170.0], "value": 185.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 67.0, 67.0, 67.0, 66.0, 69.0, 68.0, 68.0, 68.0], "value": 67.6, "unit": "score"}]}]} 08:39:17 INFO - Return code: 0 08:39:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:39:17 ERROR - # TBPL SUCCESS # 08:39:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:39:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:39:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:39:17 INFO - 2017-02-20 08:39:17.118 screenresolution[3432:327615] starting screenresolution argv=screenresolution get 08:39:17 INFO - 2017-02-20 08:39:17.121 screenresolution[3432:327615] Display 0: 1600x1200x32@60 08:39:17 INFO - 2017-02-20 08:39:17.127 screenresolution[3433:327618] starting screenresolution argv=screenresolution list 08:39:17 INFO - Available Modes on Display 0 08:39:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:39:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:39:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:39:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:39:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:39:17 INFO - Intel Iris: 08:39:17 INFO - Chipset Model: Intel Iris 08:39:17 INFO - Type: GPU 08:39:17 INFO - Bus: Built-In 08:39:17 INFO - VRAM (Dynamic, Max): 1536 MB 08:39:17 INFO - Vendor: Intel (0x8086) 08:39:17 INFO - Device ID: 0x0a2e 08:39:17 INFO - Revision ID: 0x0009 08:39:17 INFO - Displays: 08:39:17 INFO - Display: 08:39:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:39:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:39:17 INFO - Display Serial Number: 1600x1200 60 08:39:17 INFO - Main Display: Yes 08:39:17 INFO - Mirror: Off 08:39:17 INFO - Online: Yes 08:39:17 INFO - Rotation: Supported 08:39:17 INFO - Return code: 0 08:39:17 INFO - Running post-action listener: _resource_record_post_action 08:39:17 INFO - [mozharness: 2017-02-20 16:39:17.363146Z] Finished run-tests step (success) 08:39:17 INFO - Running post-run listener: _resource_record_post_run 08:39:17 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 159744; Write bytes: 512515072; Read time: 2; Write time: 1662 08:39:17 INFO - TinderboxPrint: CPU usage
54.5% 08:39:17 INFO - TinderboxPrint: I/O read bytes / time
159,744 / 2 08:39:17 INFO - TinderboxPrint: I/O write bytes / time
512,515,072 / 1,662 08:39:17 INFO - TinderboxPrint: CPU idle
2,183.9 (45.5%) 08:39:17 INFO - TinderboxPrint: CPU system
968.4 (20.2%) 08:39:17 INFO - TinderboxPrint: CPU user
1,645.4 (34.3%) 08:39:17 INFO - TinderboxPrint: Swap in / out
221,544,448 / 0 08:39:17 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205378048; Write bytes: 201693184; Read time: 15768; Write time: 444 08:39:17 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 155648; Write bytes: 299852800; Read time: 1; Write time: 1179 08:39:17 INFO - Running post-run listener: _upload_blobber_files 08:39:17 INFO - Blob upload gear active. 08:39:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:39:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:39:18 INFO - (blobuploader) - INFO - Open directory for files ... 08:39:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:39:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:39:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:18 INFO - (blobuploader) - INFO - Done attempting. 08:39:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:39:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:39:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:21 INFO - (blobuploader) - INFO - Done attempting. 08:39:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:39:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:39:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:22 INFO - (blobuploader) - INFO - Done attempting. 08:39:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:39:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:25 INFO - (blobuploader) - INFO - Done attempting. 08:39:25 INFO - (blobuploader) - INFO - Iteration through files over. 08:39:25 INFO - Return code: 0 08:39:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:39:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:39:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f303c99ad88d236c7ff437f7232efdb2d0415e264c055688edbc53802e3cd80b31a4b5181f5aff8310510640f5f0de5c7f5e214ea38b6f30585a485fe4df3dc3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/424e1055a06f08d8693fc45f487e5cbf9565a81af8f17b8086ecf35ec9e1f55fc59c8f70cad53af158e2fff4498704dfe9072d17ee6d47474953fc8c63136087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa74abc96c2c040cadf477254fce733267f076a6837a9b90bc76e7ab3ef37790c8f80b0a80846b3d8e88ab37bf4fd52dc700e711ab387a6e265969fbebce04dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d29ecfaedc6a52e3380724053ebfe4ade29f476713c3c3c74e96969e87b9a4c64156728eeb98e6ac64f774ed69b411c43617c38a6bf6a315714bac7e76a3dd51"} 08:39:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:39:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:39:25 INFO - Contents: 08:39:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f303c99ad88d236c7ff437f7232efdb2d0415e264c055688edbc53802e3cd80b31a4b5181f5aff8310510640f5f0de5c7f5e214ea38b6f30585a485fe4df3dc3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/424e1055a06f08d8693fc45f487e5cbf9565a81af8f17b8086ecf35ec9e1f55fc59c8f70cad53af158e2fff4498704dfe9072d17ee6d47474953fc8c63136087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa74abc96c2c040cadf477254fce733267f076a6837a9b90bc76e7ab3ef37790c8f80b0a80846b3d8e88ab37bf4fd52dc700e711ab387a6e265969fbebce04dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d29ecfaedc6a52e3380724053ebfe4ade29f476713c3c3c74e96969e87b9a4c64156728eeb98e6ac64f774ed69b411c43617c38a6bf6a315714bac7e76a3dd51"} 08:39:25 INFO - Running post-run listener: copy_logs_to_upload_dir 08:39:25 INFO - Copying logs to upload dir... 08:39:25 INFO - mkdir: /builds/slave/test/build/upload/logs 08:39:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.628513 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-20 08:39:25.429201) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 08:39:25.433154) ========= 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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f303c99ad88d236c7ff437f7232efdb2d0415e264c055688edbc53802e3cd80b31a4b5181f5aff8310510640f5f0de5c7f5e214ea38b6f30585a485fe4df3dc3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/424e1055a06f08d8693fc45f487e5cbf9565a81af8f17b8086ecf35ec9e1f55fc59c8f70cad53af158e2fff4498704dfe9072d17ee6d47474953fc8c63136087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa74abc96c2c040cadf477254fce733267f076a6837a9b90bc76e7ab3ef37790c8f80b0a80846b3d8e88ab37bf4fd52dc700e711ab387a6e265969fbebce04dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d29ecfaedc6a52e3380724053ebfe4ade29f476713c3c3c74e96969e87b9a4c64156728eeb98e6ac64f774ed69b411c43617c38a6bf6a315714bac7e76a3dd51"} build_url:https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011491 build_url: 'https://queue.taskcluster.net/v1/task/YnmrkAtIRgCTaL6DIuxwpw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f303c99ad88d236c7ff437f7232efdb2d0415e264c055688edbc53802e3cd80b31a4b5181f5aff8310510640f5f0de5c7f5e214ea38b6f30585a485fe4df3dc3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/424e1055a06f08d8693fc45f487e5cbf9565a81af8f17b8086ecf35ec9e1f55fc59c8f70cad53af158e2fff4498704dfe9072d17ee6d47474953fc8c63136087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa74abc96c2c040cadf477254fce733267f076a6837a9b90bc76e7ab3ef37790c8f80b0a80846b3d8e88ab37bf4fd52dc700e711ab387a6e265969fbebce04dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d29ecfaedc6a52e3380724053ebfe4ade29f476713c3c3c74e96969e87b9a4c64156728eeb98e6ac64f774ed69b411c43617c38a6bf6a315714bac7e76a3dd51"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 08:39:25.468670) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:39:25.468997) ========= 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.aqr76TDLJ6/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.xJV6ebJ6B4/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.005313 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:39:25.517288) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 08:39:25.517602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 08:39:25.517924) ========= ========= Total master_lag: 0.18 =========