builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0112 starttime: 1487682345.62 results: success (0) buildid: 20170221034428 builduid: b18a0b3ba2e340cb88f1701058b2b06e revision: c0053a0cf40f389068d7fdf443d721df8259cdaf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:45.622851) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:45.623663) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:45.624304) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:45.653789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:45.654485) ========= 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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-21 05:05:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2017-02-21 05:05:45 (14.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124641 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:45.821337) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:45.822025) ========= 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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.026974 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:45.875873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-21 05:05:45.876673) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c0053a0cf40f389068d7fdf443d721df8259cdaf --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 c0053a0cf40f389068d7fdf443d721df8259cdaf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GYb1BxOe2r/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.xdYjYNBxJW/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-21 05:05:45,976 truncating revision to first 12 chars 2017-02-21 05:05:45,976 Setting DEBUG logging. 2017-02-21 05:05:45,976 attempt 1/10 2017-02-21 05:05:45,976 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c0053a0cf40f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-21 05:05:47,428 unpacking tar archive at: mozilla-aurora-c0053a0cf40f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.717558 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-21 05:05:47.619641) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:47.619997) ========= script_repo_revision: c0053a0cf40f389068d7fdf443d721df8259cdaf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:47.620435) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:47.620733) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 05:05:47.641143) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-21 05:05:47.641446) ========= /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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:05:47 INFO - MultiFileLogger online at 20170221 05:05:47 in /builds/slave/test 05:05:47 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 05:05:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:47 INFO - {'append_to_log': False, 05:05:47 INFO - 'base_work_dir': '/builds/slave/test', 05:05:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:05:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:47 INFO - 'branch': 'Mozilla-Aurora', 05:05:47 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:47 INFO - 'config_files': ('talos/mac_config.py',), 05:05:47 INFO - 'default_actions': ('clobber', 05:05:47 INFO - 'read-buildbot-config', 05:05:47 INFO - 'download-and-extract', 05:05:47 INFO - 'populate-webroot', 05:05:47 INFO - 'create-virtualenv', 05:05:47 INFO - 'install', 05:05:47 INFO - 'run-tests'), 05:05:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:47 INFO - 'download_minidump_stackwalk': True, 05:05:47 INFO - 'download_symbols': 'ondemand', 05:05:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:47 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:47 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:47 INFO - 'installer_path': 'installer.exe', 05:05:47 INFO - 'log_level': 'info', 05:05:47 INFO - 'log_name': 'talos', 05:05:47 INFO - 'log_to_console': True, 05:05:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:47 INFO - 'opt_config_files': (), 05:05:47 INFO - 'pip_index': False, 05:05:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:47 INFO - 'cmd': ('bash', 05:05:47 INFO - '-c', 05:05:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:47 INFO - 'enabled': True, 05:05:47 INFO - 'halt_on_failure': False, 05:05:47 INFO - 'name': 'check_screen_resolution'},), 05:05:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:47 INFO - 'cmd': ('bash', 05:05:47 INFO - '-c', 05:05:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:47 INFO - 'enabled': True, 05:05:47 INFO - 'halt_on_failure': False, 05:05:47 INFO - 'name': 'check_screen_resolution'},), 05:05:47 INFO - 'run_cmd_checks_enabled': True, 05:05:47 INFO - 'sps_profile': False, 05:05:47 INFO - 'sps_profile_interval': 0, 05:05:47 INFO - 'suite': 'dromaeojs-e10s', 05:05:47 INFO - 'system_bits': '32', 05:05:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:47 INFO - 'title': 't-yosemite-r7-0112', 05:05:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:47 INFO - 'use_talos_json': True, 05:05:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:47 INFO - 'work_dir': 'build'} 05:05:47 INFO - [mozharness: 2017-02-21 13:05:47.853738Z] Running clobber step. 05:05:47 INFO - Running pre-action listener: _resource_record_pre_action 05:05:47 INFO - Running main action method: clobber 05:05:47 INFO - rmtree: /builds/slave/test/build 05:05:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:50 INFO - Running post-action listener: _resource_record_post_action 05:05:50 INFO - [mozharness: 2017-02-21 13:05:50.439548Z] Finished clobber step (success) 05:05:50 INFO - [mozharness: 2017-02-21 13:05:50.439672Z] Running read-buildbot-config step. 05:05:50 INFO - Running pre-action listener: _resource_record_pre_action 05:05:50 INFO - Running main action method: read_buildbot_config 05:05:50 INFO - Using buildbot properties: 05:05:50 INFO - { 05:05:50 INFO - "project": "", 05:05:50 INFO - "product": "firefox", 05:05:50 INFO - "script_repo_revision": "production", 05:05:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:05:50 INFO - "repository": "", 05:05:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:05:50 INFO - "buildid": "20170221034428", 05:05:50 INFO - "pgo_build": "False", 05:05:50 INFO - "basedir": "/builds/slave/test", 05:05:50 INFO - "buildnumber": 9, 05:05:50 INFO - "slavename": "t-yosemite-r7-0112", 05:05:50 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:05:50 INFO - "platform": "yosemite_r7", 05:05:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:05:50 INFO - "branch": "mozilla-aurora", 05:05:50 INFO - "repo_path": "releases/mozilla-aurora", 05:05:50 INFO - "revision": "c0053a0cf40f389068d7fdf443d721df8259cdaf", 05:05:50 INFO - "stage_platform": "macosx64", 05:05:50 INFO - "builduid": "b18a0b3ba2e340cb88f1701058b2b06e", 05:05:50 INFO - "slavebuilddir": "test" 05:05:50 INFO - } 05:05:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 05:05:50 INFO - Running post-action listener: _resource_record_post_action 05:05:50 INFO - [mozharness: 2017-02-21 13:05:50.443066Z] Finished read-buildbot-config step (success) 05:05:50 INFO - [mozharness: 2017-02-21 13:05:50.443153Z] Running download-and-extract step. 05:05:50 INFO - Running pre-action listener: _resource_record_pre_action 05:05:50 INFO - Running main action method: download_and_extract 05:05:50 INFO - mkdir: /builds/slave/test/build/tests 05:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:50 INFO - trying https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 05:05:50 INFO - Downloading https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/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 05:05:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/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 05:05:51 INFO - Downloaded 1199 bytes. 05:05:51 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 05:05:51 INFO - Using the following test package requirements: 05:05:51 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 05:05:51 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:05:51 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 05:05:51 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:05:51 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 05:05:51 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:05:51 INFO - u'jsshell-mac.zip'], 05:05:51 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:05:51 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 05:05:51 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 05:05:51 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:05:51 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 05:05:51 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:05:51 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 05:05:51 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:05:51 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 05:05:51 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:05:51 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 05:05:51 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:05:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 05:05:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:05:51 INFO - Fetch https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 05:05:52 INFO - Expected file size: 16359154 05:05:52 INFO - Obtained file size: 16359154 05:05:59 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 05:05:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 05:05:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:05:59 INFO - Fetch https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 05:06:00 INFO - Expected file size: 16359154 05:06:00 INFO - Obtained file size: 16359154 05:06:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 05:06:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:06:07 INFO - Fetch https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 05:06:08 INFO - Expected file size: 14403485 05:06:08 INFO - Obtained file size: 14403485 05:06:08 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')]} 05:06:08 INFO - trying https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 05:06:08 INFO - Downloading https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 05:06:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/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 05:06:11 INFO - Downloaded 73962711 bytes. 05:06:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 05:06:11 INFO - mkdir: /builds/slave/test/properties 05:06:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:06:11 INFO - Writing to file /builds/slave/test/properties/build_url 05:06:11 INFO - Contents: 05:06:11 INFO - build_url:https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 05:06:11 INFO - Running post-action listener: _resource_record_post_action 05:06:11 INFO - Running post-action listener: set_extra_try_arguments 05:06:11 INFO - [mozharness: 2017-02-21 13:06:11.755538Z] Finished download-and-extract step (success) 05:06:11 INFO - [mozharness: 2017-02-21 13:06:11.755657Z] Running populate-webroot step. 05:06:11 INFO - Running pre-action listener: _resource_record_pre_action 05:06:11 INFO - Running main action method: populate_webroot 05:06:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:06:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:06:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:06:11 INFO - '--tppagecycles', 05:06:11 INFO - '7'], 05:06:11 INFO - 'tests': ['tsvgm']}}, 05:06:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:06:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:06:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:06:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:06:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:06:11 INFO - 'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:06:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:06:11 INFO - 'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['damp', 'tps']}, 05:06:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:06:11 INFO - 'tests': ['damp', 'tps']}, 05:06:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['dromaeo_dom']}, 05:06:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['basic_compositor_video']}, 05:06:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:06:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['a11yr', 05:06:11 INFO - 'ts_paint', 05:06:11 INFO - 'tpaint', 05:06:11 INFO - 'sessionrestore', 05:06:11 INFO - 'sessionrestore_no_auto_restore', 05:06:11 INFO - 'tabpaint']}, 05:06:11 INFO - 'other-e10s': {'tests': ['a11yr', 05:06:11 INFO - 'ts_paint', 05:06:11 INFO - 'tpaint', 05:06:11 INFO - 'sessionrestore', 05:06:11 INFO - 'sessionrestore_no_auto_restore', 05:06:11 INFO - 'tabpaint']}, 05:06:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:06:11 INFO - 'ts_paint', 05:06:11 INFO - 'tpaint', 05:06:11 INFO - 'sessionrestore', 05:06:11 INFO - 'sessionrestore_no_auto_restore', 05:06:11 INFO - 'tabpaint']}, 05:06:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:06:11 INFO - 'ts_paint', 05:06:11 INFO - 'tpaint', 05:06:11 INFO - 'sessionrestore', 05:06:11 INFO - 'sessionrestore_no_auto_restore', 05:06:11 INFO - 'tabpaint']}, 05:06:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['a11yr', 05:06:11 INFO - 'ts_paint', 05:06:11 INFO - 'tpaint', 05:06:11 INFO - 'sessionrestore', 05:06:11 INFO - 'sessionrestore_no_auto_restore', 05:06:11 INFO - 'tabpaint']}, 05:06:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['a11yr', 05:06:11 INFO - 'ts_paint', 05:06:11 INFO - 'tpaint', 05:06:11 INFO - 'sessionrestore', 05:06:11 INFO - 'sessionrestore_no_auto_restore', 05:06:11 INFO - 'tabpaint']}, 05:06:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['tsvgx', 05:06:11 INFO - 'tsvgr_opacity', 05:06:11 INFO - 'tart', 05:06:11 INFO - 'tscrollx', 05:06:11 INFO - 'cart', 05:06:11 INFO - 'tsvg_static']}, 05:06:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:06:11 INFO - 'tsvgr_opacity', 05:06:11 INFO - 'tart', 05:06:11 INFO - 'tscrollx', 05:06:11 INFO - 'cart', 05:06:11 INFO - 'tsvg_static']}, 05:06:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:06:11 INFO - 'talos_options': ['--disable-e10s'], 05:06:11 INFO - 'tests': ['tp5o']}, 05:06:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:06:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:06:11 INFO - 'talos_options': ['--disable-e10s', 05:06:11 INFO - '--xperf_path', 05:06:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:11 INFO - 'tests': ['tp5n']}, 05:06:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:06:11 INFO - 'talos_options': ['--xperf_path', 05:06:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:11 INFO - 'tests': ['tp5n']}}, 05:06:11 INFO - 'talos.zip': {'path': '', 05:06:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:06:11 INFO - Running post-action listener: _resource_record_post_action 05:06:11 INFO - [mozharness: 2017-02-21 13:06:11.773889Z] Finished populate-webroot step (success) 05:06:11 INFO - [mozharness: 2017-02-21 13:06:11.774024Z] Running create-virtualenv step. 05:06:11 INFO - Running pre-action listener: _resource_record_pre_action 05:06:11 INFO - Running main action method: create_virtualenv 05:06:11 INFO - Creating virtualenv /builds/slave/test/build/venv 05:06:11 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 05:06:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:06:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:06:11 INFO - Using real prefix '/tools/python27' 05:06:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:06:12 INFO - Installing distribute.............................................................................................................................................................................................done. 05:06:15 INFO - Installing pip.................done. 05:06:15 INFO - Return code: 0 05:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:06:15 INFO - Reading from file tmpfile_stdout 05:06:15 INFO - Output received: 05:06:15 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) 05:06:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:06:15 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')]} 05:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:15 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')]} 05:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:15 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:15 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 05:06:15 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 05:06:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:15 INFO - 'HOME': '/Users/cltbld', 05:06:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:15 INFO - 'LOGNAME': 'cltbld', 05:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:15 INFO - 'MOZ_NO_REMOTE': '1', 05:06:15 INFO - 'NO_EM_RESTART': '1', 05:06:15 INFO - 'PAGER': '/bin/cat', 05:06:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:15 INFO - 'PWD': '/builds/slave/test', 05:06:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:15 INFO - 'SHELL': '/bin/bash', 05:06:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:15 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:15 INFO - 'USER': 'cltbld', 05:06:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:15 INFO - 'XPC_FLAGS': '0x0', 05:06:15 INFO - 'XPC_SERVICE_NAME': '0', 05:06:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:16 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 05:06:16 INFO - Cleaning up... 05:06:16 INFO - Return code: 0 05:06:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:06:16 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')]} 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:16 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')]} 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:16 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:16 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 05:06:16 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 05:06:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:16 INFO - 'HOME': '/Users/cltbld', 05:06:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:16 INFO - 'LOGNAME': 'cltbld', 05:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:16 INFO - 'MOZ_NO_REMOTE': '1', 05:06:16 INFO - 'NO_EM_RESTART': '1', 05:06:16 INFO - 'PAGER': '/bin/cat', 05:06:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:16 INFO - 'PWD': '/builds/slave/test', 05:06:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:16 INFO - 'SHELL': '/bin/bash', 05:06:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:16 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:16 INFO - 'USER': 'cltbld', 05:06:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:16 INFO - 'XPC_FLAGS': '0x0', 05:06:16 INFO - 'XPC_SERVICE_NAME': '0', 05:06:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:16 INFO - Downloading/unpacking psutil>=3.1.1 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:19 INFO - Installing collected packages: psutil 05:06:19 INFO - Running setup.py install for psutil 05:06:19 INFO - building 'psutil._psutil_osx' extension 05:06:19 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 05:06:20 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 05:06:20 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 05:06:20 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 05:06:20 INFO - building 'psutil._psutil_posix' extension 05:06:20 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 05:06:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:06:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:06:20 INFO - ^ 05:06:20 INFO - 1 warning generated. 05:06:20 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 05:06:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:20 INFO - Successfully installed psutil 05:06:20 INFO - Cleaning up... 05:06:21 INFO - Return code: 0 05:06:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:06:21 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')]} 05:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 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')]} 05:06:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:21 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 05:06:21 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 05:06:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:21 INFO - 'HOME': '/Users/cltbld', 05:06:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:21 INFO - 'LOGNAME': 'cltbld', 05:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:21 INFO - 'MOZ_NO_REMOTE': '1', 05:06:21 INFO - 'NO_EM_RESTART': '1', 05:06:21 INFO - 'PAGER': '/bin/cat', 05:06:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:21 INFO - 'PWD': '/builds/slave/test', 05:06:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:21 INFO - 'SHELL': '/bin/bash', 05:06:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:21 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:21 INFO - 'USER': 'cltbld', 05:06:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:21 INFO - 'XPC_FLAGS': '0x0', 05:06:21 INFO - 'XPC_SERVICE_NAME': '0', 05:06:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:06:21 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 05:06:21 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 05:06:21 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 05:06:21 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 05:06:21 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 05:06:21 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 05:06:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:06:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:06:23 INFO - Installing collected packages: mozsystemmonitor 05:06:23 INFO - Running setup.py install for mozsystemmonitor 05:06:24 INFO - Successfully installed mozsystemmonitor 05:06:24 INFO - Cleaning up... 05:06:24 INFO - Return code: 0 05:06:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:24 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:24 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 05:06:24 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 05:06:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:24 INFO - 'HOME': '/Users/cltbld', 05:06:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:24 INFO - 'LOGNAME': 'cltbld', 05:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:24 INFO - 'MOZ_NO_REMOTE': '1', 05:06:24 INFO - 'NO_EM_RESTART': '1', 05:06:24 INFO - 'PAGER': '/bin/cat', 05:06:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:24 INFO - 'PWD': '/builds/slave/test', 05:06:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:24 INFO - 'SHELL': '/bin/bash', 05:06:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:24 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:24 INFO - 'USER': 'cltbld', 05:06:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:24 INFO - 'XPC_FLAGS': '0x0', 05:06:24 INFO - 'XPC_SERVICE_NAME': '0', 05:06:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:24 INFO - Downloading/unpacking jsonschema==2.5.1 05:06:24 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 05:06:24 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 05:06:24 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 05:06:24 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 05:06:24 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 05:06:24 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 05:06:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:06:27 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 05:06:27 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 05:06:27 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 05:06:27 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 05:06:27 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 05:06:27 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 05:06:27 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:27 INFO - warning: no files found matching '*.txt' 05:06:27 INFO - no previously-included directories found matching 'build' 05:06:27 INFO - no previously-included directories found matching 'dist' 05:06:27 INFO - no previously-included directories found matching '.git*' 05:06:27 INFO - Installing collected packages: jsonschema, functools32 05:06:27 INFO - Running setup.py install for functools32 05:06:27 INFO - warning: no files found matching '*.txt' 05:06:27 INFO - no previously-included directories found matching 'build' 05:06:27 INFO - no previously-included directories found matching 'dist' 05:06:27 INFO - no previously-included directories found matching '.git*' 05:06:27 INFO - Successfully installed jsonschema functools32 05:06:27 INFO - Cleaning up... 05:06:27 INFO - Return code: 0 05:06:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:06:27 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')]} 05:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:27 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')]} 05:06:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:27 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:27 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 05:06:27 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 05:06:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:28 INFO - 'HOME': '/Users/cltbld', 05:06:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:28 INFO - 'LOGNAME': 'cltbld', 05:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:28 INFO - 'MOZ_NO_REMOTE': '1', 05:06:28 INFO - 'NO_EM_RESTART': '1', 05:06:28 INFO - 'PAGER': '/bin/cat', 05:06:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:28 INFO - 'PWD': '/builds/slave/test', 05:06:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:28 INFO - 'SHELL': '/bin/bash', 05:06:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:28 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:28 INFO - 'USER': 'cltbld', 05:06:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:28 INFO - 'XPC_FLAGS': '0x0', 05:06:28 INFO - 'XPC_SERVICE_NAME': '0', 05:06:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:06:28 INFO - Cleaning up... 05:06:28 INFO - Return code: 0 05:06:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:28 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:28 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 05:06:28 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 05:06:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:28 INFO - 'HOME': '/Users/cltbld', 05:06:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:28 INFO - 'LOGNAME': 'cltbld', 05:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:28 INFO - 'MOZ_NO_REMOTE': '1', 05:06:28 INFO - 'NO_EM_RESTART': '1', 05:06:28 INFO - 'PAGER': '/bin/cat', 05:06:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:28 INFO - 'PWD': '/builds/slave/test', 05:06:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:28 INFO - 'SHELL': '/bin/bash', 05:06:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:28 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:28 INFO - 'USER': 'cltbld', 05:06:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:28 INFO - 'XPC_FLAGS': '0x0', 05:06:28 INFO - 'XPC_SERVICE_NAME': '0', 05:06:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:28 INFO - Downloading/unpacking blobuploader==1.2.4 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:31 INFO - Downloading blobuploader-1.2.4.tar.gz 05:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:06:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:06:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:32 INFO - Downloading docopt-0.6.1.tar.gz 05:06:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:32 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:32 INFO - Running setup.py install for blobuploader 05:06:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:06:32 INFO - Running setup.py install for requests 05:06:33 INFO - Running setup.py install for docopt 05:06:33 INFO - Successfully installed blobuploader requests docopt 05:06:33 INFO - Cleaning up... 05:06:33 INFO - Return code: 0 05:06:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:33 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')]} 05:06:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:33 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')]} 05:06:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:33 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:33 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 05:06:33 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 05:06:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:33 INFO - 'HOME': '/Users/cltbld', 05:06:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:33 INFO - 'LOGNAME': 'cltbld', 05:06:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:33 INFO - 'MOZ_NO_REMOTE': '1', 05:06:33 INFO - 'NO_EM_RESTART': '1', 05:06:33 INFO - 'PAGER': '/bin/cat', 05:06:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:33 INFO - 'PWD': '/builds/slave/test', 05:06:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:33 INFO - 'SHELL': '/bin/bash', 05:06:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:33 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:33 INFO - 'USER': 'cltbld', 05:06:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:33 INFO - 'XPC_FLAGS': '0x0', 05:06:33 INFO - 'XPC_SERVICE_NAME': '0', 05:06:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-vOlxbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-oLZ2sQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-UoYSDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kWBCKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-c2msyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-s0vvlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-JNbZSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-pVsBuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-BhNQde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-NuxskV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-jEtdlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-5mVC6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-7Sbtq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:35 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-FIPDI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:35 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ein7XO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:35 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-hruJfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:35 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Tm5hm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:35 INFO - Running setup.py install for manifestparser 05:06:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Running setup.py install for mozcrash 05:06:35 INFO - Running setup.py install for mozdebug 05:06:35 INFO - Running setup.py install for mozdevice 05:06:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Running setup.py install for mozfile 05:06:36 INFO - Running setup.py install for mozhttpd 05:06:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Running setup.py install for mozinfo 05:06:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Running setup.py install for mozInstall 05:06:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:06:36 INFO - Running setup.py install for mozleak 05:06:36 INFO - Running setup.py install for mozlog 05:06:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for moznetwork 05:06:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozprocess 05:06:37 INFO - Running setup.py install for mozprofile 05:06:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozrunner 05:06:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:06:37 INFO - Running setup.py install for mozscreenshot 05:06:37 INFO - Running setup.py install for moztest 05:06:37 INFO - Running setup.py install for mozversion 05:06:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:06:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:38 INFO - Cleaning up... 05:06:38 INFO - Return code: 0 05:06:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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')]} 05:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:38 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:38 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 05:06:38 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 05:06:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:38 INFO - 'HOME': '/Users/cltbld', 05:06:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:38 INFO - 'LOGNAME': 'cltbld', 05:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:38 INFO - 'MOZ_NO_REMOTE': '1', 05:06:38 INFO - 'NO_EM_RESTART': '1', 05:06:38 INFO - 'PAGER': '/bin/cat', 05:06:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:38 INFO - 'PWD': '/builds/slave/test', 05:06:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:38 INFO - 'SHELL': '/bin/bash', 05:06:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:38 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:38 INFO - 'USER': 'cltbld', 05:06:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:38 INFO - 'XPC_FLAGS': '0x0', 05:06:38 INFO - 'XPC_SERVICE_NAME': '0', 05:06:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-6kIbJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-HfomQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-UaT_JL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-el1o6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-0s0L6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:38 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)) 05:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-hV7yDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-yVSB7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ylgVhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-uIexRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-3ODApd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-3PA_Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-pFybAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-POorFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-23slM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-e9pQAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:39 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)) 05:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:39 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-oPIKoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:40 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)) 05:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:40 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-LlZorV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:40 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)) 05:06:40 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)) 05:06:40 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)) 05:06:40 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)) 05:06:40 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)) 05:06:40 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)) 05:06:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:40 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 05:06:40 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 05:06:40 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 05:06:40 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 05:06:40 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 05:06:40 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 05:06:43 INFO - Downloading blessings-1.6.tar.gz 05:06:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:43 INFO - Installing collected packages: blessings 05:06:43 INFO - Running setup.py install for blessings 05:06:43 INFO - Successfully installed blessings 05:06:43 INFO - Cleaning up... 05:06:43 INFO - Return code: 0 05:06:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:43 INFO - Reading from file tmpfile_stdout 05:06:43 INFO - Current package versions: 05:06:43 INFO - blessings == 1.6 05:06:43 INFO - blobuploader == 1.2.4 05:06:43 INFO - docopt == 0.6.1 05:06:43 INFO - functools32 == 3.2.3-2 05:06:43 INFO - jsonschema == 2.5.1 05:06:43 INFO - manifestparser == 1.1 05:06:43 INFO - mozInstall == 1.12 05:06:43 INFO - mozcrash == 1.0 05:06:43 INFO - mozdebug == 0.1 05:06:43 INFO - mozdevice == 0.49 05:06:43 INFO - mozfile == 1.2 05:06:43 INFO - mozhttpd == 0.7 05:06:43 INFO - mozinfo == 0.9 05:06:43 INFO - mozleak == 0.1 05:06:43 INFO - mozlog == 3.4 05:06:43 INFO - moznetwork == 0.27 05:06:43 INFO - mozprocess == 0.24 05:06:43 INFO - mozprofile == 0.28 05:06:43 INFO - mozrunner == 6.13 05:06:43 INFO - mozscreenshot == 0.1 05:06:43 INFO - mozsystemmonitor == 0.3 05:06:43 INFO - moztest == 0.8 05:06:43 INFO - mozversion == 1.4 05:06:43 INFO - psutil == 3.1.1 05:06:43 INFO - requests == 1.2.3 05:06:43 INFO - wsgiref == 0.1.2 05:06:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:43 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')]} 05:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:44 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 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:44 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 05:06:44 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 05:06:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:44 INFO - 'HOME': '/Users/cltbld', 05:06:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:44 INFO - 'LOGNAME': 'cltbld', 05:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:44 INFO - 'MOZ_NO_REMOTE': '1', 05:06:44 INFO - 'NO_EM_RESTART': '1', 05:06:44 INFO - 'PAGER': '/bin/cat', 05:06:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:44 INFO - 'PWD': '/builds/slave/test', 05:06:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:44 INFO - 'SHELL': '/bin/bash', 05:06:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:44 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:44 INFO - 'USER': 'cltbld', 05:06:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:44 INFO - 'XPC_FLAGS': '0x0', 05:06:44 INFO - 'XPC_SERVICE_NAME': '0', 05:06:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 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)) 05:06:44 INFO - Cleaning up... 05:06:44 INFO - Return code: 0 05:06:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a697b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bb0590da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, '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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 05:06:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:06:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:06:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:06:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:44 INFO - 'HOME': '/Users/cltbld', 05:06:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:44 INFO - 'LOGNAME': 'cltbld', 05:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:44 INFO - 'MOZ_NO_REMOTE': '1', 05:06:44 INFO - 'NO_EM_RESTART': '1', 05:06:44 INFO - 'PAGER': '/bin/cat', 05:06:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:44 INFO - 'PWD': '/builds/slave/test', 05:06:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:44 INFO - 'SHELL': '/bin/bash', 05:06:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:06:44 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:06:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:44 INFO - 'USER': 'cltbld', 05:06:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:44 INFO - 'XPC_FLAGS': '0x0', 05:06:44 INFO - 'XPC_SERVICE_NAME': '0', 05:06:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:06:44 INFO - Cleaning up... 05:06:44 INFO - Return code: 0 05:06:44 INFO - Running post-action listener: _resource_record_post_action 05:06:44 INFO - Running post-action listener: _start_resource_monitoring 05:06:44 INFO - Starting resource monitoring. 05:06:44 INFO - [mozharness: 2017-02-21 13:06:44.775931Z] Finished create-virtualenv step (success) 05:06:44 INFO - [mozharness: 2017-02-21 13:06:44.776818Z] Running install step. 05:06:44 INFO - Running pre-action listener: _resource_record_pre_action 05:06:44 INFO - Running main action method: install 05:06:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:45 INFO - Reading from file tmpfile_stdout 05:06:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:45 INFO - Reading from file tmpfile_stdout 05:06:45 INFO - Output received: 05:06:45 INFO - Usage: mozinstall [options] installer 05:06:45 INFO - Options: 05:06:45 INFO - -h, --help show this help message and exit 05:06:45 INFO - -d DEST, --destination=DEST 05:06:45 INFO - Directory to install application into. [default: 05:06:45 INFO - "/builds/slave/test"] 05:06:45 INFO - --app=APP Application being installed. [default: firefox] 05:06:45 INFO - mkdir: /builds/slave/test/build/application 05:06:45 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'] 05:06:45 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 05:07:04 INFO - Reading from file tmpfile_stdout 05:07:04 INFO - Output received: 05:07:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:07:04 INFO - Running post-action listener: _resource_record_post_action 05:07:04 INFO - [mozharness: 2017-02-21 13:07:04.969302Z] Finished install step (success) 05:07:04 INFO - [mozharness: 2017-02-21 13:07:04.969440Z] Running run-tests step. 05:07:04 INFO - Running pre-action listener: _resource_record_pre_action 05:07:04 INFO - Running main action method: run_tests 05:07:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:04 INFO - 2017-02-21 05:07:04.983 screenresolution[1628:7487] starting screenresolution argv=screenresolution get 05:07:04 INFO - 2017-02-21 05:07:04.986 screenresolution[1628:7487] Display 0: 1600x1200x32@60 05:07:04 INFO - 2017-02-21 05:07:04.992 screenresolution[1629:7490] starting screenresolution argv=screenresolution list 05:07:04 INFO - Available Modes on Display 0 05:07:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:05 INFO - Intel Iris: 05:07:05 INFO - Chipset Model: Intel Iris 05:07:05 INFO - Type: GPU 05:07:05 INFO - Bus: Built-In 05:07:05 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:05 INFO - Vendor: Intel (0x8086) 05:07:05 INFO - Device ID: 0x0a2e 05:07:05 INFO - Revision ID: 0x0009 05:07:05 INFO - Displays: 05:07:05 INFO - Display: 05:07:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:05 INFO - Display Serial Number: 1600x1200 60 05:07:05 INFO - Main Display: Yes 05:07:05 INFO - Mirror: Off 05:07:05 INFO - Online: Yes 05:07:05 INFO - Rotation: Supported 05:07:05 INFO - Return code: 0 05:07:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:07:05 INFO - Python 2.7.3 05:07:05 INFO - Return code: 0 05:07:05 INFO - grabbing minidump binary from tooltool 05:07: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')]} 05:07:05 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 0x10a475d30>, '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 0x10a39cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a46abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:07:05 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 05:07:05 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 05:07:05 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 05:07:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:07:05 INFO - Return code: 0 05:07:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:07:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:07:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:07:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:07:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:07:05 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/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 05:07:05 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/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0112 --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 05:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYb1BxOe2r/Render', 05:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:05 INFO - 'HOME': '/Users/cltbld', 05:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:05 INFO - 'LOGNAME': 'cltbld', 05:07:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:07:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:05 INFO - 'MOZ_NO_REMOTE': '1', 05:07:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:07:05 INFO - 'NO_EM_RESTART': '1', 05:07:05 INFO - 'PAGER': '/bin/cat', 05:07:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:05 INFO - 'PWD': '/builds/slave/test', 05:07:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:05 INFO - 'SHELL': '/bin/bash', 05:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xdYjYNBxJW/Listeners', 05:07:05 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:05 INFO - 'USER': 'cltbld', 05:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:05 INFO - 'XPC_FLAGS': '0x0', 05:07:05 INFO - 'XPC_SERVICE_NAME': '0', 05:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:05 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/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 05:07:05 INFO - mozversion application_buildid: 20170221034428 05:07:05 INFO - mozversion application_changeset: c0053a0cf40f389068d7fdf443d721df8259cdaf 05:07:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:07:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:07:05 INFO - mozversion application_name: Firefox 05:07:05 INFO - mozversion application_remotingname: firefox-dev 05:07:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:07:05 INFO - mozversion application_vendor: Mozilla 05:07:05 INFO - mozversion application_version: 53.0a2 05:07:05 INFO - mozversion platform_buildid: 20170221034428 05:07:05 INFO - mozversion platform_changeset: c0053a0cf40f389068d7fdf443d721df8259cdaf 05:07:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:07:05 INFO - mozversion platform_version: 53.0a2 05:07:05 INFO - using testdate: 1487682425 05:07:05 INFO - actual date: 1487682425 05:07:05 INFO - starting webserver on 'localhost:49242' 05:07:05 INFO - SUITE-START | Running 2 tests 05:07:05 INFO - TEST-START | dromaeo_css 05:07:05 INFO - operating with platform_type : mac_ 05:07:05 INFO - Initialising browser for dromaeo_css test... 05:07:05 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpz8N4k4/profile http://localhost:49242/getInfo.html) 05:07:08 INFO - PROCESS | 1639 | 2017-02-21 05:07:08.574 plugin-container[1641:7667] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 05:07:08 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:08 INFO - PROCESS | 1639 | 2017-02-21 05:07:08.577 plugin-container[1641:7667] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0b, name = 'com.apple.CFPasteboardClient' 05:07:08 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:08 INFO - PROCESS | 1639 | 2017-02-21 05:07:08.577 plugin-container[1641:7667] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:07:08 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 05:07:08 INFO - PROCESS | 1639 | colorDepth:24 05:07:08 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 05:07:08 INFO - PROCESS | 1639 | __metrics 05:07:08 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:07:09 INFO - TEST-INFO | 1639: exit 0 05:07:09 INFO - Browser initialized. 05:07:09 INFO - Running cycle 1/1 for dromaeo_css test... 05:07:09 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpz8N4k4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:11 INFO - PROCESS | 1642 | 2017-02-21 05:07:11.934 plugin-container[1643:7797] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 05:07:11 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:11 INFO - PROCESS | 1642 | 2017-02-21 05:07:11.935 plugin-container[1643:7797] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 05:07:11 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:11 INFO - PROCESS | 1642 | 2017-02-21 05:07:11.935 plugin-container[1643:7797] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:07:21 INFO - PROCESS | 1642 | RSS: Main: 219230208 05:07:21 INFO - PROCESS | 1642 | 05:10:23 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 05:10:25 INFO - PROCESS | 1642 | RSS: Main: 236433408 05:10:25 INFO - PROCESS | 1642 | 05:12:10 INFO - PROCESS | 1642 | ************************* 05:12:10 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 05:12:10 INFO - PROCESS | 1642 | 05:12:10 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:12:10 INFO - PROCESS | 1642 | Full stack: 05:12:10 INFO - PROCESS | 1642 | ************************* 05:13:27 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 05:13:28 INFO - PROCESS | 1642 | RSS: Main: 246554624 05:13:28 INFO - PROCESS | 1642 | 05:16:30 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 05:16:30 INFO - PROCESS | 1642 | RSS: Main: 253693952 05:16:30 INFO - PROCESS | 1642 | 05:19:32 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 05:19:33 INFO - PROCESS | 1642 | RSS: Main: 250368000 05:19:33 INFO - PROCESS | 1642 | 05:22:35 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 05:22:36 INFO - PROCESS | 1642 | RSS: Main: 254353408 05:22:36 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 05:25:39 INFO - PROCESS | 1642 | RSS: Main: 255676416 05:25:39 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | __start_tp_report 05:25:39 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 05:25:39 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 05:25:39 INFO - PROCESS | 1642 | |i|pagename|runs| 05:25:39 INFO - PROCESS | 1642 | |0;dojo.html;2681.919542413728;3292.8518216680245;3293.0450169450874;3328.4508056170735;3331.0839519132237;4241.957580424195;5396.217548455472;5403.756830942607;5434.83695489135;5440.483154100364;4753.90492190156;5432.239486471897;5476.014317422862;5509.063459211933;5523.972380138103;4205.306124489455;5047.394312682465;5061.468545802693;5091.949080509203;5113.821016264432;5075.111855425286;5124.6412751107255;5125;5135.486451354854;5145.073886700392;4936.876578085541;4963.205887058071;5062.417821950467;5117.130087885069;5164.896702065957;5054.014467178901;5056.924146137792;5081.060003899291;5100.0054989277105;5148.974255128738;5053.772580233898;5056.367954005749;5067.062593420229;5071.46749591295;5102.6428150029515;5334.59990500714;5419.160030195326;5430.8913821723345;5461.098918678414;5594.832155035356;5839.0949402842625;5842.853928651775;5876.147958546029;5886.470217680387;5993.4006599340155;6035.943709850802;6044.697765111727;6059.212302400704;6068.423499767515;6080.30076541199;5544.556435485232;5553.750081246313;5561;5579.386267510611;5581.413951535071;6035.637861728311;6094.969525152346;6111.113888486145;6123.83172914054;6132.478739307119;4384.69307148503;5829.562782791308;5830.2420685310635;5855.912161317584;5978.282606087298;5966.671833049137;6023.186869772612;6032.54755893301;6052.273727152682;6061.060535616986;5745;5793.652380857162;5809.477147056785;5830.591858569859;5987.341392446828;5895.380984996599;5902.144189092557;5904.675242861598;5927.347991720907;5972.432618901197;4807.413553527327;4811.855644330606;4831.371921650233;4832.033593281358;4838.661293709477;3506.6142724300307;3599.6940260078186;4290.2706539888295;4323.351497275371;4325.048489332342;4833.927491087637;5555.749991250404;5576.498115169654;5627.971860140673;5657.066584013674;6011.12838638395;6032.517398608101;6059.121427393092;6062.727177277033;6083.482903953125;5501.944980550224;5520.171974203901;5544.695041772741;5564.749586268627;5582.162675598693;5261.5264626183825;5263.605229607795;5268.946210757864;5270.578353731769;5300.549453296436;6025.975584150618;6198;6199.039148931923;6244.625322480666;6250.937490625127;5883.2057672213705;5918.940810591839;5955.017422125474;6003.3996600339615;6060.787872424444;5290.709011004278;5532.640378375393;5674.4892959633835;6146.078088286793;6202.131701561695;5856.326522449973;5880.529557635294;5918.082697181943;5933.525317974465;5933.851653708519;5873.676947767914;5878.706064696663;5964.940350596438;5997.91003134962;6006.969965150146;5312.946870531246;5832.766689332549;5861.501772349398;5868.823935281948;5869.236999190249;4733.881652958704;5794.942050579441;5821;5827.621204621686;5841.299044114563;5566.888662226652;5573.860653483695;5584.46947539983;5634.267545219095;5684.573656975661;4937.456879743154;5538.556915446674;5737.512311453574;5847.5906686533235;5869.970650146722;5605.915911261252;5669.31968163823;5673.347565030074;5673.404292549388;5689.146628005832 05:25:39 INFO - PROCESS | 1642 | |1;ext.html;474.91214125386807;493.44733898034184;501.8394113883554;507.0720581336155;538.3405328472624;28136.57795133072;37246.627533724655;37259.8822035339;37276.254474910485;37310;41054.794726026324;41126.58873411265;41544.37683434751;41582.792086039524;41683.37474937878;8359.164083591653;8604.827903441927;9116.589753461094;9298.256139508823;9316.627334906629;11061.944690276578;11294.774104517905;11328.886711132865;11347.43262836859;11460.426978651076;11235.157363197752;11273.32360058395;11289.943550282238;11326.433678316092;11357;11321.490532925996;11396.71508212293;11401.37292448915;11406.657800265964;11411.600593979254;9323.580438880266;11322.773544529064;11379.374134422604;11387.54449822006;11421.885781142248;39515.40726889099;39753.80738577847;39786.403203951966;39932.40101398481;39971.80014099911;3908.6482216600357;3951.4270430787374;3984.362501999666;4020.2361551305157;4062.1875624874847;29382.55926161109;30981.845090774626;31035.0689479316;31220.531692024637;31304.686953130404;3081.5223640335785;3226.1934516370907;3232.095013396229;3233.596741506466;3388.102152929476;351085;351710;352419;352722;352951;57526.84946301134;60923.69538152369;61059;61068.69465652643;61102.083468748;63920;64328.67835660792;64362;64367.71264574776;64576;16345;16448.671026579403;16620.501384958472;16638.417655382003;16725.163741813103;10337.689869303933;11480.540778368771;11733.061355091571;12884.96920246378;13030.80453793194;14762.48331308399;16628.6674266514;16957.745633815503;17128;17193.57016074608;6792.558483698544;6966.9651651741415;7027.4378049756915;7036.085308909809;7057.682404291819;859.8409294280578;860.8665656823202;869.427602100874;871.2986046232717;905.2441211588398;25659.74340256611;29583;29712.96004639827;29722.959696410522;30022.399552009276;962.8651988721584;1015.8730158730159;1043.7234132954773;1043.9530221140067;1100.2573263047411;12746.49014039428;13959.302034898417;16288.511344659679;16491.257893394824;16602.75095873563;499016;602766;606306;607528;608535;58863;59064.818703626544;59207.40792592105;59270.11094833581;59287.814243714885;1411.3084588551535;1434.0535246737104;1459.2630721485793;1461.5250043735828;1464.0337377330911;29141.271468213465;30252.09243722692;30829.69170308268;30848.53727194049;31142.377152457277;50038.24942625936;50154.746131347005;50289.99420011506;50349.74825125851;50358;508148;508358;512650;513562;515261;38238;38738.41892371673;38861.417078743274;38987;39144.6085539141;45168.096638066396;45895.082098358514;46131.07737845157;46412;46825;31957.721691133025;35036;36830.89507314785;37155.25689486141;37228.813855930544;37925.431118532695;38445.807770960964;38801.805990969864;38985.6101438982;39351.40972885466;36194.4570831443;36248;37521.874343769734;37704.81147594244;37781;31609;33811;34003;34155.82922085374;34258.65741342655;24830;25042.62436063497;25044.874775626005;25496.49007019812;25529.617055743805 05:25:39 INFO - PROCESS | 1642 | |2;jquery.html;396.09492310071494;398.59343469660945;408.764960147915;409.30213985155314;425.17303844023365;14298.285085745723;15981.360745570193;19543.70684439733;20830.583388332245;20856.165753369845;19894.70157947632;20790.376288711326;21113.261035863747;21223.787762122374;21440.356789296307;5552.9167062494;5709.571782116337;5959.195508606326;6023.849403764897;6045.214122164112;5956.910646340309;5993.820185394445;5996.580239383223;6010.549208809357;6121.846953826145;6477.708503117371;6504.967475162617;6509.869802603945;6527.967360163192;6531.281559028503;5491.615586908918;5928.318243401977;5984.401559843981;6040.48655864252;6503.28463868974;5772.480476757112;5804.82585522435;5842.795502157404;5846.298444186725;5848.941510584925;12012.939935300356;13179.934100329438;13362.933185334108;13527.458901643922;13538.255395953218;2239.5296987632455;2241.3612120545567;2442.8884857389844;2612.9608055879175;2649.576067829138;8133.227343402426;8185;10849.752278487917;10984.450777461096;15582.922085389613;1749.0904729540568;1816.3461153984556;1828.5611453251124;1847.833694967446;1868.4768264885854;105340.89318213593;129846.3507682465;133224.00163997547;133919;141680.87478687827;9968.80062398748;11799.35103569312;11803.350815705218;12024.098192635587;12307.75384492305;15536.611584710246;15698.529044128694;15734.370625174864;15802.841971580367;15807.972481788647;8056.791481277855;8079.232472915063;8718.215360617574;8898.59956301955;8938.910610893938;8522.232999030117;8604.612792424356;8730.650773969097;8744.519051452104;8746.212840844355;4069.2878746219294;4658.394408726844;4795.688280261772;4820.758962051884;4898.363212782316;2053.650879350514;2077.418322869599;2077.7195078664186;2081.354780018199;2081.6565266731122;803.2730379007008;822.3873214455269;838.9035260945069;839.4417712221305;856.546030603781;20882.791172088084;21580;21605.891970540048;21871.562568748537;21952.45118872041;865.8095019600421;869.7825543614097;893.8927328720595;898.9280857531381;900.144862380741;11093.667189984313;11289.435528223721;11366.715832104264;11370.886291137149;11526.020288275422;540138;660427;668091;709854;718827;138241;140796.29601851926;144082;148927;152317.2384138072;901.7700486375932;929.7164364868645;1049.9737506562396;1073.1039581949003;1081.4862940103387;18264.36074737378;18950.86294822315;19033.238670453025;19039.333623323117;19155.71266430977;46997;54738.178927315326;58181;58831.05844707833;61590.22843972121;65494;75839.86240206512;78942.8422863079;82581;84725;6834.8633027340165;8147.511149331059;8223.876641850497;8366;8504.702335418231;10985.945070274916;12407.44166512491;12470.688232794253;12794.360281985677;13253;9297.581608827484;10073.848892266475;10675.57297708083;10892.836607450736;11012.889871101506;9019.81960360776;10606.257561970588;10738.892611073788;11072.446377681244;11227.38249396291;10883.945580272364;11172.944135279597;11220.719482012688;11234.101271898391;11237.38194399316;8091.1504292048485;8202.138775428493;8244.917550824655;8410.411271210956;8450.746477605682;10703.732406689895;10810.405427701551;10884.020438160604;11013.2841365311;11025 05:25:39 INFO - PROCESS | 1642 | |3;mootools.html;1429.5568373804122;1436.741386550421;1444.2634256529168;1450.4778279819263;1458.56972193203;6963.686634101465;7788.143304236543;7932.56370899601;8183.7544873653815;8593.312534997202;7989.800254993628;8148.185181481849;8311.293540049088;8415.326773858093;8425.1153628869;2312.364099872536;2365.2312998275543;2367.218817790134;2372.28831350595;2386.367612582667;1682.6887025900187;1733.7486064520638;2360.586897292967;2389.6057150570136;2405.9879700601473;1847.3441928115526;2273.533925545259;2287.6731495732433;2354.19957214547;2356;2304.585174668559;2314.305708287507;2328.8253380996493;2338.8596684199;2341.1337805012167;2332.8017118544944;2340.2979106268053;2396.7842894139612;2398.1846172301325;2402.6756387887763;8452.197041281068;8557.101504342018;8564.957175214146;8569.35729820266;8572.528510931896;5104.617153713486;5272;5272.894542109137;5274.92087618686;5277.287566178594;3092.2424006118645;3462.8614855405754;3563.251717139404;3575.5173051637958;3646.762960407565;1218.3786269002783;1223.0521345956865;1253.2104773992417;1253.8558065822456;1274.286399616218;10830.891691083067;10929.125669946385;10966;10996.06533444658;11024.118070554336;4017.517897852218;4429.402030725811;4755.83354582588;4774.809007639656;4816.446108697544;8143.552104634184;9647;9653.03469653029;9672.371295865747;9707.757306067264;5297.17893726473;5335.919961200585;5344.225087362311;5345.1714984177515;5351.464853514617;4576.176288268077;4714.45783734868;4780.689255198401;4888.975555122272;4898.853034408973;3363.428217203081;3949.7037722170953;3978.3833505806647;4044.5753195914594;4083.469149010669;1204.2292932523017;1292.75437666843;1293.7062937062938;1297.2475963940892;1303.9739205216033;653.9803805885831;782.5500337306072;802.3420794948203;838.5178522349673;888.0409158109227;4743.667943244009;5262.552797980586;5361.437049109865;5397.2443857859935;5508.338999320107;876.8553188723789;949.2595775295281;953.7901661634429;974.8294048541477;1004.7186787699455;3194.34515924235;3627.981860090683;3644.653757892996;3680.6687398134295;3697.260547890379;9762.414255144715;10136.594536218616;10155.492225388702;10163.085322321027;10196.745081373025;10012.849807252896;10026.448545329931;10035.749106272402;10077.69766906994;10080.344777589433;7456.291652293024;7893.842123157505;7894.131645518988;8001.479903806234;8006.559639219899;5620.409856965123;5647.322321321303;5669.773209071591;5759.251297331488;5816.82549523515;8888.60001299956;9129.041450647586;9297.6280948764;9350.906490935004;9370.484623345781;9038.141376569216;9359.391639543408;9458.33791634579;9551.94928557872;9561.521923903638;7193.892091618525;7246.818829529304;7256.528325659026;7306.452016098708;7370.705171793068;7858.44990850635;7936.484128531858;7937.2459616336355;7952.36381089523;7962.601869906365;6080.847978800389;7054.8236294091;7169.713211471482;7197.316254955771;7304.6347682616715;7102.573845569283;7105.3960413363375;7123.821904452223;7151.141862976476;7165.8892871604985;6329.588576742497;6341.4292713656;6428.389303016206;6445.871082578416;6450.9354906450335;4911.705297682151;4967.4535801062575;4969.055879382906;4983.800647974041;5039.571636410946;4471.128130014746;4862.513748625109;4881.170201065898;4916.557509824133;4946.530079642428 05:25:39 INFO - PROCESS | 1642 | |4;prototype.html;350.9210427653778;358.5750885201037;367.7039982813834;367.7242068448663;376.62149539712567;4544.136614043329;5438.700871452069;5452.700101494416;5528.889422211553;5567.8886422271535;4538.863834084983;5390.8921821563545;5426.728663566825;5427.185922111686;5441.891162176754;3414.590249170103;3631.2221150819646;4252.362145678142;4276.294411422127;4283.4431523902;3804.372278574032;4105.7331273467125;4141.9585804142025;4153.0863210093885;4170.311898536753;3581.570211574615;4103.54860965295;4116.917661646765;4124.546299907008;4150.464328198571;4147.527627692186;4155.33514637658;4155.916881662365;4172.374143878412;4179.811908464127;3186.9043928682177;3474.8957531274104;3517.982410087933;3560.928781424357;3580.7314451415987;4120.0335758254005;4384.583464570861;4864.367632207825;5109.821156259551;5231;4644.58198762113;4693.883666803201;4765.880852978668;4767.213409787381;4774.689645173087;5013;5492.972535137299;5792.420757924174;5803;5829.329627092896;3905.6875449963936;3922.5096862892137;3926.8821935341984;3938.35017222158;3970.3647416413237;5335.439778823206;5608.943910560883;6018.886505996361;6084.471006816036;6115.471132216946;3503.667151620592;3863.517060367454;3935.2326296371934;3955.248502784462;3968.1666849961252;4017.296973029708;4037.4953130858644;4045.5954404559307;4059.7767122807936;4073.1242782801246;3978.303796835542;4395.033092719596;4728.267118596623;4733.715977041388;4735.2186889163595;5226.764795584208;5627.803026894121;5664.631798933056;5798.82603521895;5811.738471768781;1219.9756004879853;1294.8446186457686;1315.6251716955803;1320.1880843281313;1332.666833291677;704.4646068987606;873.1704880363679;878.4948654523674;886.5965985476593;890.2744263425287;4400.361947517592;4401.6698747594055;4802.951970480251;4869.6591238612955;5044.646874718735;5338.279332290091;5503.504684578407;5749;5761.711914404263;5805.622634528742;4521.909561808746;4807.735574543433;5026.798928042837;5230.476952304739;5263.552598029134;2392.4258178037494;2505.671993843266;2585.8836352364187;2680.649188514357;2999.2501874531367;5395.460453954652;5923.644581325134;5972.790952316648;5976.85057873548;5988.461038506555;5129.692218466904;5238.494894390821;5356.839464715837;5514.876772442558;5518.109732638481;5138.434772175057;5287.915977224659;5624.634398764068;5754.827355179351;5828.338923406894;5209.958008398411;5504.394516603091;5586.692731899784;5759.424057594207;5766.538676905921;5194.3766747990485;5311.867203319794;5456.508914197741;5657.943420565906;5674.57440691958;4743.549362810528;5005.3993520776285;5927.85180370477;5974.981015946626;5981.175741398915;3875.6124387561017;3910.06158521947;3911.8239679213934;3915.158240978203;3931.3291850962655;4811.845157162325;5017.67385119966;5637.605367624227;5887.028640274307;5937.792177273947;5143.048536020848;5472.945270547243;5538.91019495047;5682.8579285516535;5787.408462672799;4847.466778654416;5755.690477975098;5758.683272420057;5764.426055845235;5766.29894181226;4545.545385507105;4769.950610865604;5577.3028371453565;5635.774569017193;5664.49672510347;5060.671056381323;5390.649607775482;5739.6269242500775;5795.449432303924;5815.680137592473;5363.249145119608;5406.459354064562;5683.209788916478;5684.772609095755;5727.513161381329 05:25:39 INFO - PROCESS | 1642 | |5;yui.html;692.4668005635657;696.2584847137798;708.7271400510801;783.5103060587134;817.2481317188189;14449.783253251197;15528.45650402236;16834.326626934937;17525.386611468602;17716.02561859097;15736.370545178179;16198.109103999275;16243.187840607981;16420.83579164208;16538.66922661546;851.531657588327;860.3117505995178;877.0965905117707;877.1316396767187;878.0341624213346;682.2733788515216;701.0850839654249;869.8086420987372;871.5859966515918;873.2271939333704;876.8991565969932;906.247814314121;913.8720579118896;917.2753524715466;918.8392031394479;891.5052146058935;897.5691667999332;900.5902314446944;901.0583939782942;901.3150005995425;661.8874791285493;664.5979182594509;670.902719105727;672.6434989455596;673.8483841135705;15154.318055687521;15336.079835209923;15488.148151851643;15514.224288785628;15521.456749013843;13622.523211687543;13709.314534273246;13738.038337316391;13851.51519696816;13871.930640346834;12681.850405887084;12740.745185096246;12791.104622676416;12870;12983.156094853899;4337.544557821447;4397.428334316551;4407.581279778416;4411.183930972748;4471.1728330259;110198;111617;113529;114470.28294575505;114941.85058149396;10488.983773699782;10696.144308455305;10759;10824.458777061116;10833.458327083614;20908.68636970447;21182.364529064154;21300;21416.571668566543;21427.035783389783;6492.448141907991;7571.507851989603;7590.240975902366;7747.496412733154;7776.950111734956;4241.130568233505;4254.553271906467;4283.593058659422;4312.223799716081;4343.69594128408;3538.292341531704;3612.133088058847;3615.6745892869767;3638.3450978824067;3661.3226553087766;661.1966460750211;663.8904580744123;667.1127400557344;671.6675245753383;678.1827897383641;665.670493105906;666.1340257665016;695.8851789454784;707.6072779607265;710.8187412209853;10139;10171.338862973884;10188.677365193063;10405.635802746867;10518.789624207473;552.0311852698569;660.6729668813967;661.685699292832;684.1379861374704;692.4287462843174;4093.242750091183;4269.829206831692;4835.975820120877;4839.903201935942;4849.587785038311;93410.53294733482;96134.51932740292;96404.29248814574;96583.51708241555;97085;93708;93943;94223.0577694228;94843.68047118318;95029.52485237668;59718.104228436605;71269.64365178141;76176.85734713984;76391.23608763842;78676.60661696654;7297.671604777904;7527.7164069188175;7629.38964882797;9276.72169834906;9408.623655053721;40100.799496002335;51470.48529514657;52804.73597631987;55093.72453137708;55709;64923.67538162279;66013;68890;69409;71211;11306.773864522498;11453.026492747877;11542.5529258413;11554.133439991867;12152.392380380768;10253.436061016715;12326.691832704255;12570.560030398892;12771.244818639856;12963.611091667264;10904.836427453436;11707.2975621463;11914.940425297818;12029.939850300692;12253.938730306649;9873.605055797687;11790.646280611596;11927.224730392496;11938.164328497269;12194.085443591588;11686.766264674488;11845.407729613657;11892.702682433008;11904.702382440162;12342.44458999365;9614.519274036411;9639.566219520279;10092.545835437277;10293.3823970562;10572.259941803999;7191.928080719126;8083.019660183058;9121.43368036253;9782.217422606032;9958.900410996088 05:25:39 INFO - PROCESS | 1642 | __end_tp_report 05:25:39 INFO - PROCESS | 1642 | __start_cc_report 05:25:39 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,392 05:25:39 INFO - PROCESS | 1642 | __end_cc_report 05:25:39 INFO - PROCESS | 1642 | __startTimestamp1487683539433__endTimestamp 05:25:39 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | ------- Summary: start ------- 05:25:39 INFO - PROCESS | 1642 | Number of tests: 6 05:25:39 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5469.07 Median:5594.83 stddev:648.61 (11.6%) stddev-sans-first:612.64 05:25:39 INFO - PROCESS | 1642 | Values: 2681.9 3292.9 3293.0 3328.5 3331.1 4242.0 5396.2 5403.8 5434.8 5440.5 4753.9 5432.2 5476.0 5509.1 5524.0 4205.3 5047.4 5061.5 5091.9 5113.8 5075.1 5124.6 5125.0 5135.5 5145.1 4936.9 4963.2 5062.4 5117.1 5164.9 5054.0 5056.9 5081.1 5100.0 5149.0 5053.8 5056.4 5067.1 5071.5 5102.6 5334.6 5419.2 5430.9 5461.1 5594.8 5839.1 5842.9 5876.1 5886.5 5993.4 6035.9 6044.7 6059.2 6068.4 6080.3 5544.6 5553.8 5561.0 5579.4 5581.4 6035.6 6095.0 6111.1 6123.8 6132.5 4384.7 5829.6 5830.2 5855.9 5978.3 5966.7 6023.2 6032.5 6052.3 6061.1 5745.0 5793.7 5809.5 5830.6 5987.3 5895.4 5902.1 5904.7 5927.3 5972.4 4807.4 4811.9 4831.4 4832.0 4838.7 3506.6 3599.7 4290.3 4323.4 4325.0 4833.9 5555.7 5576.5 5628.0 5657.1 6011.1 6032.5 6059.1 6062.7 6083.5 5501.9 5520.2 5544.7 5564.7 5582.2 5261.5 5263.6 5268.9 5270.6 5300.5 6026.0 6198.0 6199.0 6244.6 6250.9 5883.2 5918.9 5955.0 6003.4 6060.8 5290.7 5532.6 5674.5 6146.1 6202.1 5856.3 5880.5 5918.1 5933.5 5933.9 5873.7 5878.7 5964.9 5997.9 6007.0 5312.9 5832.8 5861.5 5868.8 5869.2 4733.9 5794.9 5821.0 5827.6 5841.3 5566.9 5573.9 5584.5 5634.3 5684.6 4937.5 5538.6 5737.5 5847.6 5870.0 5605.9 5669.3 5673.3 5673.4 5689.1 05:25:39 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:63240.02 Median:37237.72 stddev:131374.01 (352.8%) stddev-sans-first:131658.03 05:25:39 INFO - PROCESS | 1642 | Values: 474.9 493.4 501.8 507.1 538.3 28136.6 37246.6 37259.9 37276.3 37310.0 41054.8 41126.6 41544.4 41582.8 41683.4 8359.2 8604.8 9116.6 9298.3 9316.6 11061.9 11294.8 11328.9 11347.4 11460.4 11235.2 11273.3 11289.9 11326.4 11357.0 11321.5 11396.7 11401.4 11406.7 11411.6 9323.6 11322.8 11379.4 11387.5 11421.9 39515.4 39753.8 39786.4 39932.4 39971.8 3908.6 3951.4 3984.4 4020.2 4062.2 29382.6 30981.8 31035.1 31220.5 31304.7 3081.5 3226.2 3232.1 3233.6 3388.1 351085.0 351710.0 352419.0 352722.0 352951.0 57526.8 60923.7 61059.0 61068.7 61102.1 63920.0 64328.7 64362.0 64367.7 64576.0 16345.0 16448.7 16620.5 16638.4 16725.2 10337.7 11480.5 11733.1 12885.0 13030.8 14762.5 16628.7 16957.7 17128.0 17193.6 6792.6 6967.0 7027.4 7036.1 7057.7 859.8 860.9 869.4 871.3 905.2 25659.7 29583.0 29713.0 29723.0 30022.4 962.9 1015.9 1043.7 1044.0 1100.3 12746.5 13959.3 16288.5 16491.3 16602.8 499016.0 602766.0 606306.0 607528.0 608535.0 58863.0 59064.8 59207.4 59270.1 59287.8 1411.3 1434.1 1459.3 1461.5 1464.0 29141.3 30252.1 30829.7 30848.5 31142.4 50038.2 50154.7 50290.0 50349.7 50358.0 508148.0 508358.0 512650.0 513562.0 515261.0 38238.0 38738.4 38861.4 38987.0 39144.6 45168.1 45895.1 46131.1 46412.0 46825.0 31957.7 35036.0 36830.9 37155.3 37228.8 37925.4 38445.8 38801.8 38985.6 39351.4 36194.5 36248.0 37521.9 37704.8 37781.0 31609.0 33811.0 34003.0 34155.8 34258.7 24830.0 25042.6 25044.9 25496.5 25529.6 05:25:39 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:37278.99 Median:2527.92 stddev:110793.13 (4382.8%) stddev-sans-first:111069.32 05:25:39 INFO - PROCESS | 1642 | Values: 396.1 398.6 408.8 409.3 425.2 14298.3 15981.4 19543.7 20830.6 20856.2 19894.7 20790.4 21113.3 21223.8 21440.4 5552.9 5709.6 5959.2 6023.8 6045.2 5956.9 5993.8 5996.6 6010.5 6121.8 6477.7 6505.0 6509.9 6528.0 6531.3 5491.6 5928.3 5984.4 6040.5 6503.3 5772.5 5804.8 5842.8 5846.3 5848.9 12012.9 13179.9 13362.9 13527.5 13538.3 2239.5 2241.4 2442.9 2613.0 2649.6 8133.2 8185.0 10849.8 10984.5 15582.9 1749.1 1816.3 1828.6 1847.8 1868.5 105340.9 129846.4 133224.0 133919.0 141680.9 9968.8 11799.4 11803.4 12024.1 12307.8 15536.6 15698.5 15734.4 15802.8 15808.0 8056.8 8079.2 8718.2 8898.6 8938.9 8522.2 8604.6 8730.7 8744.5 8746.2 4069.3 4658.4 4795.7 4820.8 4898.4 2053.7 2077.4 2077.7 2081.4 2081.7 803.3 822.4 838.9 839.4 856.5 20882.8 21580.0 21605.9 21871.6 21952.5 865.8 869.8 893.9 898.9 900.1 11093.7 11289.4 11366.7 11370.9 11526.0 540138.0 660427.0 668091.0 709854.0 718827.0 138241.0 140796.3 144082.0 148927.0 152317.2 901.8 929.7 1050.0 1073.1 1081.5 18264.4 18950.9 19033.2 19039.3 19155.7 46997.0 54738.2 58181.0 58831.1 61590.2 65494.0 75839.9 78942.8 82581.0 84725.0 6834.9 8147.5 8223.9 8366.0 8504.7 10985.9 12407.4 12470.7 12794.4 13253.0 9297.6 10073.8 10675.6 10892.8 11012.9 9019.8 10606.3 10738.9 11072.4 11227.4 10883.9 11172.9 11220.7 11234.1 11237.4 8091.2 8202.1 8244.9 8410.4 8450.7 10703.7 10810.4 10884.0 11013.3 11025.0 05:25:39 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5443.55 Median:5072.09 stddev:2986.84 (58.9%) stddev-sans-first:2979.99 05:25:39 INFO - PROCESS | 1642 | Values: 1429.6 1436.7 1444.3 1450.5 1458.6 6963.7 7788.1 7932.6 8183.8 8593.3 7989.8 8148.2 8311.3 8415.3 8425.1 2312.4 2365.2 2367.2 2372.3 2386.4 1682.7 1733.7 2360.6 2389.6 2406.0 1847.3 2273.5 2287.7 2354.2 2356.0 2304.6 2314.3 2328.8 2338.9 2341.1 2332.8 2340.3 2396.8 2398.2 2402.7 8452.2 8557.1 8565.0 8569.4 8572.5 5104.6 5272.0 5272.9 5274.9 5277.3 3092.2 3462.9 3563.3 3575.5 3646.8 1218.4 1223.1 1253.2 1253.9 1274.3 10830.9 10929.1 10966.0 10996.1 11024.1 4017.5 4429.4 4755.8 4774.8 4816.4 8143.6 9647.0 9653.0 9672.4 9707.8 5297.2 5335.9 5344.2 5345.2 5351.5 4576.2 4714.5 4780.7 4889.0 4898.9 3363.4 3949.7 3978.4 4044.6 4083.5 1204.2 1292.8 1293.7 1297.2 1304.0 654.0 782.6 802.3 838.5 888.0 4743.7 5262.6 5361.4 5397.2 5508.3 876.9 949.3 953.8 974.8 1004.7 3194.3 3628.0 3644.7 3680.7 3697.3 9762.4 10136.6 10155.5 10163.1 10196.7 10012.8 10026.4 10035.7 10077.7 10080.3 7456.3 7893.8 7894.1 8001.5 8006.6 5620.4 5647.3 5669.8 5759.3 5816.8 8888.6 9129.0 9297.6 9350.9 9370.5 9038.1 9359.4 9458.3 9551.9 9561.5 7193.9 7246.8 7256.5 7306.5 7370.7 7858.4 7936.5 7937.2 7952.4 7962.6 6080.8 7054.8 7169.7 7197.3 7304.6 7102.6 7105.4 7123.8 7151.1 7165.9 6329.6 6341.4 6428.4 6445.9 6450.9 4911.7 4967.5 4969.1 4983.8 5039.6 4471.1 4862.5 4881.2 4916.6 4946.5 05:25:39 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4546.23 Median:5052.66 stddev:1394.10 (27.6%) stddev-sans-first:1361.98 05:25:39 INFO - PROCESS | 1642 | Values: 350.9 358.6 367.7 367.7 376.6 4544.1 5438.7 5452.7 5528.9 5567.9 4538.9 5390.9 5426.7 5427.2 5441.9 3414.6 3631.2 4252.4 4276.3 4283.4 3804.4 4105.7 4142.0 4153.1 4170.3 3581.6 4103.5 4116.9 4124.5 4150.5 4147.5 4155.3 4155.9 4172.4 4179.8 3186.9 3474.9 3518.0 3560.9 3580.7 4120.0 4384.6 4864.4 5109.8 5231.0 4644.6 4693.9 4765.9 4767.2 4774.7 5013.0 5493.0 5792.4 5803.0 5829.3 3905.7 3922.5 3926.9 3938.4 3970.4 5335.4 5608.9 6018.9 6084.5 6115.5 3503.7 3863.5 3935.2 3955.2 3968.2 4017.3 4037.5 4045.6 4059.8 4073.1 3978.3 4395.0 4728.3 4733.7 4735.2 5226.8 5627.8 5664.6 5798.8 5811.7 1220.0 1294.8 1315.6 1320.2 1332.7 704.5 873.2 878.5 886.6 890.3 4400.4 4401.7 4803.0 4869.7 5044.6 5338.3 5503.5 5749.0 5761.7 5805.6 4521.9 4807.7 5026.8 5230.5 5263.6 2392.4 2505.7 2585.9 2680.6 2999.3 5395.5 5923.6 5972.8 5976.9 5988.5 5129.7 5238.5 5356.8 5514.9 5518.1 5138.4 5287.9 5624.6 5754.8 5828.3 5210.0 5504.4 5586.7 5759.4 5766.5 5194.4 5311.9 5456.5 5657.9 5674.6 4743.5 5005.4 5927.9 5975.0 5981.2 3875.6 3910.1 3911.8 3915.2 3931.3 4811.8 5017.7 5637.6 5887.0 5937.8 5143.0 5472.9 5538.9 5682.9 5787.4 4847.5 5755.7 5758.7 5764.4 5766.3 4545.5 4770.0 5577.3 5635.8 5664.5 5060.7 5390.6 5739.6 5795.4 5815.7 5363.2 5406.5 5683.2 5684.8 5727.5 05:25:39 INFO - PROCESS | 1642 | 05:25:39 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20249.65 Median:4837.94 stddev:29688.92 (613.7%) stddev-sans-first:29735.89 05:25:39 INFO - PROCESS | 1642 | Values: 692.5 696.3 708.7 783.5 817.2 14449.8 15528.5 16834.3 17525.4 17716.0 15736.4 16198.1 16243.2 16420.8 16538.7 851.5 860.3 877.1 877.1 878.0 682.3 701.1 869.8 871.6 873.2 876.9 906.2 913.9 917.3 918.8 891.5 897.6 900.6 901.1 901.3 661.9 664.6 670.9 672.6 673.8 15154.3 15336.1 15488.1 15514.2 15521.5 13622.5 13709.3 13738.0 13851.5 13871.9 12681.9 12740.7 12791.1 12870.0 12983.2 4337.5 4397.4 4407.6 4411.2 4471.2 110198.0 111617.0 113529.0 114470.3 114941.9 10489.0 10696.1 10759.0 10824.5 10833.5 20908.7 21182.4 21300.0 21416.6 21427.0 6492.4 7571.5 7590.2 7747.5 7777.0 4241.1 4254.6 4283.6 4312.2 4343.7 3538.3 3612.1 3615.7 3638.3 3661.3 661.2 663.9 667.1 671.7 678.2 665.7 666.1 695.9 707.6 710.8 10139.0 10171.3 10188.7 10405.6 10518.8 552.0 660.7 661.7 684.1 692.4 4093.2 4269.8 4836.0 4839.9 4849.6 93410.5 96134.5 96404.3 96583.5 97085.0 93708.0 93943.0 94223.1 94843.7 95029.5 59718.1 71269.6 76176.9 76391.2 78676.6 7297.7 7527.7 7629.4 9276.7 9408.6 40100.8 51470.5 52804.7 55093.7 55709.0 64923.7 66013.0 68890.0 69409.0 71211.0 11306.8 11453.0 11542.6 11554.1 12152.4 10253.4 12326.7 12570.6 12771.2 12963.6 10904.8 11707.3 11914.9 12029.9 12253.9 9873.6 11790.6 11927.2 11938.2 12194.1 11686.8 11845.4 11892.7 11904.7 12342.4 9614.5 9639.6 10092.5 10293.4 10572.3 7191.9 8083.0 9121.4 9782.2 9958.9 05:25:39 INFO - PROCESS | 1642 | -------- Summary: end -------- 05:25:39 INFO - PROCESS | 1642 | 05:25:40 INFO - TEST-INFO | 1642: exit 0 05:25:40 INFO - TEST-OK | dromaeo_css | took 1114468ms 05:25:40 INFO - TEST-START | kraken 05:25:40 INFO - operating with platform_type : mac_ 05:25:40 INFO - Initialising browser for kraken test... 05:25:40 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpcnp4x7/profile http://localhost:49242/getInfo.html) 05:25:42 INFO - PROCESS | 1654 | 2017-02-21 05:25:42.649 plugin-container[1655:10072] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 05:25:42 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:25:42 INFO - PROCESS | 1654 | 2017-02-21 05:25:42.652 plugin-container[1655:10072] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 05:25:42 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:25:42 INFO - PROCESS | 1654 | 2017-02-21 05:25:42.652 plugin-container[1655:10072] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:25:42 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 05:25:42 INFO - PROCESS | 1654 | colorDepth:24 05:25:42 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 05:25:42 INFO - PROCESS | 1654 | __metrics 05:25:42 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:25:43 INFO - TEST-INFO | 1654: exit 0 05:25:43 INFO - Browser initialized. 05:25:43 INFO - Running cycle 1/1 for kraken test... 05:25:43 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpcnp4x7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:45 INFO - PROCESS | 1656 | 2017-02-21 05:25:45.990 plugin-container[1657:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 05:25:45 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:25:45 INFO - PROCESS | 1656 | 2017-02-21 05:25:45.990 plugin-container[1657:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 05:25:45 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:25:45 INFO - PROCESS | 1656 | 2017-02-21 05:25:45.990 plugin-container[1657:10201] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:25:55 INFO - PROCESS | 1656 | RSS: Main: 219332608 05:25:55 INFO - PROCESS | 1656 | 05:25:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:59 INFO - PROCESS | 1656 | RSS: Main: 233603072 05:25:59 INFO - PROCESS | 1656 | 05:26:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 05:26:02 INFO - PROCESS | 1656 | RSS: Main: 241696768 05:26:02 INFO - PROCESS | 1656 | 05:26:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 05:26:06 INFO - PROCESS | 1656 | RSS: Main: 245456896 05:26:06 INFO - PROCESS | 1656 | 05:26:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 05:26:09 INFO - PROCESS | 1656 | RSS: Main: 245649408 05:26:09 INFO - PROCESS | 1656 | 05:26:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:26:12 INFO - PROCESS | 1656 | RSS: Main: 245641216 05:26:12 INFO - PROCESS | 1656 | 05:26:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 05:26:15 INFO - PROCESS | 1656 | RSS: Main: 251469824 05:26:15 INFO - PROCESS | 1656 | 05:26:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 05:26:19 INFO - PROCESS | 1656 | RSS: Main: 250470400 05:26:19 INFO - PROCESS | 1656 | 05:26:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 05:26:23 INFO - PROCESS | 1656 | RSS: Main: 250568704 05:26:23 INFO - PROCESS | 1656 | 05:26:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:25 INFO - PROCESS | 1656 | RSS: Main: 250150912 05:26:25 INFO - PROCESS | 1656 | 05:26:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:27 INFO - PROCESS | 1656 | RSS: Main: 250122240 05:26:27 INFO - PROCESS | 1656 | 05:26:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:30 INFO - PROCESS | 1656 | RSS: Main: 250232832 05:26:30 INFO - PROCESS | 1656 | 05:26:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:34 INFO - PROCESS | 1656 | RSS: Main: 250552320 05:26:34 INFO - PROCESS | 1656 | 05:26:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:38 INFO - PROCESS | 1656 | RSS: Main: 250630144 05:26:38 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 05:26:40 INFO - PROCESS | 1656 | RSS: Main: 252592128 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | __start_tp_report 05:26:40 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 05:26:40 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 05:26:40 INFO - PROCESS | 1656 | |i|pagename|runs| 05:26:40 INFO - PROCESS | 1656 | |0;ai-astar;108;120;121;122;120;122;120;162;180;146 05:26:40 INFO - PROCESS | 1656 | |1;audio-beat-detection;143;113;115;147;116;117;115;112;133;114 05:26:40 INFO - PROCESS | 1656 | |2;audio-dft;201;199;195;197;196;193;196;199;205;195 05:26:40 INFO - PROCESS | 1656 | |3;audio-fft;90;85;53;100;84;84;81;82;70;58 05:26:40 INFO - PROCESS | 1656 | |4;audio-oscillator;88;74;71;85;99;58;96;101;104;110 05:26:40 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;90;87;88;86;106;86;90;105;136;91 05:26:40 INFO - PROCESS | 1656 | |6;imaging-darkroom;95;97;125;120;154;150;97;113;155;114 05:26:40 INFO - PROCESS | 1656 | |7;imaging-desaturate;95;93;93;92;94;93;90;95;92;97 05:26:40 INFO - PROCESS | 1656 | |8;json-parse-financial;61;61;60;64;66;64;63;64;64;63 05:26:40 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;67;73;70;77;74;68;63;93;105;60 05:26:40 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;84;84;84;84;83;88;87;86;82;80 05:26:40 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;149;155;152;139;141;173;157;147;205;181 05:26:40 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;173;173;174;168;172;168;204;234;234;170 05:26:40 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;70;71;82;89;85;85;88;90;59;73 05:26:40 INFO - PROCESS | 1656 | __end_tp_report 05:26:40 INFO - PROCESS | 1656 | __start_cc_report 05:26:40 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,985 05:26:40 INFO - PROCESS | 1656 | __end_cc_report 05:26:40 INFO - PROCESS | 1656 | __startTimestamp1487683600366__endTimestamp 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | ------- Summary: start ------- 05:26:40 INFO - PROCESS | 1656 | Number of tests: 14 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:132.10 Median:122.00 stddev:22.92 (18.8%) stddev-sans-first:22.59 05:26:40 INFO - PROCESS | 1656 | Values: 108.0 120.0 121.0 122.0 120.0 122.0 120.0 162.0 180.0 146.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:116.50 stddev:13.29 (11.4%) stddev-sans-first:11.84 05:26:40 INFO - PROCESS | 1656 | Values: 143.0 113.0 115.0 147.0 116.0 117.0 115.0 112.0 133.0 114.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:197.60 Median:198.00 stddev:3.50 (1.8%) stddev-sans-first:3.49 05:26:40 INFO - PROCESS | 1656 | Values: 201.0 199.0 195.0 197.0 196.0 193.0 196.0 199.0 205.0 195.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:78.70 Median:83.00 stddev:14.37 (17.3%) stddev-sans-first:14.65 05:26:40 INFO - PROCESS | 1656 | Values: 90.0 85.0 53.0 100.0 84.0 84.0 81.0 82.0 70.0 58.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:79.50 stddev:16.61 (20.9%) stddev-sans-first:17.62 05:26:40 INFO - PROCESS | 1656 | Values: 88.0 74.0 71.0 85.0 99.0 58.0 96.0 101.0 104.0 110.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:87.50 stddev:15.71 (18.0%) stddev-sans-first:16.48 05:26:40 INFO - PROCESS | 1656 | Values: 90.0 87.0 88.0 86.0 106.0 86.0 90.0 105.0 136.0 91.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:122.00 Median:154.50 stddev:23.65 (15.3%) stddev-sans-first:22.98 05:26:40 INFO - PROCESS | 1656 | Values: 95.0 97.0 125.0 120.0 154.0 150.0 97.0 113.0 155.0 114.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:93.50 stddev:1.96 (2.1%) stddev-sans-first:1.99 05:26:40 INFO - PROCESS | 1656 | Values: 95.0 93.0 93.0 92.0 94.0 93.0 90.0 95.0 92.0 97.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:64.00 stddev:1.83 (2.9%) stddev-sans-first:1.79 05:26:40 INFO - PROCESS | 1656 | Values: 61.0 61.0 60.0 64.0 66.0 64.0 63.0 64.0 64.0 63.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:75.00 Median:71.50 stddev:13.90 (19.4%) stddev-sans-first:14.44 05:26:40 INFO - PROCESS | 1656 | Values: 67.0 73.0 70.0 77.0 74.0 68.0 63.0 93.0 105.0 60.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.00 stddev:2.35 (2.8%) stddev-sans-first:2.49 05:26:40 INFO - PROCESS | 1656 | Values: 84.0 84.0 84.0 84.0 83.0 88.0 87.0 86.0 82.0 80.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:159.90 Median:156.00 stddev:20.62 (13.2%) stddev-sans-first:21.49 05:26:40 INFO - PROCESS | 1656 | Values: 149.0 155.0 152.0 139.0 141.0 173.0 157.0 147.0 205.0 181.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.00 Median:173.50 stddev:26.88 (15.5%) stddev-sans-first:28.03 05:26:40 INFO - PROCESS | 1656 | Values: 173.0 173.0 174.0 168.0 172.0 168.0 204.0 234.0 234.0 170.0 05:26:40 INFO - PROCESS | 1656 | 05:26:40 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.20 Median:85.00 stddev:10.35 (12.2%) stddev-sans-first:10.43 05:26:40 INFO - PROCESS | 1656 | Values: 70.0 71.0 82.0 89.0 85.0 85.0 88.0 90.0 59.0 73.0 05:26:40 INFO - PROCESS | 1656 | -------- Summary: end -------- 05:26:40 INFO - PROCESS | 1656 | 05:26:41 INFO - TEST-INFO | 1656: exit 0 05:26:41 INFO - TEST-OK | kraken | took 61211ms 05:26:41 INFO - SUITE-END | took 1175s 05:26:41 INFO - Completed test suite (00:19:35) 05:26:41 INFO - javascript benchmark 05:26:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7187.722097622532, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2681.919542413728, 3292.8518216680245, 3293.0450169450874, 3328.4508056170735, 3331.0839519132237, 4241.957580424195, 5396.217548455472, 5403.756830942607, 5434.83695489135, 5440.483154100364, 4753.90492190156, 5432.239486471897, 5476.014317422862, 5509.063459211933, 5523.972380138103, 4205.306124489455, 5047.394312682465, 5061.468545802693, 5091.949080509203, 5113.821016264432, 5075.111855425286, 5124.6412751107255, 5125.0, 5135.486451354854, 5145.073886700392, 4936.876578085541, 4963.205887058071, 5062.417821950467, 5117.130087885069, 5164.896702065957, 5054.014467178901, 5056.924146137792, 5081.060003899291, 5100.0054989277105, 5148.974255128738, 5053.772580233898, 5056.367954005749, 5067.062593420229, 5071.46749591295, 5102.6428150029515, 5334.59990500714, 5419.160030195326, 5430.8913821723345, 5461.098918678414, 5594.832155035356, 5839.0949402842625, 5842.853928651775, 5876.147958546029, 5886.470217680387, 5993.4006599340155, 6035.943709850802, 6044.697765111727, 6059.212302400704, 6068.423499767515, 6080.30076541199, 5544.556435485232, 5553.750081246313, 5561.0, 5579.386267510611, 5581.413951535071, 6035.637861728311, 6094.969525152346, 6111.113888486145, 6123.83172914054, 6132.478739307119, 4384.69307148503, 5829.562782791308, 5830.2420685310635, 5855.912161317584, 5978.282606087298, 5966.671833049137, 6023.186869772612, 6032.54755893301, 6052.273727152682, 6061.060535616986, 5745.0, 5793.652380857162, 5809.477147056785, 5830.591858569859, 5987.341392446828, 5895.380984996599, 5902.144189092557, 5904.675242861598, 5927.347991720907, 5972.432618901197, 4807.413553527327, 4811.855644330606, 4831.371921650233, 4832.033593281358, 4838.661293709477, 3506.6142724300307, 3599.6940260078186, 4290.2706539888295, 4323.351497275371, 4325.048489332342, 4833.927491087637, 5555.749991250404, 5576.498115169654, 5627.971860140673, 5657.066584013674, 6011.12838638395, 6032.517398608101, 6059.121427393092, 6062.727177277033, 6083.482903953125, 5501.944980550224, 5520.171974203901, 5544.695041772741, 5564.749586268627, 5582.162675598693, 5261.5264626183825, 5263.605229607795, 5268.946210757864, 5270.578353731769, 5300.549453296436, 6025.975584150618, 6198.0, 6199.039148931923, 6244.625322480666, 6250.937490625127, 5883.2057672213705, 5918.940810591839, 5955.017422125474, 6003.3996600339615, 6060.787872424444, 5290.709011004278, 5532.640378375393, 5674.4892959633835, 6146.078088286793, 6202.131701561695, 5856.326522449973, 5880.529557635294, 5918.082697181943, 5933.525317974465, 5933.851653708519, 5873.676947767914, 5878.706064696663, 5964.940350596438, 5997.91003134962, 6006.969965150146, 5312.946870531246, 5832.766689332549, 5861.501772349398, 5868.823935281948, 5869.236999190249, 4733.881652958704, 5794.942050579441, 5821.0, 5827.621204621686, 5841.299044114563, 5566.888662226652, 5573.860653483695, 5584.46947539983, 5634.267545219095, 5684.573656975661, 4937.456879743154, 5538.556915446674, 5737.512311453574, 5847.5906686533235, 5869.970650146722, 5605.915911261252, 5669.31968163823, 5673.347565030074, 5673.404292549388, 5689.146628005832], "value": 5428.529490633617, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.91214125386807, 493.44733898034184, 501.8394113883554, 507.0720581336155, 538.3405328472624, 28136.57795133072, 37246.627533724655, 37259.8822035339, 37276.254474910485, 37310.0, 41054.794726026324, 41126.58873411265, 41544.37683434751, 41582.792086039524, 41683.37474937878, 8359.164083591653, 8604.827903441927, 9116.589753461094, 9298.256139508823, 9316.627334906629, 11061.944690276578, 11294.774104517905, 11328.886711132865, 11347.43262836859, 11460.426978651076, 11235.157363197752, 11273.32360058395, 11289.943550282238, 11326.433678316092, 11357.0, 11321.490532925996, 11396.71508212293, 11401.37292448915, 11406.657800265964, 11411.600593979254, 9323.580438880266, 11322.773544529064, 11379.374134422604, 11387.54449822006, 11421.885781142248, 39515.40726889099, 39753.80738577847, 39786.403203951966, 39932.40101398481, 39971.80014099911, 3908.6482216600357, 3951.4270430787374, 3984.362501999666, 4020.2361551305157, 4062.1875624874847, 29382.55926161109, 30981.845090774626, 31035.0689479316, 31220.531692024637, 31304.686953130404, 3081.5223640335785, 3226.1934516370907, 3232.095013396229, 3233.596741506466, 3388.102152929476, 351085.0, 351710.0, 352419.0, 352722.0, 352951.0, 57526.84946301134, 60923.69538152369, 61059.0, 61068.69465652643, 61102.083468748, 63920.0, 64328.67835660792, 64362.0, 64367.71264574776, 64576.0, 16345.0, 16448.671026579403, 16620.501384958472, 16638.417655382003, 16725.163741813103, 10337.689869303933, 11480.540778368771, 11733.061355091571, 12884.96920246378, 13030.80453793194, 14762.48331308399, 16628.6674266514, 16957.745633815503, 17128.0, 17193.57016074608, 6792.558483698544, 6966.9651651741415, 7027.4378049756915, 7036.085308909809, 7057.682404291819, 859.8409294280578, 860.8665656823202, 869.427602100874, 871.2986046232717, 905.2441211588398, 25659.74340256611, 29583.0, 29712.96004639827, 29722.959696410522, 30022.399552009276, 962.8651988721584, 1015.8730158730159, 1043.7234132954773, 1043.9530221140067, 1100.2573263047411, 12746.49014039428, 13959.302034898417, 16288.511344659679, 16491.257893394824, 16602.75095873563, 499016.0, 602766.0, 606306.0, 607528.0, 608535.0, 58863.0, 59064.818703626544, 59207.40792592105, 59270.11094833581, 59287.814243714885, 1411.3084588551535, 1434.0535246737104, 1459.2630721485793, 1461.5250043735828, 1464.0337377330911, 29141.271468213465, 30252.09243722692, 30829.69170308268, 30848.53727194049, 31142.377152457277, 50038.24942625936, 50154.746131347005, 50289.99420011506, 50349.74825125851, 50358.0, 508148.0, 508358.0, 512650.0, 513562.0, 515261.0, 38238.0, 38738.41892371673, 38861.417078743274, 38987.0, 39144.6085539141, 45168.096638066396, 45895.082098358514, 46131.07737845157, 46412.0, 46825.0, 31957.721691133025, 35036.0, 36830.89507314785, 37155.25689486141, 37228.813855930544, 37925.431118532695, 38445.807770960964, 38801.805990969864, 38985.6101438982, 39351.40972885466, 36194.4570831443, 36248.0, 37521.874343769734, 37704.81147594244, 37781.0, 31609.0, 33811.0, 34003.0, 34155.82922085374, 34258.65741342655, 24830.0, 25042.62436063497, 25044.874775626005, 25496.49007019812, 25529.617055743805], "value": 19945.364390822648, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.09492310071494, 398.59343469660945, 408.764960147915, 409.30213985155314, 425.17303844023365, 14298.285085745723, 15981.360745570193, 19543.70684439733, 20830.583388332245, 20856.165753369845, 19894.70157947632, 20790.376288711326, 21113.261035863747, 21223.787762122374, 21440.356789296307, 5552.9167062494, 5709.571782116337, 5959.195508606326, 6023.849403764897, 6045.214122164112, 5956.910646340309, 5993.820185394445, 5996.580239383223, 6010.549208809357, 6121.846953826145, 6477.708503117371, 6504.967475162617, 6509.869802603945, 6527.967360163192, 6531.281559028503, 5491.615586908918, 5928.318243401977, 5984.401559843981, 6040.48655864252, 6503.28463868974, 5772.480476757112, 5804.82585522435, 5842.795502157404, 5846.298444186725, 5848.941510584925, 12012.939935300356, 13179.934100329438, 13362.933185334108, 13527.458901643922, 13538.255395953218, 2239.5296987632455, 2241.3612120545567, 2442.8884857389844, 2612.9608055879175, 2649.576067829138, 8133.227343402426, 8185.0, 10849.752278487917, 10984.450777461096, 15582.922085389613, 1749.0904729540568, 1816.3461153984556, 1828.5611453251124, 1847.833694967446, 1868.4768264885854, 105340.89318213593, 129846.3507682465, 133224.00163997547, 133919.0, 141680.87478687827, 9968.80062398748, 11799.35103569312, 11803.350815705218, 12024.098192635587, 12307.75384492305, 15536.611584710246, 15698.529044128694, 15734.370625174864, 15802.841971580367, 15807.972481788647, 8056.791481277855, 8079.232472915063, 8718.215360617574, 8898.59956301955, 8938.910610893938, 8522.232999030117, 8604.612792424356, 8730.650773969097, 8744.519051452104, 8746.212840844355, 4069.2878746219294, 4658.394408726844, 4795.688280261772, 4820.758962051884, 4898.363212782316, 2053.650879350514, 2077.418322869599, 2077.7195078664186, 2081.354780018199, 2081.6565266731122, 803.2730379007008, 822.3873214455269, 838.9035260945069, 839.4417712221305, 856.546030603781, 20882.791172088084, 21580.0, 21605.891970540048, 21871.562568748537, 21952.45118872041, 865.8095019600421, 869.7825543614097, 893.8927328720595, 898.9280857531381, 900.144862380741, 11093.667189984313, 11289.435528223721, 11366.715832104264, 11370.886291137149, 11526.020288275422, 540138.0, 660427.0, 668091.0, 709854.0, 718827.0, 138241.0, 140796.29601851926, 144082.0, 148927.0, 152317.2384138072, 901.7700486375932, 929.7164364868645, 1049.9737506562396, 1073.1039581949003, 1081.4862940103387, 18264.36074737378, 18950.86294822315, 19033.238670453025, 19039.333623323117, 19155.71266430977, 46997.0, 54738.178927315326, 58181.0, 58831.05844707833, 61590.22843972121, 65494.0, 75839.86240206512, 78942.8422863079, 82581.0, 84725.0, 6834.8633027340165, 8147.511149331059, 8223.876641850497, 8366.0, 8504.702335418231, 10985.945070274916, 12407.44166512491, 12470.688232794253, 12794.360281985677, 13253.0, 9297.581608827484, 10073.848892266475, 10675.57297708083, 10892.836607450736, 11012.889871101506, 9019.81960360776, 10606.257561970588, 10738.892611073788, 11072.446377681244, 11227.38249396291, 10883.945580272364, 11172.944135279597, 11220.719482012688, 11234.101271898391, 11237.38194399316, 8091.1504292048485, 8202.138775428493, 8244.917550824655, 8410.411271210956, 8450.746477605682, 10703.732406689895, 10810.405427701551, 10884.020438160604, 11013.2841365311, 11025.0], "value": 9568.51802073394, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.5568373804122, 1436.741386550421, 1444.2634256529168, 1450.4778279819263, 1458.56972193203, 6963.686634101465, 7788.143304236543, 7932.56370899601, 8183.7544873653815, 8593.312534997202, 7989.800254993628, 8148.185181481849, 8311.293540049088, 8415.326773858093, 8425.1153628869, 2312.364099872536, 2365.2312998275543, 2367.218817790134, 2372.28831350595, 2386.367612582667, 1682.6887025900187, 1733.7486064520638, 2360.586897292967, 2389.6057150570136, 2405.9879700601473, 1847.3441928115526, 2273.533925545259, 2287.6731495732433, 2354.19957214547, 2356.0, 2304.585174668559, 2314.305708287507, 2328.8253380996493, 2338.8596684199, 2341.1337805012167, 2332.8017118544944, 2340.2979106268053, 2396.7842894139612, 2398.1846172301325, 2402.6756387887763, 8452.197041281068, 8557.101504342018, 8564.957175214146, 8569.35729820266, 8572.528510931896, 5104.617153713486, 5272.0, 5272.894542109137, 5274.92087618686, 5277.287566178594, 3092.2424006118645, 3462.8614855405754, 3563.251717139404, 3575.5173051637958, 3646.762960407565, 1218.3786269002783, 1223.0521345956865, 1253.2104773992417, 1253.8558065822456, 1274.286399616218, 10830.891691083067, 10929.125669946385, 10966.0, 10996.06533444658, 11024.118070554336, 4017.517897852218, 4429.402030725811, 4755.83354582588, 4774.809007639656, 4816.446108697544, 8143.552104634184, 9647.0, 9653.03469653029, 9672.371295865747, 9707.757306067264, 5297.17893726473, 5335.919961200585, 5344.225087362311, 5345.1714984177515, 5351.464853514617, 4576.176288268077, 4714.45783734868, 4780.689255198401, 4888.975555122272, 4898.853034408973, 3363.428217203081, 3949.7037722170953, 3978.3833505806647, 4044.5753195914594, 4083.469149010669, 1204.2292932523017, 1292.75437666843, 1293.7062937062938, 1297.2475963940892, 1303.9739205216033, 653.9803805885831, 782.5500337306072, 802.3420794948203, 838.5178522349673, 888.0409158109227, 4743.667943244009, 5262.552797980586, 5361.437049109865, 5397.2443857859935, 5508.338999320107, 876.8553188723789, 949.2595775295281, 953.7901661634429, 974.8294048541477, 1004.7186787699455, 3194.34515924235, 3627.981860090683, 3644.653757892996, 3680.6687398134295, 3697.260547890379, 9762.414255144715, 10136.594536218616, 10155.492225388702, 10163.085322321027, 10196.745081373025, 10012.849807252896, 10026.448545329931, 10035.749106272402, 10077.69766906994, 10080.344777589433, 7456.291652293024, 7893.842123157505, 7894.131645518988, 8001.479903806234, 8006.559639219899, 5620.409856965123, 5647.322321321303, 5669.773209071591, 5759.251297331488, 5816.82549523515, 8888.60001299956, 9129.041450647586, 9297.6280948764, 9350.906490935004, 9370.484623345781, 9038.141376569216, 9359.391639543408, 9458.33791634579, 9551.94928557872, 9561.521923903638, 7193.892091618525, 7246.818829529304, 7256.528325659026, 7306.452016098708, 7370.705171793068, 7858.44990850635, 7936.484128531858, 7937.2459616336355, 7952.36381089523, 7962.601869906365, 6080.847978800389, 7054.8236294091, 7169.713211471482, 7197.316254955771, 7304.6347682616715, 7102.573845569283, 7105.3960413363375, 7123.821904452223, 7151.141862976476, 7165.8892871604985, 6329.588576742497, 6341.4292713656, 6428.389303016206, 6445.871082578416, 6450.9354906450335, 4911.705297682151, 4967.4535801062575, 4969.055879382906, 4983.800647974041, 5039.571636410946, 4471.128130014746, 4862.513748625109, 4881.170201065898, 4916.557509824133, 4946.530079642428], "value": 4411.945919131091, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.9210427653778, 358.5750885201037, 367.7039982813834, 367.7242068448663, 376.62149539712567, 4544.136614043329, 5438.700871452069, 5452.700101494416, 5528.889422211553, 5567.8886422271535, 4538.863834084983, 5390.8921821563545, 5426.728663566825, 5427.185922111686, 5441.891162176754, 3414.590249170103, 3631.2221150819646, 4252.362145678142, 4276.294411422127, 4283.4431523902, 3804.372278574032, 4105.7331273467125, 4141.9585804142025, 4153.0863210093885, 4170.311898536753, 3581.570211574615, 4103.54860965295, 4116.917661646765, 4124.546299907008, 4150.464328198571, 4147.527627692186, 4155.33514637658, 4155.916881662365, 4172.374143878412, 4179.811908464127, 3186.9043928682177, 3474.8957531274104, 3517.982410087933, 3560.928781424357, 3580.7314451415987, 4120.0335758254005, 4384.583464570861, 4864.367632207825, 5109.821156259551, 5231.0, 4644.58198762113, 4693.883666803201, 4765.880852978668, 4767.213409787381, 4774.689645173087, 5013.0, 5492.972535137299, 5792.420757924174, 5803.0, 5829.329627092896, 3905.6875449963936, 3922.5096862892137, 3926.8821935341984, 3938.35017222158, 3970.3647416413237, 5335.439778823206, 5608.943910560883, 6018.886505996361, 6084.471006816036, 6115.471132216946, 3503.667151620592, 3863.517060367454, 3935.2326296371934, 3955.248502784462, 3968.1666849961252, 4017.296973029708, 4037.4953130858644, 4045.5954404559307, 4059.7767122807936, 4073.1242782801246, 3978.303796835542, 4395.033092719596, 4728.267118596623, 4733.715977041388, 4735.2186889163595, 5226.764795584208, 5627.803026894121, 5664.631798933056, 5798.82603521895, 5811.738471768781, 1219.9756004879853, 1294.8446186457686, 1315.6251716955803, 1320.1880843281313, 1332.666833291677, 704.4646068987606, 873.1704880363679, 878.4948654523674, 886.5965985476593, 890.2744263425287, 4400.361947517592, 4401.6698747594055, 4802.951970480251, 4869.6591238612955, 5044.646874718735, 5338.279332290091, 5503.504684578407, 5749.0, 5761.711914404263, 5805.622634528742, 4521.909561808746, 4807.735574543433, 5026.798928042837, 5230.476952304739, 5263.552598029134, 2392.4258178037494, 2505.671993843266, 2585.8836352364187, 2680.649188514357, 2999.2501874531367, 5395.460453954652, 5923.644581325134, 5972.790952316648, 5976.85057873548, 5988.461038506555, 5129.692218466904, 5238.494894390821, 5356.839464715837, 5514.876772442558, 5518.109732638481, 5138.434772175057, 5287.915977224659, 5624.634398764068, 5754.827355179351, 5828.338923406894, 5209.958008398411, 5504.394516603091, 5586.692731899784, 5759.424057594207, 5766.538676905921, 5194.3766747990485, 5311.867203319794, 5456.508914197741, 5657.943420565906, 5674.57440691958, 4743.549362810528, 5005.3993520776285, 5927.85180370477, 5974.981015946626, 5981.175741398915, 3875.6124387561017, 3910.06158521947, 3911.8239679213934, 3915.158240978203, 3931.3291850962655, 4811.845157162325, 5017.67385119966, 5637.605367624227, 5887.028640274307, 5937.792177273947, 5143.048536020848, 5472.945270547243, 5538.91019495047, 5682.8579285516535, 5787.408462672799, 4847.466778654416, 5755.690477975098, 5758.683272420057, 5764.426055845235, 5766.29894181226, 4545.545385507105, 4769.950610865604, 5577.3028371453565, 5635.774569017193, 5664.49672510347, 5060.671056381323, 5390.649607775482, 5739.6269242500775, 5795.449432303924, 5815.680137592473, 5363.249145119608, 5406.459354064562, 5683.209788916478, 5684.772609095755, 5727.513161381329], "value": 4112.106304772665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.4668005635657, 696.2584847137798, 708.7271400510801, 783.5103060587134, 817.2481317188189, 14449.783253251197, 15528.45650402236, 16834.326626934937, 17525.386611468602, 17716.02561859097, 15736.370545178179, 16198.109103999275, 16243.187840607981, 16420.83579164208, 16538.66922661546, 851.531657588327, 860.3117505995178, 877.0965905117707, 877.1316396767187, 878.0341624213346, 682.2733788515216, 701.0850839654249, 869.8086420987372, 871.5859966515918, 873.2271939333704, 876.8991565969932, 906.247814314121, 913.8720579118896, 917.2753524715466, 918.8392031394479, 891.5052146058935, 897.5691667999332, 900.5902314446944, 901.0583939782942, 901.3150005995425, 661.8874791285493, 664.5979182594509, 670.902719105727, 672.6434989455596, 673.8483841135705, 15154.318055687521, 15336.079835209923, 15488.148151851643, 15514.224288785628, 15521.456749013843, 13622.523211687543, 13709.314534273246, 13738.038337316391, 13851.51519696816, 13871.930640346834, 12681.850405887084, 12740.745185096246, 12791.104622676416, 12870.0, 12983.156094853899, 4337.544557821447, 4397.428334316551, 4407.581279778416, 4411.183930972748, 4471.1728330259, 110198.0, 111617.0, 113529.0, 114470.28294575505, 114941.85058149396, 10488.983773699782, 10696.144308455305, 10759.0, 10824.458777061116, 10833.458327083614, 20908.68636970447, 21182.364529064154, 21300.0, 21416.571668566543, 21427.035783389783, 6492.448141907991, 7571.507851989603, 7590.240975902366, 7747.496412733154, 7776.950111734956, 4241.130568233505, 4254.553271906467, 4283.593058659422, 4312.223799716081, 4343.69594128408, 3538.292341531704, 3612.133088058847, 3615.6745892869767, 3638.3450978824067, 3661.3226553087766, 661.1966460750211, 663.8904580744123, 667.1127400557344, 671.6675245753383, 678.1827897383641, 665.670493105906, 666.1340257665016, 695.8851789454784, 707.6072779607265, 710.8187412209853, 10139.0, 10171.338862973884, 10188.677365193063, 10405.635802746867, 10518.789624207473, 552.0311852698569, 660.6729668813967, 661.685699292832, 684.1379861374704, 692.4287462843174, 4093.242750091183, 4269.829206831692, 4835.975820120877, 4839.903201935942, 4849.587785038311, 93410.53294733482, 96134.51932740292, 96404.29248814574, 96583.51708241555, 97085.0, 93708.0, 93943.0, 94223.0577694228, 94843.68047118318, 95029.52485237668, 59718.104228436605, 71269.64365178141, 76176.85734713984, 76391.23608763842, 78676.60661696654, 7297.671604777904, 7527.7164069188175, 7629.38964882797, 9276.72169834906, 9408.623655053721, 40100.799496002335, 51470.48529514657, 52804.73597631987, 55093.72453137708, 55709.0, 64923.67538162279, 66013.0, 68890.0, 69409.0, 71211.0, 11306.773864522498, 11453.026492747877, 11542.5529258413, 11554.133439991867, 12152.392380380768, 10253.436061016715, 12326.691832704255, 12570.560030398892, 12771.244818639856, 12963.611091667264, 10904.836427453436, 11707.2975621463, 11914.940425297818, 12029.939850300692, 12253.938730306649, 9873.605055797687, 11790.646280611596, 11927.224730392496, 11938.164328497269, 12194.085443591588, 11686.766264674488, 11845.407729613657, 11892.702682433008, 11904.702382440162, 12342.44458999365, 9614.519274036411, 9639.566219520279, 10092.545835437277, 10293.3823970562, 10572.259941803999, 7191.928080719126, 8083.019660183058, 9121.43368036253, 9782.217422606032, 9958.900410996088], "value": 7335.613161924801, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 121.0, 122.0, 120.0, 122.0, 120.0, 162.0, 180.0, 146.0], "value": 132.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 115.0, 147.0, 116.0, 117.0, 115.0, 112.0, 133.0, 114.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 199.0, 195.0, 197.0, 196.0, 193.0, 196.0, 199.0, 205.0, 195.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 53.0, 100.0, 84.0, 84.0, 81.0, 82.0, 70.0, 58.0], "value": 78.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 74.0, 71.0, 85.0, 99.0, 58.0, 96.0, 101.0, 104.0, 110.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 88.0, 86.0, 106.0, 86.0, 90.0, 105.0, 136.0, 91.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 125.0, 120.0, 154.0, 150.0, 97.0, 113.0, 155.0, 114.0], "value": 122.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 93.0, 92.0, 94.0, 93.0, 90.0, 95.0, 92.0, 97.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 64.0, 66.0, 64.0, 63.0, 64.0, 64.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 70.0, 77.0, 74.0, 68.0, 63.0, 93.0, 105.0, 60.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 83.0, 88.0, 87.0, 86.0, 82.0, 80.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 155.0, 152.0, 139.0, 141.0, 173.0, 157.0, 147.0, 205.0, 181.0], "value": 159.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 174.0, 168.0, 172.0, 168.0, 204.0, 234.0, 234.0, 170.0], "value": 187.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 82.0, 89.0, 85.0, 85.0, 88.0, 90.0, 59.0, 73.0], "value": 79.2, "unit": "score"}]}]} 05:26:41 INFO - Return code: 0 05:26:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:26:41 ERROR - # TBPL SUCCESS # 05:26:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:41 INFO - 2017-02-21 05:26:41.660 screenresolution[1661:10589] starting screenresolution argv=screenresolution get 05:26:41 INFO - 2017-02-21 05:26:41.662 screenresolution[1661:10589] Display 0: 1600x1200x32@60 05:26:41 INFO - 2017-02-21 05:26:41.668 screenresolution[1662:10592] starting screenresolution argv=screenresolution list 05:26:41 INFO - Available Modes on Display 0 05:26:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:41 INFO - Intel Iris: 05:26:41 INFO - Chipset Model: Intel Iris 05:26:41 INFO - Type: GPU 05:26:41 INFO - Bus: Built-In 05:26:41 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:41 INFO - Vendor: Intel (0x8086) 05:26:41 INFO - Device ID: 0x0a2e 05:26:41 INFO - Revision ID: 0x0009 05:26:41 INFO - Displays: 05:26:41 INFO - Display: 05:26:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:41 INFO - Display Serial Number: 1600x1200 60 05:26:41 INFO - Main Display: Yes 05:26:41 INFO - Mirror: Off 05:26:41 INFO - Online: Yes 05:26:41 INFO - Rotation: Supported 05:26:41 INFO - Return code: 0 05:26:41 INFO - Running post-action listener: _resource_record_post_action 05:26:41 INFO - [mozharness: 2017-02-21 13:26:41.904136Z] Finished run-tests step (success) 05:26:41 INFO - Running post-run listener: _resource_record_post_run 05:26:41 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39950848; Write bytes: 509701120; Read time: 384; Write time: 1585 05:26:41 INFO - TinderboxPrint: CPU usage
54.3% 05:26:41 INFO - TinderboxPrint: I/O read bytes / time
39,950,848 / 384 05:26:41 INFO - TinderboxPrint: I/O write bytes / time
509,701,120 / 1,585 05:26:41 INFO - TinderboxPrint: CPU idle
2,184.7 (45.7%) 05:26:41 INFO - TinderboxPrint: CPU system
964.4 (20.2%) 05:26:41 INFO - TinderboxPrint: CPU user
1,636.5 (34.2%) 05:26:41 INFO - TinderboxPrint: Swap in / out
248,242,176 / 0 05:26:41 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207729152; Write bytes: 200024064; Read time: 15917; Write time: 374 05:26:41 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35269120; Write bytes: 299596800; Read time: 350; Write time: 1180 05:26:42 INFO - Running post-run listener: _upload_blobber_files 05:26:42 INFO - Blob upload gear active. 05:26:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:42 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 05:26:42 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'] 05:26:42 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 05:26:42 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:26:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:26:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:43 INFO - (blobuploader) - INFO - Done attempting. 05:26:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:26:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:26:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:44 INFO - (blobuploader) - INFO - Done attempting. 05:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:26:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:45 INFO - (blobuploader) - INFO - Done attempting. 05:26:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:26:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:26:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:48 INFO - (blobuploader) - INFO - Done attempting. 05:26:48 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:48 INFO - Return code: 0 05:26:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac14d5a9328a51d5de745d7dede7a079bf803b9e8ecafc32989da4240d589f547183576834a1b929c37721d35d9051de661f794d225b1fb47e2e34c6adefcf31", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0c7a917f1e642db5faf16093116518fe174117f3b4227a60e5ce517c02011d09fd5e39432721cc1acbd8d946e00b09adc477a06ad3730ff384c19d2a7e6c20d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e686257c545c847cd413872648cf8eb71edda31895e5c824924a8410177103138942566fc883d0884699ce06984fc15c97f3129da64c7a7ea191f4a3290e4e64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef8df97b11f2090defdb12b398841e74495c947fa2aadcb5f2010b33c27a84fde8bb7585ce7674418ee30fd614a26eee84b0f72982721c4ee1204d9f28cc0c37"} 05:26:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:48 INFO - Contents: 05:26:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac14d5a9328a51d5de745d7dede7a079bf803b9e8ecafc32989da4240d589f547183576834a1b929c37721d35d9051de661f794d225b1fb47e2e34c6adefcf31", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0c7a917f1e642db5faf16093116518fe174117f3b4227a60e5ce517c02011d09fd5e39432721cc1acbd8d946e00b09adc477a06ad3730ff384c19d2a7e6c20d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e686257c545c847cd413872648cf8eb71edda31895e5c824924a8410177103138942566fc883d0884699ce06984fc15c97f3129da64c7a7ea191f4a3290e4e64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef8df97b11f2090defdb12b398841e74495c947fa2aadcb5f2010b33c27a84fde8bb7585ce7674418ee30fd614a26eee84b0f72982721c4ee1204d9f28cc0c37"} 05:26:48 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:48 INFO - Copying logs to upload dir... 05:26:48 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.851294 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-21 05:26:48.539717) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 05:26:48.543561) ========= 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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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/ac14d5a9328a51d5de745d7dede7a079bf803b9e8ecafc32989da4240d589f547183576834a1b929c37721d35d9051de661f794d225b1fb47e2e34c6adefcf31", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0c7a917f1e642db5faf16093116518fe174117f3b4227a60e5ce517c02011d09fd5e39432721cc1acbd8d946e00b09adc477a06ad3730ff384c19d2a7e6c20d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e686257c545c847cd413872648cf8eb71edda31895e5c824924a8410177103138942566fc883d0884699ce06984fc15c97f3129da64c7a7ea191f4a3290e4e64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef8df97b11f2090defdb12b398841e74495c947fa2aadcb5f2010b33c27a84fde8bb7585ce7674418ee30fd614a26eee84b0f72982721c4ee1204d9f28cc0c37"} build_url:https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/E_cMxWirS-aVjbDhZnO1bg/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/ac14d5a9328a51d5de745d7dede7a079bf803b9e8ecafc32989da4240d589f547183576834a1b929c37721d35d9051de661f794d225b1fb47e2e34c6adefcf31", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0c7a917f1e642db5faf16093116518fe174117f3b4227a60e5ce517c02011d09fd5e39432721cc1acbd8d946e00b09adc477a06ad3730ff384c19d2a7e6c20d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e686257c545c847cd413872648cf8eb71edda31895e5c824924a8410177103138942566fc883d0884699ce06984fc15c97f3129da64c7a7ea191f4a3290e4e64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef8df97b11f2090defdb12b398841e74495c947fa2aadcb5f2010b33c27a84fde8bb7585ce7674418ee30fd614a26eee84b0f72982721c4ee1204d9f28cc0c37"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 05:26:48.583300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:26:48.583738) ========= 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.GYb1BxOe2r/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.xdYjYNBxJW/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005042 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:26:48.633975) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 05:26:48.634310) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 05:26:48.634656) ========= ========= Total master_lag: 0.22 =========