builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0347 starttime: 1486491865.49 results: success (0) buildid: 20170207083756 builduid: ef3377da9e7d41a2bf1b92b405258790 revision: 514a5268030faf927430313a96a9e470e5c14c11 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:25.485699) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:25.486165) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:25.486446) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:25.512359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:25.512712) ========= 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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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-07 10:24:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 78.5M=0s 2017-02-07 10:24:25 (78.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140450 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:25.677175) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:25.677509) ========= 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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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.024869 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:25.725780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 10:24:25.726149) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 514a5268030faf927430313a96a9e470e5c14c11 --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 514a5268030faf927430313a96a9e470e5c14c11 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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-07 10:24:25,833 truncating revision to first 12 chars 2017-02-07 10:24:25,834 Setting DEBUG logging. 2017-02-07 10:24:25,834 attempt 1/10 2017-02-07 10:24:25,834 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/514a5268030f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-07 10:24:27,225 unpacking tar archive at: mozilla-aurora-514a5268030f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.668477 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 10:24:27.417735) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:27.418191) ========= script_repo_revision: 514a5268030faf927430313a96a9e470e5c14c11 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:27.418583) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:27.418870) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 10:24:27.438164) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-07 10:24:27.438464) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:24:27 INFO - MultiFileLogger online at 20170207 10:24:27 in /builds/slave/test 10:24:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:24:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:24:27 INFO - {'append_to_log': False, 10:24:27 INFO - 'base_work_dir': '/builds/slave/test', 10:24:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:24:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:24:27 INFO - 'branch': 'Mozilla-Aurora', 10:24:27 INFO - 'buildbot_json_path': 'buildprops.json', 10:24:27 INFO - 'config_files': ('talos/mac_config.py',), 10:24:27 INFO - 'default_actions': ('clobber', 10:24:27 INFO - 'read-buildbot-config', 10:24:27 INFO - 'download-and-extract', 10:24:27 INFO - 'populate-webroot', 10:24:27 INFO - 'create-virtualenv', 10:24:27 INFO - 'install', 10:24:27 INFO - 'run-tests'), 10:24:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:24:27 INFO - 'download_minidump_stackwalk': True, 10:24:27 INFO - 'download_symbols': 'ondemand', 10:24:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:24:27 INFO - 'tooltool.py': '/tools/tooltool.py', 10:24:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:24:27 INFO - '/tools/misc-python/virtualenv.py')}, 10:24:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:24:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:24:27 INFO - 'installer_path': 'installer.exe', 10:24:27 INFO - 'log_level': 'info', 10:24:27 INFO - 'log_name': 'talos', 10:24:27 INFO - 'log_to_console': True, 10:24:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:24:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:24:27 INFO - 'opt_config_files': (), 10:24:27 INFO - 'pip_index': False, 10:24:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:24:27 INFO - 'cmd': ('bash', 10:24:27 INFO - '-c', 10:24:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:24:27 INFO - 'enabled': True, 10:24:27 INFO - 'halt_on_failure': False, 10:24:27 INFO - 'name': 'check_screen_resolution'},), 10:24:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:24:27 INFO - 'cmd': ('bash', 10:24:27 INFO - '-c', 10:24:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:24:27 INFO - 'enabled': True, 10:24:27 INFO - 'halt_on_failure': False, 10:24:27 INFO - 'name': 'check_screen_resolution'},), 10:24:27 INFO - 'run_cmd_checks_enabled': True, 10:24:27 INFO - 'sps_profile': False, 10:24:27 INFO - 'sps_profile_interval': 0, 10:24:27 INFO - 'suite': 'dromaeojs', 10:24:27 INFO - 'system_bits': '32', 10:24:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:24:27 INFO - 'title': 't-yosemite-r7-0347', 10:24:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:24:27 INFO - 'use_talos_json': True, 10:24:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:24:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:24:27 INFO - 'work_dir': 'build'} 10:24:27 INFO - [mozharness: 2017-02-07 18:24:27.650729Z] Running clobber step. 10:24:27 INFO - Running pre-action listener: _resource_record_pre_action 10:24:27 INFO - Running main action method: clobber 10:24:27 INFO - rmtree: /builds/slave/test/build 10:24:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:24:30 INFO - Running post-action listener: _resource_record_post_action 10:24:30 INFO - [mozharness: 2017-02-07 18:24:30.053521Z] Finished clobber step (success) 10:24:30 INFO - [mozharness: 2017-02-07 18:24:30.053809Z] Running read-buildbot-config step. 10:24:30 INFO - Running pre-action listener: _resource_record_pre_action 10:24:30 INFO - Running main action method: read_buildbot_config 10:24:30 INFO - Using buildbot properties: 10:24:30 INFO - { 10:24:30 INFO - "project": "", 10:24:30 INFO - "product": "firefox", 10:24:30 INFO - "script_repo_revision": "production", 10:24:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:24:30 INFO - "repository": "", 10:24:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:24:30 INFO - "buildid": "20170207083756", 10:24:30 INFO - "pgo_build": "False", 10:24:30 INFO - "basedir": "/builds/slave/test", 10:24:30 INFO - "buildnumber": 55, 10:24:30 INFO - "slavename": "t-yosemite-r7-0347", 10:24:30 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:24:30 INFO - "platform": "yosemite_r7", 10:24:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:24:30 INFO - "branch": "mozilla-aurora", 10:24:30 INFO - "repo_path": "releases/mozilla-aurora", 10:24:30 INFO - "revision": "514a5268030faf927430313a96a9e470e5c14c11", 10:24:30 INFO - "stage_platform": "macosx64", 10:24:30 INFO - "builduid": "ef3377da9e7d41a2bf1b92b405258790", 10:24:30 INFO - "slavebuilddir": "test" 10:24:30 INFO - } 10:24:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 10:24:30 INFO - Running post-action listener: _resource_record_post_action 10:24:30 INFO - [mozharness: 2017-02-07 18:24:30.059673Z] Finished read-buildbot-config step (success) 10:24:30 INFO - [mozharness: 2017-02-07 18:24:30.059834Z] Running download-and-extract step. 10:24:30 INFO - Running pre-action listener: _resource_record_pre_action 10:24:30 INFO - Running main action method: download_and_extract 10:24:30 INFO - mkdir: /builds/slave/test/build/tests 10:24:30 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')]} 10:24:30 INFO - trying https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 10:24:30 INFO - Downloading https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/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 10:24:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/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 10:24:31 INFO - Downloaded 1199 bytes. 10:24:31 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 10:24:31 INFO - Using the following test package requirements: 10:24:31 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 10:24:31 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:24:31 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 10:24:31 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:24:31 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 10:24:31 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:24:31 INFO - u'jsshell-mac.zip'], 10:24:31 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:24:31 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 10:24:31 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 10:24:31 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:24:31 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 10:24:31 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:24:31 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 10:24:31 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:24:31 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 10:24:31 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:24:31 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 10:24:31 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:24:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 10:24:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:24:31 INFO - Fetch https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 10:24:32 INFO - Expected file size: 16357326 10:24:32 INFO - Obtained file size: 16357326 10:24:38 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 10:24:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 10:24:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:24:38 INFO - Fetch https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 10:24:39 INFO - Expected file size: 16357326 10:24:39 INFO - Obtained file size: 16357326 10:24:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 10:24:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:24:46 INFO - Fetch https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 10:24:47 INFO - Expected file size: 14403485 10:24:47 INFO - Obtained file size: 14403485 10:24:47 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')]} 10:24:47 INFO - trying https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 10:24:47 INFO - Downloading https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 10:24:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/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 10:24:50 INFO - Downloaded 73820613 bytes. 10:24:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 10:24:50 INFO - mkdir: /builds/slave/test/properties 10:24:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:24:50 INFO - Writing to file /builds/slave/test/properties/build_url 10:24:50 INFO - Contents: 10:24:50 INFO - build_url:https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 10:24:50 INFO - Running post-action listener: _resource_record_post_action 10:24:50 INFO - Running post-action listener: set_extra_try_arguments 10:24:50 INFO - [mozharness: 2017-02-07 18:24:50.766092Z] Finished download-and-extract step (success) 10:24:50 INFO - [mozharness: 2017-02-07 18:24:50.766217Z] Running populate-webroot step. 10:24:50 INFO - Running pre-action listener: _resource_record_pre_action 10:24:50 INFO - Running main action method: populate_webroot 10:24:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:24:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:24:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:24:50 INFO - '--tppagecycles', 10:24:50 INFO - '7'], 10:24:50 INFO - 'tests': ['tsvgm']}}, 10:24:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:24:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:24:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:24:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:24:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:24:50 INFO - 'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:24:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:24:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:24:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:24:50 INFO - 'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['damp', 'tps']}, 10:24:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:24:50 INFO - 'tests': ['damp', 'tps']}, 10:24:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['dromaeo_dom']}, 10:24:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:24:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['basic_compositor_video']}, 10:24:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:24:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['a11yr', 10:24:50 INFO - 'ts_paint', 10:24:50 INFO - 'tpaint', 10:24:50 INFO - 'sessionrestore', 10:24:50 INFO - 'sessionrestore_no_auto_restore', 10:24:50 INFO - 'tabpaint']}, 10:24:50 INFO - 'other-e10s': {'tests': ['a11yr', 10:24:50 INFO - 'ts_paint', 10:24:50 INFO - 'tpaint', 10:24:50 INFO - 'sessionrestore', 10:24:50 INFO - 'sessionrestore_no_auto_restore', 10:24:50 INFO - 'tabpaint']}, 10:24:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:24:50 INFO - 'ts_paint', 10:24:50 INFO - 'tpaint', 10:24:50 INFO - 'sessionrestore', 10:24:50 INFO - 'sessionrestore_no_auto_restore', 10:24:50 INFO - 'tabpaint']}, 10:24:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:24:50 INFO - 'ts_paint', 10:24:50 INFO - 'tpaint', 10:24:50 INFO - 'sessionrestore', 10:24:50 INFO - 'sessionrestore_no_auto_restore', 10:24:50 INFO - 'tabpaint']}, 10:24:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['a11yr', 10:24:50 INFO - 'ts_paint', 10:24:50 INFO - 'tpaint', 10:24:50 INFO - 'sessionrestore', 10:24:50 INFO - 'sessionrestore_no_auto_restore', 10:24:50 INFO - 'tabpaint']}, 10:24:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['a11yr', 10:24:50 INFO - 'ts_paint', 10:24:50 INFO - 'tpaint', 10:24:50 INFO - 'sessionrestore', 10:24:50 INFO - 'sessionrestore_no_auto_restore', 10:24:50 INFO - 'tabpaint']}, 10:24:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['tsvgx', 10:24:50 INFO - 'tsvgr_opacity', 10:24:50 INFO - 'tart', 10:24:50 INFO - 'tscrollx', 10:24:50 INFO - 'cart', 10:24:50 INFO - 'tsvg_static']}, 10:24:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:24:50 INFO - 'tsvgr_opacity', 10:24:50 INFO - 'tart', 10:24:50 INFO - 'tscrollx', 10:24:50 INFO - 'cart', 10:24:50 INFO - 'tsvg_static']}, 10:24:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:24:50 INFO - 'talos_options': ['--disable-e10s'], 10:24:50 INFO - 'tests': ['tp5o']}, 10:24:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:24:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:24:50 INFO - 'talos_options': ['--disable-e10s', 10:24:50 INFO - '--xperf_path', 10:24:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:24:50 INFO - 'tests': ['tp5n']}, 10:24:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:24:50 INFO - 'talos_options': ['--xperf_path', 10:24:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:24:50 INFO - 'tests': ['tp5n']}}, 10:24:50 INFO - 'talos.zip': {'path': '', 10:24:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:24:50 INFO - Running post-action listener: _resource_record_post_action 10:24:50 INFO - [mozharness: 2017-02-07 18:24:50.785178Z] Finished populate-webroot step (success) 10:24:50 INFO - [mozharness: 2017-02-07 18:24:50.785291Z] Running create-virtualenv step. 10:24:50 INFO - Running pre-action listener: _resource_record_pre_action 10:24:50 INFO - Running main action method: create_virtualenv 10:24:50 INFO - Creating virtualenv /builds/slave/test/build/venv 10:24:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:24:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:24:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:24:50 INFO - Using real prefix '/tools/python27' 10:24:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:24:51 INFO - Installing distribute.............................................................................................................................................................................................done. 10:24:54 INFO - Installing pip.................done. 10:24:54 INFO - Return code: 0 10:24:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:24:54 INFO - Reading from file tmpfile_stdout 10:24:54 INFO - Output received: 10:24:54 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:24:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:54 INFO - 'HOME': '/Users/cltbld', 10:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:54 INFO - 'LOGNAME': 'cltbld', 10:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:54 INFO - 'MOZ_NO_REMOTE': '1', 10:24:54 INFO - 'NO_EM_RESTART': '1', 10:24:54 INFO - 'PAGER': '/bin/cat', 10:24:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:54 INFO - 'PWD': '/builds/slave/test', 10:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:54 INFO - 'SHELL': '/bin/bash', 10:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:24:54 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:54 INFO - 'USER': 'cltbld', 10:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:54 INFO - 'XPC_FLAGS': '0x0', 10:24:54 INFO - 'XPC_SERVICE_NAME': '0', 10:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:24:54 INFO - Cleaning up... 10:24:54 INFO - Return code: 0 10:24:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:24:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:24:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:24:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:55 INFO - 'HOME': '/Users/cltbld', 10:24:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:55 INFO - 'LOGNAME': 'cltbld', 10:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:55 INFO - 'MOZ_NO_REMOTE': '1', 10:24:55 INFO - 'NO_EM_RESTART': '1', 10:24:55 INFO - 'PAGER': '/bin/cat', 10:24:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:55 INFO - 'PWD': '/builds/slave/test', 10:24:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:55 INFO - 'SHELL': '/bin/bash', 10:24:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:24:55 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:24:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:55 INFO - 'USER': 'cltbld', 10:24:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:55 INFO - 'XPC_FLAGS': '0x0', 10:24:55 INFO - 'XPC_SERVICE_NAME': '0', 10:24:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:55 INFO - Downloading/unpacking psutil>=3.1.1 10:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:24:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:24:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:24:58 INFO - Installing collected packages: psutil 10:24:58 INFO - Running setup.py install for psutil 10:24:58 INFO - building 'psutil._psutil_osx' extension 10:24:58 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 10:24:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:24:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:24:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:24:59 INFO - building 'psutil._psutil_posix' extension 10:24:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:24:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:24:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:24:59 INFO - ^ 10:24:59 INFO - 1 warning generated. 10:24:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:24:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:24:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:24:59 INFO - Successfully installed psutil 10:24:59 INFO - Cleaning up... 10:24:59 INFO - Return code: 0 10:24:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:24:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:24:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:24:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:59 INFO - 'HOME': '/Users/cltbld', 10:24:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:59 INFO - 'LOGNAME': 'cltbld', 10:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:59 INFO - 'MOZ_NO_REMOTE': '1', 10:24:59 INFO - 'NO_EM_RESTART': '1', 10:24:59 INFO - 'PAGER': '/bin/cat', 10:24:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:59 INFO - 'PWD': '/builds/slave/test', 10:24:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:59 INFO - 'SHELL': '/bin/bash', 10:24:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:24:59 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:24:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:59 INFO - 'USER': 'cltbld', 10:24:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:59 INFO - 'XPC_FLAGS': '0x0', 10:24:59 INFO - 'XPC_SERVICE_NAME': '0', 10:24:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:25:02 INFO - Installing collected packages: mozsystemmonitor 10:25:02 INFO - Running setup.py install for mozsystemmonitor 10:25:03 INFO - Successfully installed mozsystemmonitor 10:25:03 INFO - Cleaning up... 10:25:03 INFO - Return code: 0 10:25:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:25:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:25:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:25:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:25:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:03 INFO - 'HOME': '/Users/cltbld', 10:25:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:03 INFO - 'LOGNAME': 'cltbld', 10:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:03 INFO - 'MOZ_NO_REMOTE': '1', 10:25:03 INFO - 'NO_EM_RESTART': '1', 10:25:03 INFO - 'PAGER': '/bin/cat', 10:25:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:03 INFO - 'PWD': '/builds/slave/test', 10:25:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:03 INFO - 'SHELL': '/bin/bash', 10:25:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:25:03 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:25:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:03 INFO - 'USER': 'cltbld', 10:25:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:03 INFO - 'XPC_FLAGS': '0x0', 10:25:03 INFO - 'XPC_SERVICE_NAME': '0', 10:25:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:03 INFO - Downloading/unpacking jsonschema==2.5.1 10:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:25:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:25:05 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 10:25:05 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 10:25:05 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 10:25:05 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 10:25:05 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 10:25:05 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 10:25:06 INFO - Downloading functools32-3.2.3-2.tar.gz 10:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:25:06 INFO - warning: no files found matching '*.txt' 10:25:06 INFO - no previously-included directories found matching 'build' 10:25:06 INFO - no previously-included directories found matching 'dist' 10:25:06 INFO - no previously-included directories found matching '.git*' 10:25:06 INFO - Installing collected packages: jsonschema, functools32 10:25:06 INFO - Running setup.py install for functools32 10:25:06 INFO - warning: no files found matching '*.txt' 10:25:06 INFO - no previously-included directories found matching 'build' 10:25:06 INFO - no previously-included directories found matching 'dist' 10:25:06 INFO - no previously-included directories found matching '.git*' 10:25:06 INFO - Successfully installed jsonschema functools32 10:25:06 INFO - Cleaning up... 10:25:06 INFO - Return code: 0 10:25:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:06 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 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:25:06 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 10:25:06 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 10:25:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:25:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:06 INFO - 'HOME': '/Users/cltbld', 10:25:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:06 INFO - 'LOGNAME': 'cltbld', 10:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:06 INFO - 'MOZ_NO_REMOTE': '1', 10:25:06 INFO - 'NO_EM_RESTART': '1', 10:25:06 INFO - 'PAGER': '/bin/cat', 10:25:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:06 INFO - 'PWD': '/builds/slave/test', 10:25:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:06 INFO - 'SHELL': '/bin/bash', 10:25:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:25:06 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:25:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:06 INFO - 'USER': 'cltbld', 10:25:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:06 INFO - 'XPC_FLAGS': '0x0', 10:25:06 INFO - 'XPC_SERVICE_NAME': '0', 10:25:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:25:07 INFO - Cleaning up... 10:25:07 INFO - Return code: 0 10:25:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:07 INFO - 'HOME': '/Users/cltbld', 10:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:07 INFO - 'LOGNAME': 'cltbld', 10:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:07 INFO - 'MOZ_NO_REMOTE': '1', 10:25:07 INFO - 'NO_EM_RESTART': '1', 10:25:07 INFO - 'PAGER': '/bin/cat', 10:25:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:07 INFO - 'PWD': '/builds/slave/test', 10:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:07 INFO - 'SHELL': '/bin/bash', 10:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:25:07 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:07 INFO - 'USER': 'cltbld', 10:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:07 INFO - 'XPC_FLAGS': '0x0', 10:25:07 INFO - 'XPC_SERVICE_NAME': '0', 10:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:07 INFO - Downloading/unpacking blobuploader==1.2.4 10:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:09 INFO - Downloading blobuploader-1.2.4.tar.gz 10:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:25:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:25:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:11 INFO - Downloading docopt-0.6.1.tar.gz 10:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:25:11 INFO - Installing collected packages: blobuploader, requests, docopt 10:25:11 INFO - Running setup.py install for blobuploader 10:25:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:25:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:25:11 INFO - Running setup.py install for requests 10:25:12 INFO - Running setup.py install for docopt 10:25:12 INFO - Successfully installed blobuploader requests docopt 10:25:12 INFO - Cleaning up... 10:25:12 INFO - Return code: 0 10:25:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:25:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:12 INFO - 'HOME': '/Users/cltbld', 10:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:12 INFO - 'LOGNAME': 'cltbld', 10:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:12 INFO - 'MOZ_NO_REMOTE': '1', 10:25:12 INFO - 'NO_EM_RESTART': '1', 10:25:12 INFO - 'PAGER': '/bin/cat', 10:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:12 INFO - 'PWD': '/builds/slave/test', 10:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:12 INFO - 'SHELL': '/bin/bash', 10:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:25:12 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:12 INFO - 'USER': 'cltbld', 10:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:12 INFO - 'XPC_FLAGS': '0x0', 10:25:12 INFO - 'XPC_SERVICE_NAME': '0', 10:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:25:12 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-WxHdC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:25:12 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-VZiRUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:25:12 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-kbcreV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:25:12 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-cFCVud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:25:12 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-mZ2ALx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-9RDVPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-PQNXXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-10KsTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-5Ux1x5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-onAaai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-JERVA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-i_UkLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-CUs342-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-r61spq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:25:13 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-t6ny9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:25:14 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-X_dosh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:25:14 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-YvZd_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:25:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:25:14 INFO - Running setup.py install for manifestparser 10:25:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:25:14 INFO - Running setup.py install for mozcrash 10:25:14 INFO - Running setup.py install for mozdebug 10:25:14 INFO - Running setup.py install for mozdevice 10:25:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:25:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:25:14 INFO - Running setup.py install for mozfile 10:25:15 INFO - Running setup.py install for mozhttpd 10:25:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:25:15 INFO - Running setup.py install for mozinfo 10:25:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:25:15 INFO - Running setup.py install for mozInstall 10:25:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:25:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:25:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:25:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:25:15 INFO - Running setup.py install for mozleak 10:25:15 INFO - Running setup.py install for mozlog 10:25:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:25:15 INFO - Running setup.py install for moznetwork 10:25:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:25:15 INFO - Running setup.py install for mozprocess 10:25:16 INFO - Running setup.py install for mozprofile 10:25:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:25:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:25:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:25:16 INFO - Running setup.py install for mozrunner 10:25:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:25:16 INFO - Running setup.py install for mozscreenshot 10:25:16 INFO - Running setup.py install for moztest 10:25:16 INFO - Running setup.py install for mozversion 10:25:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:25:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:25:16 INFO - Cleaning up... 10:25:16 INFO - Return code: 0 10:25:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:25: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')]} 10:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25: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')]} 10:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:16 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 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:25:16 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 10:25:16 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 10:25:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:25:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:16 INFO - 'HOME': '/Users/cltbld', 10:25:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:16 INFO - 'LOGNAME': 'cltbld', 10:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:16 INFO - 'MOZ_NO_REMOTE': '1', 10:25:16 INFO - 'NO_EM_RESTART': '1', 10:25:16 INFO - 'PAGER': '/bin/cat', 10:25:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:16 INFO - 'PWD': '/builds/slave/test', 10:25:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:16 INFO - 'SHELL': '/bin/bash', 10:25:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:25:16 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:25:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:16 INFO - 'USER': 'cltbld', 10:25:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:16 INFO - 'XPC_FLAGS': '0x0', 10:25:16 INFO - 'XPC_SERVICE_NAME': '0', 10:25:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-5CPecn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-qHGX40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-cqvGkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-jvFUdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-w8ykGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-6DV5QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:25:17 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)) 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-ISDTyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:25:17 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)) 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-288cQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:25:17 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)) 10:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:25:17 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-tSUOnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:25:18 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-rHXuRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:25:18 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-g1sFM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:25:18 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-2m5ufo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:25:18 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-MNnQXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:25:18 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-1DyCvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:25:18 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-iqlA_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:25:18 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)) 10:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:25:18 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-HosKzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:25:18 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)) 10:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:25:18 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-4Ui0sV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:25:18 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)) 10:25:18 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)) 10:25:18 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)) 10:25:18 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)) 10:25:18 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)) 10:25:18 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)) 10:25:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:25:18 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 10:25:18 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 10:25:18 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 10:25:18 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 10:25:18 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 10:25:18 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 10:25:21 INFO - Downloading blessings-1.6.tar.gz 10:25:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:25:21 INFO - Installing collected packages: blessings 10:25:21 INFO - Running setup.py install for blessings 10:25:21 INFO - Successfully installed blessings 10:25:21 INFO - Cleaning up... 10:25:22 INFO - Return code: 0 10:25:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:25:22 INFO - Reading from file tmpfile_stdout 10:25:22 INFO - Current package versions: 10:25:22 INFO - blessings == 1.6 10:25:22 INFO - blobuploader == 1.2.4 10:25:22 INFO - docopt == 0.6.1 10:25:22 INFO - functools32 == 3.2.3-2 10:25:22 INFO - jsonschema == 2.5.1 10:25:22 INFO - manifestparser == 1.1 10:25:22 INFO - mozInstall == 1.12 10:25:22 INFO - mozcrash == 1.0 10:25:22 INFO - mozdebug == 0.1 10:25:22 INFO - mozdevice == 0.49 10:25:22 INFO - mozfile == 1.2 10:25:22 INFO - mozhttpd == 0.7 10:25:22 INFO - mozinfo == 0.9 10:25:22 INFO - mozleak == 0.1 10:25:22 INFO - mozlog == 3.4 10:25:22 INFO - moznetwork == 0.27 10:25:22 INFO - mozprocess == 0.24 10:25:22 INFO - mozprofile == 0.28 10:25:22 INFO - mozrunner == 6.13 10:25:22 INFO - mozscreenshot == 0.1 10:25:22 INFO - mozsystemmonitor == 0.3 10:25:22 INFO - moztest == 0.8 10:25:22 INFO - mozversion == 1.4 10:25:22 INFO - psutil == 3.1.1 10:25:22 INFO - requests == 1.2.3 10:25:22 INFO - wsgiref == 0.1.2 10:25:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:25:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:25:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:25:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:22 INFO - 'HOME': '/Users/cltbld', 10:25:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:22 INFO - 'LOGNAME': 'cltbld', 10:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:22 INFO - 'MOZ_NO_REMOTE': '1', 10:25:22 INFO - 'NO_EM_RESTART': '1', 10:25:22 INFO - 'PAGER': '/bin/cat', 10:25:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:22 INFO - 'PWD': '/builds/slave/test', 10:25:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:22 INFO - 'SHELL': '/bin/bash', 10:25:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:25:22 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:25:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:22 INFO - 'USER': 'cltbld', 10:25:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:22 INFO - 'XPC_FLAGS': '0x0', 10:25:22 INFO - 'XPC_SERVICE_NAME': '0', 10:25:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 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)) 10:25:23 INFO - Cleaning up... 10:25:23 INFO - Return code: 0 10:25:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:23 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 0x1016f6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016f9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e0ad6f2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, '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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 10:25:23 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 10:25:23 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 10:25:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:25:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:23 INFO - 'HOME': '/Users/cltbld', 10:25:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:23 INFO - 'LOGNAME': 'cltbld', 10:25:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:23 INFO - 'MOZ_NO_REMOTE': '1', 10:25:23 INFO - 'NO_EM_RESTART': '1', 10:25:23 INFO - 'PAGER': '/bin/cat', 10:25:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:23 INFO - 'PWD': '/builds/slave/test', 10:25:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:23 INFO - 'SHELL': '/bin/bash', 10:25:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:25:23 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:25:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:23 INFO - 'USER': 'cltbld', 10:25:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:23 INFO - 'XPC_FLAGS': '0x0', 10:25:23 INFO - 'XPC_SERVICE_NAME': '0', 10:25:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:25:23 INFO - Cleaning up... 10:25:23 INFO - Return code: 0 10:25:23 INFO - Running post-action listener: _resource_record_post_action 10:25:23 INFO - Running post-action listener: _start_resource_monitoring 10:25:23 INFO - Starting resource monitoring. 10:25:23 INFO - [mozharness: 2017-02-07 18:25:23.983296Z] Finished create-virtualenv step (success) 10:25:23 INFO - [mozharness: 2017-02-07 18:25:23.984235Z] Running install step. 10:25:23 INFO - Running pre-action listener: _resource_record_pre_action 10:25:23 INFO - Running main action method: install 10:25:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:25:24 INFO - Reading from file tmpfile_stdout 10:25:24 INFO - Detecting whether we're running mozinstall >=1.0... 10:25:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:25:24 INFO - Reading from file tmpfile_stdout 10:25:24 INFO - Output received: 10:25:24 INFO - Usage: mozinstall [options] installer 10:25:24 INFO - Options: 10:25:24 INFO - -h, --help show this help message and exit 10:25:24 INFO - -d DEST, --destination=DEST 10:25:24 INFO - Directory to install application into. [default: 10:25:24 INFO - "/builds/slave/test"] 10:25:24 INFO - --app=APP Application being installed. [default: firefox] 10:25:24 INFO - mkdir: /builds/slave/test/build/application 10:25:24 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'] 10:25:24 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 10:25:44 INFO - Reading from file tmpfile_stdout 10:25:44 INFO - Output received: 10:25:44 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:25:44 INFO - Running post-action listener: _resource_record_post_action 10:25:44 INFO - [mozharness: 2017-02-07 18:25:44.421089Z] Finished install step (success) 10:25:44 INFO - [mozharness: 2017-02-07 18:25:44.421220Z] Running run-tests step. 10:25:44 INFO - Running pre-action listener: _resource_record_pre_action 10:25:44 INFO - Running main action method: run_tests 10:25:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:25:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:25:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:25:44 INFO - 2017-02-07 10:25:44.436 screenresolution[1623:7297] starting screenresolution argv=screenresolution get 10:25:44 INFO - 2017-02-07 10:25:44.438 screenresolution[1623:7297] Display 0: 1600x1200x32@60 10:25:44 INFO - 2017-02-07 10:25:44.444 screenresolution[1624:7300] starting screenresolution argv=screenresolution list 10:25:44 INFO - Available Modes on Display 0 10:25:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:25:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:25:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:25:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:25:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:25:44 INFO - Intel Iris: 10:25:44 INFO - Chipset Model: Intel Iris 10:25:44 INFO - Type: GPU 10:25:44 INFO - Bus: Built-In 10:25:44 INFO - VRAM (Dynamic, Max): 1536 MB 10:25:44 INFO - Vendor: Intel (0x8086) 10:25:44 INFO - Device ID: 0x0a2e 10:25:44 INFO - Revision ID: 0x0009 10:25:44 INFO - Displays: 10:25:44 INFO - Display: 10:25:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:25:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:25:44 INFO - Display Serial Number: 1600x1200 60 10:25:44 INFO - Main Display: Yes 10:25:44 INFO - Mirror: Off 10:25:44 INFO - Online: Yes 10:25:44 INFO - Rotation: Supported 10:25:44 INFO - Return code: 0 10:25:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:25:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:25:44 INFO - Python 2.7.3 10:25:44 INFO - Return code: 0 10:25:44 INFO - grabbing minidump binary from tooltool 10:25: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')]} 10:25:44 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 0x1014d4d30>, '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 0x1013fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014c9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:25:44 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 10:25:44 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 10:25:44 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 10:25:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:25:44 INFO - Return code: 0 10:25:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:25:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:25:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:25:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:25:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:25:44 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:25:44 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0347 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2CT6Dz3vV/Render', 10:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:44 INFO - 'HOME': '/Users/cltbld', 10:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:44 INFO - 'LOGNAME': 'cltbld', 10:25:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:25:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:44 INFO - 'MOZ_NO_REMOTE': '1', 10:25:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:25:44 INFO - 'NO_EM_RESTART': '1', 10:25:44 INFO - 'PAGER': '/bin/cat', 10:25:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:44 INFO - 'PWD': '/builds/slave/test', 10:25:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:44 INFO - 'SHELL': '/bin/bash', 10:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJgLO5ElPg/Listeners', 10:25:44 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 10:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:44 INFO - 'USER': 'cltbld', 10:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:44 INFO - 'XPC_FLAGS': '0x0', 10:25:44 INFO - 'XPC_SERVICE_NAME': '0', 10:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:25:45 INFO - mozversion application_buildid: 20170207083756 10:25:45 INFO - mozversion application_changeset: 514a5268030faf927430313a96a9e470e5c14c11 10:25:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:25:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:25:45 INFO - mozversion application_name: Firefox 10:25:45 INFO - mozversion application_remotingname: firefox-dev 10:25:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:25:45 INFO - mozversion application_vendor: Mozilla 10:25:45 INFO - mozversion application_version: 53.0a2 10:25:45 INFO - mozversion platform_buildid: 20170207083756 10:25:45 INFO - mozversion platform_changeset: 514a5268030faf927430313a96a9e470e5c14c11 10:25:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:25:45 INFO - mozversion platform_version: 53.0a2 10:25:45 INFO - using testdate: 1486491945 10:25:45 INFO - actual date: 1486491945 10:25:45 INFO - starting webserver on 'localhost:49236' 10:25:45 INFO - SUITE-START | Running 2 tests 10:25:45 INFO - TEST-START | dromaeo_css 10:25:45 INFO - operating with platform_type : mac_ 10:25:45 INFO - Initialising browser for dromaeo_css test... 10:25:45 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmprgj6sA/profile http://localhost:49236/getInfo.html) 10:25:47 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 10:25:47 INFO - PROCESS | 1634 | colorDepth:24 10:25:47 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 10:25:47 INFO - PROCESS | 1634 | __metrics 10:25:47 INFO - PROCESS | 1634 | 1486491947390 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 10:25:47 INFO - PROCESS | 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:25:47 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:25:48 INFO - TEST-INFO | 1634: exit 0 10:25:48 INFO - Browser initialized. 10:25:48 INFO - Running cycle 1/1 for dromaeo_css test... 10:25:48 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmprgj6sA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:26:00 INFO - PROCESS | 1636 | RSS: Main: 213250048 10:26:00 INFO - PROCESS | 1636 | 10:29:04 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 10:29:05 INFO - PROCESS | 1636 | RSS: Main: 341721088 10:29:05 INFO - PROCESS | 1636 | 10:30:49 INFO - PROCESS | 1636 | ************************* 10:30:49 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 10:30:49 INFO - PROCESS | 1636 | 10:30:49 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:30:49 INFO - PROCESS | 1636 | Full stack: 10:30:49 INFO - PROCESS | 1636 | ************************* 10:32:08 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 10:32:09 INFO - PROCESS | 1636 | RSS: Main: 350314496 10:32:09 INFO - PROCESS | 1636 | 10:35:11 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 10:35:12 INFO - PROCESS | 1636 | RSS: Main: 321409024 10:35:12 INFO - PROCESS | 1636 | 10:38:14 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 10:38:15 INFO - PROCESS | 1636 | RSS: Main: 326025216 10:38:15 INFO - PROCESS | 1636 | 10:41:18 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 10:41:19 INFO - PROCESS | 1636 | RSS: Main: 317222912 10:41:19 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 10:44:22 INFO - PROCESS | 1636 | RSS: Main: 570318848 10:44:22 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | __start_tp_report 10:44:22 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 10:44:22 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 10:44:22 INFO - PROCESS | 1636 | |i|pagename|runs| 10:44:22 INFO - PROCESS | 1636 | |0;dojo.html;3314.4862546305326;3331.500274958756;3355.228297491575;3375;3400.5409269748593;5680.57395695323;5710.114932185513;5727.684977326246;5737.655740655564;5740.885182296346;5709.486146246837;5716.314042314928;5788.5658575606785;5793.420657934215;5802.187693722872;5239.607029472786;5291.947080529184;5296.788128474856;5340.866478338034;5341.973290133544;4612.515685852987;4691.733232027351;5327.334083239595;5334.679919204841;5342.693184027771;5229.084910140729;5234.293370395005;5255.290535777659;5271.736413179345;5288.233206185101;5292.761825717852;5310.0441920454305;5330.7334633268565;5331.067063263933;5340.813071542516;5147.613928955306;5227.320448341729;5274.340707411574;5275.894482110375;5316.680999140064;5599.8880022399735;5623.212750214973;5627.409122042168;5662.773489060432;5794.768209271624;5273.86815329616;6240.937590624081;6303.968480157616;6309.179806625155;6324.715387807513;6198.443676503466;6290.119383286344;6292.842678933018;6296;6313.147725057104;5778.422157784188;5834.358220595731;5892.646441213456;5937.376575459558;5969.850753731148;6215.595986260879;6246.312905580383;6288.434040936246;6290.528210384239;6306.432421082125;6151;6155.076738489173;6157.0456579230295;6166.260048794174;6170.10533472644;6252.187215661935;6282.057691346335;6290.905636415458;6298.874022519524;6317.778877739257;6116.602420842631;6126.548209435026;6132.324915438508;6155.1075094112;6223.004319308889;5266.578673706094;6319.431251187415;6319.842003949846;6340.302566717657;6342.936570634327;4296.5273819879785;5050.217216331474;5051.015052064812;5069.5944324454695;5091.669041512364;4412.624926881251;4421.557844215553;4472.910541789146;4497.347884556786;4521.163584736834;5696.458836410535;5716.342620598602;5724.77100915967;5735.655860648374;5826.912596311059;6188.597741146811;6214.347493513206;6230.906536401958;6277.717847297114;6325.841853953596;5651.4348565143155;5717.971410142923;5778.3354914184565;5794.449527294901;5804.390538993345;5376.193570964387;5523.530499907552;5536.667799931936;5544.750486228129;5545.334559852763;5259.027079990214;6227.844303892439;6271.278802937538;6300.527460440577;6309.1167236586;6241.687915604292;6332.366763323631;6335.303116657256;6335.588186767845;6351.618902865843;6299.96850015747;6323.399277068671;6326.240851097898;6328.335524769833;6351.2696039956;6047.637141771508;6228.5951413158;6230.00319948806;6234.314225435289;6241.469475094668;6176.135341052166;6235.158253635701;6237.937620623736;6299.811005669837;6336.556441049082;6107.938920610737;6177.227846519185;6194.690265486617;6196.907046394398;6206.131141640084;6151.876962460815;6175.166352542349;6184.505239580732;6188.597741146811;6191.81424557264;5735;5816.796412125725;5854.677992710442;5938.317093534126;5970.76116955317;6177.6911154443;6187.690615469119;6199.225096862892;6201.10084037821;6203.875922481435;6015.180076399042;6018.127371531016;6043.516518678583;6048.637081775108;6072.423119803611 10:44:22 INFO - PROCESS | 1636 | |1;ext.html;433.6985794872565;447.7514979186551;481.76634332348783;487.8414515282534;528.484727390794;30560;32481.67518324819;39772.2045559089;41571.792141039325;41678.37482437766;43070.923226919265;43365.34951975723;43437;43462;43521.564784352224;9229.35394522382;9365.719028429157;9460;9466.952665236664;9474.431534107976;8451.577421128919;8692.217700406962;8696.304295656333;8761.080086590911;9351.766205844875;8602.311815054813;11183.888161118366;11435.085193184525;11471.311721296706;11667.533298668086;11390.487428065715;11480.655580332603;11481.196316257865;11509;11512.366819824865;8385.454945428588;11351.148663850245;11417.600383986604;11423.828642570368;11429.542818287258;32027.679723202935;39760.602393975976;40314;40490.39264411036;42688.57311426877;4031.455753473273;4037.071473561068;4047.635712785834;4055.3308704063793;4071.450354202204;28846.423071538662;29531.852340738373;30516.694833051606;31226.90705825308;31639;3132.1386618679817;3179.0621766578915;3243.4161850866835;3280.753943454227;3284.638689744126;364532;468324;473886.2611373911;476171;497204.5419318713;62107.378926211066;62366.68816655887;62402;62423.375766241756;62476.68761656163;65538;65552;65561;65563.34436655667;65665.34334656592;16229.756553651468;16773.161341932853;16819.66360672804;16822.915885420494;16826.074565898747;12002.339871307162;12479.438425270884;12733.681657958625;12781.360931953364;12990.480380784662;14750.188739619209;15133.924330378277;16637;17027;17047.48857534276;6760.560563563353;7061.399781018671;7483.8877416838795;7909.644066017043;7949.165337639581;873.1399571422144;882.6160620130264;886.6497733395273;896.6503063805121;919.237033262404;22909.541809164057;23438;29806.403871922874;30334.54498182529;30450.390992180033;1079.0989523747758;1081.7457897394106;1085.10478854945;1106.0432725692378;1190.5356910804794;12637.052221083455;15630.609234769221;15658.175154858893;15677.921610392103;15759.054456732632;597806;600025.9998700066;601982;602315;603452;60745.69627151836;60796.39203607996;60804;60911.47721306914;60917;1446.6094154578204;1472.1167299620142;1485.8217013958395;1496.2817847433075;1503.1432083712398;28379.43241135207;29523.557146642386;29543;30411.695883040888;30470.238244044078;50902.49097508978;51319;51324.74337628288;51389;51476;519293;521817;523337;523987;524345;39574.40638390484;40205;40421;40435.1912961745;40648.79675601603;36558;46670.53329466662;46802.59592212239;48070.75964620154;50042.74893127701;37532.81233593814;38446.038849029;38989.610103898594;40142.99642508961;40242;38627.42058869063;38683.03292417712;38996.6100338993;40245.39631905465;40389.1922161561;37944.810275948446;38519.614803851604;38757.80621096877;38776.2244755109;38796;33632.99101026973;33870.491942620385;34874.47688284627;34980.65019349774;34990.825045874604;22443.663345050165;24705.270631056374;25290.241292761246;25510.234692959242;26108.34729131787 10:44:22 INFO - PROCESS | 1636 | |2;jquery.html;392.55641125528166;393.41970593374776;395.8357281728083;402.5612082829715;422.33271431138803;18754.812451875478;20885.060172292244;21000.36998890034;21168;21181.894090529564;17302.653946921055;21523.784762152372;21621.91890405481;22019.66970495444;22329.55340893181;5964.940350596493;6193.81418557444;6262.9686851565675;6276.37236276371;6283.622982621035;5945.88108237835;6072.332043475214;6199.318075011745;6204.193454850885;6209.875802483948;6269.529785266123;6274.058891166316;6274.811755647338;6279.968600156993;6280.811575652715;6267.43593076623;6355.586886852363;6779.050932869427;6826.112605361272;6854.3145685431555;5357.678539287655;6128.509719222451;6152.784652537184;6156.26124865019;6163.845903852395;13082.411291491906;13818.239996800172;13823.723525529535;13849.307534623329;16627.916860415742;2531.886065127074;2558.692956845172;2584.1213987244428;2667.2798344447083;2670.5994100884827;10124.443155626437;10422.270441069128;10549.683509494727;12887.548935787296;15350.07899526032;1879.6146789908037;1884.547708549938;1887.4054672778032;1892.9621407571772;1904.5143488410506;102167;106303.15332656779;108654;118306;139679;11406.372649504357;11851.585194518322;12162.756744865053;12251.632451026484;12265.693357665952;13014.349282535835;15887.841121588868;15899.28453219608;15901.920490397473;15928.123953182687;8531.53076580794;8765.561721913879;8990.415622984616;9004;9085.636574536944;7659.387249020177;7854.036490877281;8032.919670803217;8039.718609848627;8387.538685372241;4738.597219236334;4784.808607655655;4839.661223714306;4887.437944636412;4907.828226012072;1725.9309627615005;1846.9722954155698;1918.9232430702616;1969.606078784249;2020.1616329223207;1000.4497526360473;1011.7774089700254;1013.1287093099928;1016.7051555049101;1065.8667666541683;19547.902260488605;20986.2654807081;21431.785682142978;21569.352919412442;21586.78413215848;921.5115988526092;953.826793044559;1008.2589296866797;1014.518103900656;1027.1833892055683;10480.842787358195;10816.67549973502;10910.836337454944;10954.561817527368;10971.83542246867;675137;753023.234883833;773136;774517;798588;126184.36907815402;152722;155225;155473;163619;983.4787562591838;993.8161440133598;1021.0504231065032;1022.483645758887;1024.9852645880524;17865.73201401954;18130.546736331697;18461.261549538405;18573.514118870728;18762.812371876105;57132;57239;57957;58659;58780;82129.17870821216;83049;87485;87771.1222887763;89686.10313897039;6062.90905636407;7960.920390796018;8011.519308841488;8541.316694664536;8573.91426085756;9126.954365228132;12028.819567706667;12453.501859925502;12778.552750653682;12879.742405151657;8814.691485797966;10406.531706073094;10669.199810014125;10841.970012848768;10891.509882055485;10385.532651030873;10717.303375280582;10910.94544527304;11271.288388694058;11450.141239407245;11336.433178340885;11381.374024428736;11488.655340339803;11614.941925290657;11752.412379381169;7114.14630244374;8143.429959902987;8150.83698326042;8447.704330348408;8483.957580212307;8475.660973560989;10308.742281443025;10721.195910306915;11136.610218642309;11157.218994670295 10:44:22 INFO - PROCESS | 1636 | |3;mootools.html;1446.0817380963085;1446.1178681004592;1463.9560813175608;1465.6042868425525;1472.7496325624645;8102.0682621498545;8482.660693572258;8484.618192181366;8485.406021578492;8505.319574434046;7497.587632680201;7709.113451953027;7728.96135519323;8095.7166499172545;8303.045149807775;2277.6811246425514;2312.7918487336137;2320.5938960681897;2323.2101085630884;2332.3352844439337;2317.177402022283;2345.0736958901225;2353.1999120299097;2360.6459031145296;2361.657559653849;2308.676785250058;2317.212147869724;2338.462153704649;2344.3318654183563;2346.3195673254736;2283.337832028718;2288.496530763229;2310.387747246964;2313.1672597864754;2315.668490617893;2278.111536500766;2342.906283748648;2346.448584582639;2353.7881590656925;2356.740758516562;8355.707550235713;8421.915780842175;8425.70510032152;8442.324614030862;8450;5079.111155547765;5208.062548741225;5214.348206474191;5227.980543793961;5253.474652534755;3067.2485241115846;3352.8994130176134;3507;3543.2382037861726;3545.3618348697223;1162.67445115367;1164.6331405607207;1212.7392610588765;1215.0886834873845;1228.6068458093414;10882.075023623001;10986.890131098666;11016.394098324588;11017.063549598293;11068.557257709683;4449.354843547732;4635.976820115945;4731.905361892743;4735.715857048588;4736.100140973204;9594.664186753569;9612.855807162898;9617.422954622745;9620.037996200464;9625;5273.736313184326;5289.233061206103;5289.3917199521775;5294.54996325309;5296.814611488579;4201.7268877522865;4525.275955847049;4755.0489902019735;4774.665773395829;4871.123197824355;3034.863431145604;3478.443449048191;3855.363864962306;3933.331333673282;3972.6424621784176;1135.5741596901162;1250.3998080921208;1273.5478904988595;1279.6672865055152;1292.6251387097825;637.6620391192675;771.067008919214;794.5391672829745;797.5812698333422;821.7781199076215;5363.892722145535;5434.510894019478;5472.2338872557875;5599.80400685974;5665.688387138747;566.2610293566852;570.5378643298941;572.2303501790086;590.6574186971546;592.1975722895414;3596.3166998270767;3631.092226943264;3668.944965825515;3711.758735682172;3851.9422208666892;9674.371165874196;10063.446510441996;10169.796604067877;10191.490425478696;10259.58961641526;10128.746781330377;10140.188784897191;10153.94923025395;10165;10169.23730720199;7837;7892.210778922062;7894.64474098667;7932.484388514728;7992.960035199787;5358.196270559447;5408.702521361363;5493.066178749542;5592.217089607377;5692.1461780733225;9181.54092295396;9204.217641500547;9385.38994965325;9393.201577865959;9505.334626576074;7830.765077047698;9218.400803947721;9261.490618016074;9580.23358131334;9583.281253906125;5850.473457388855;6851.760188393382;7070.893936591058;7237.3486386225495;7245.275472452712;7546.434017448603;7712.92287077122;8002.199780022184;8027.5584842834205;8130.552819594979;7150.785476435715;7227.060482137288;7265.891011634724;7309.269073092648;7395.075615548057;6899.89650155238;7038.577685338896;7041.471889608198;7121.9643901780155;7149.964250178716;6342.175517182736;6348.650824204528;6427.807165785034;6435.485161187187;6442.935570644234;4877.1221180186485;4885.633577481632;4923.458419573915;4935.950640493549;4952.455229924633;4892.877678058077;4907.263910413467;4909.4354149273295;4986.202207646759;5066.265391518283 10:44:22 INFO - PROCESS | 1636 | |4;prototype.html;295.4681573168297;359.1147820622166;366.5326708446731;373.2683939478622;376.80971109589655;5498.642588231772;5499.58753093518;5552.084530836865;5554.50009499145;5556.277683901092;4382.934255986155;5116.360454943132;5253.658512196705;5285.709285989269;5484.890302193964;3691.630836916307;4250.788525270299;4277.721948073381;4317.409554267446;4318;4007.639312461878;4021.5777343378963;4266.8293268269235;4333.523312435629;4337.197618440583;3462.4806279058216;3556.8043757593323;4096.754194748325;4197.628339987912;4306.956930430687;3858;3893.3186692329014;4198.215758302739;4222.978885105555;4265.722728022669;3267.8202698851555;3665.3952097903816;3727.1986522897705;3733.234686889208;3751.7936513491745;4051.6353528182312;4668.883277918079;5326.414094449646;5390.407055223922;5474.288342515487;4922.556969872729;4930.827421040282;4933.876653083702;4942;4978.6514943953935;5112.335396398482;5512.779488820443;5797.565182611279;5799.854054000005;5822.912656310159;3672.2471893262086;4032.112935154291;4158.7296825706235;4180.561041090672;4205.789710514462;4962.950370496285;5038.974805125951;5314.335708036534;6020.277566692025;6069.241344831896;3566.5185199998186;3860.613938606173;3957.703172262092;4219.599138081878;4235.53409124996;4272.636825869774;4273.572642735701;4303.354496825501;4324.740515569076;4331.393604895317;4037.6581582681974;4046.7571945683353;4415.735055896656;4716.971702168032;4758.905451746118;4902.730349830794;5826.359100498942;5837.066069428871;5841.465913122584;5852.063669812809;1211.5884993981913;1211.6970757310673;1274.8335273224895;1308.4700696218047;1379.4964837834318;917.6833992272656;940.8823897012874;982.931194816356;990.4206039466992;997.8602995580625;4649.560702875372;5135.584836084037;5146.511081447256;5185.870353241199;5188.740562971836;5096.286519887219;5147.433782283946;5600.991821472093;5670.886582268332;5839.854003649943;4247.638950689165;4367.388565600819;4458.464984201916;4661.394018777605;5100.719460429637;2597.467519158568;2676.8126231163633;2695.636089127982;2932.4868148074;2934.457125431802;5355.4376790436445;5636.464535869086;5714.457126572969;5836.795712150054;5863.5309175265875;5503.293978866564;5740.933264024948;6116.724747386378;6119.5104391648565;6149.692515374213;5231.398389185292;5888.411158884077;5939.287285525765;6152.753889844356;6179.598326108789;5311.379100182866;5882;5951;5966.910496342472;5969.134815288599;5847.883042339045;5868.559858010581;5876.679675983056;5882.205902203319;5889.764409423747;5424.945750542444;5899.941000589939;5947.791827286024;6187.474064704551;6374.4900407966325;3192.968070319267;3941.7240793144206;3961.28696834561;3974.6621537169663;3975.204959008152;5209.869753256199;5920.25352521016;5927.733252003756;5933.757363485129;5936.7922122725495;4970.900581988412;5557.472040156179;5758.2514273144225;5794.275715535558;5795.710214489174;5711.571632127524;5717.828465146053;5740.39725828782;5749.1663708762835;5799.2750906136735;5251.842444726664;5385.6768593882925;5451.20110363587;5536.2249285099315;5537.197106419627;5629.211910332639;5731.484166425042;5755.251817263729;5767.336756273083;5781.4507621775865;4735;5457.972710136424;5619.550435965031;5639.3232812062815;5644.4195625225 10:44:22 INFO - PROCESS | 1636 | |5;yui.html;696.4324075878162;700.3311837195482;742.4320394897902;758.6282721466483;865.8008658008656;14888.404463821447;15699.921500392511;15700.21498925055;15838.049717016973;15898.682026359465;14254;14348.856511434882;14472;15731.92134039331;16689.24898379573;866.5580553917484;890.0520945193348;890.3589415620775;890.9287257019455;891.9866202006975;883.3463237204454;884.5621417398397;884.6992022712277;886.8315020146151;887.0597167002998;924.2421214604027;924.6347692661394;924.856647219682;927.0219917986522;927.5455027036771;920.0155833258416;922.1101636920404;923.3121324613206;923.9722808315761;924.5700749151644;680.8876535371658;683.1187767779608;683.2143035509155;684.490054909091;686.1868685607521;15457.613559660986;15869.841301587066;15872.206389680585;15887.761683574756;15904.681906361808;14073.788893166611;14112.435502579885;14267.286635668175;14268.215248161347;14308.356123974447;9640.807183856283;9946.850797238047;11591.362475063868;11746.530138794438;11885.34630595317;3798.449224862407;3800.4869342638667;3808.5620153682403;3887.552931412867;3912.217556488714;100681;102633.97366026319;105732;105875.94124058737;107774;10241.557374208476;10678.679639610824;10977;11061.446927653746;11169;22243.332700019026;22322.107115715513;22332.441688957904;22352.664710029363;22359.888200559217;7637.121731001006;7712.460127791001;7775.883361749578;7877.606119693993;7940;4221.387898754663;4266.978665106654;4386.152234047275;4398.5821346972625;4496.752678602708;3416.4533674611944;3515.226650136966;3556.626554211822;3568.107973006748;3576.910577235538;641.3842710997401;650.3301599352675;655.3315618069543;666.2837449741451;668.6355936014884;683.8119517132828;703.4229949967862;716.4304378019383;738.6565247160096;738.8102879579917;8106.797330066795;9137.314701397421;9284.11800878929;10322.380657160595;10579.153667706565;677.9186497620218;711.9857602847914;715.9212486626467;722.523134731075;724.6340597997977;4470.843520476833;4632.559906808848;4666.603338716247;4671.48613652498;4680.976595117003;107295.46352268345;107743.46128269308;109436;110129;113003.86996129934;56107.43892561104;60556.69721651363;61816.07275890865;83022;96962.22004675068;81152.18847811446;81762.59118704368;83222.58388708139;84315.57842210872;84730.15269847223;10338.948305258426;10880;11078.833817492905;11177.385243811668;11200.43997800123;51253.231201532755;51309.48690513047;51490.74254628703;52593;52721.7363913178;69310.65344673244;70355.29644703692;71012.64493677499;72528.27471725426;73597.26402735904;11194;12194.51221951112;12507.437165327765;12961.481540738265;13444.924406047689;14177.362018708976;14355.856441435451;14628.853711462749;14901.776473353126;15400;12015.639530814089;12717.745645086861;13318.267495287851;13841.984573691954;14486.637834054232;13073.084884058024;13493.662658433617;13667.589972300846;13817.79273310881;14275.072120312147;11508.539658413905;12810.743785124432;13341.466341346237;13452.865471345161;14047.508337208148;11315.168525372013;11406.657800266004;11630.360330181922;11751.588694395656;12222.388880555758;9764.80291934309;10408.687739367831;10977.454872294402;11068.467610774083;11231 10:44:22 INFO - PROCESS | 1636 | __end_tp_report 10:44:22 INFO - PROCESS | 1636 | __start_cc_report 10:44:22 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1315 10:44:22 INFO - PROCESS | 1636 | __end_cc_report 10:44:22 INFO - PROCESS | 1636 | __startTimestamp1486493062677__endTimestamp 10:44:22 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | ------- Summary: start ------- 10:44:22 INFO - PROCESS | 1636 | Number of tests: 6 10:44:22 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5762.66 Median:5970.76 stddev:649.68 (10.9%) stddev-sans-first:622.64 10:44:22 INFO - PROCESS | 1636 | Values: 3314.5 3331.5 3355.2 3375.0 3400.5 5680.6 5710.1 5727.7 5737.7 5740.9 5709.5 5716.3 5788.6 5793.4 5802.2 5239.6 5291.9 5296.8 5340.9 5342.0 4612.5 4691.7 5327.3 5334.7 5342.7 5229.1 5234.3 5255.3 5271.7 5288.2 5292.8 5310.0 5330.7 5331.1 5340.8 5147.6 5227.3 5274.3 5275.9 5316.7 5599.9 5623.2 5627.4 5662.8 5794.8 5273.9 6240.9 6304.0 6309.2 6324.7 6198.4 6290.1 6292.8 6296.0 6313.1 5778.4 5834.4 5892.6 5937.4 5969.9 6215.6 6246.3 6288.4 6290.5 6306.4 6151.0 6155.1 6157.0 6166.3 6170.1 6252.2 6282.1 6290.9 6298.9 6317.8 6116.6 6126.5 6132.3 6155.1 6223.0 5266.6 6319.4 6319.8 6340.3 6342.9 4296.5 5050.2 5051.0 5069.6 5091.7 4412.6 4421.6 4472.9 4497.3 4521.2 5696.5 5716.3 5724.8 5735.7 5826.9 6188.6 6214.3 6230.9 6277.7 6325.8 5651.4 5718.0 5778.3 5794.4 5804.4 5376.2 5523.5 5536.7 5544.8 5545.3 5259.0 6227.8 6271.3 6300.5 6309.1 6241.7 6332.4 6335.3 6335.6 6351.6 6300.0 6323.4 6326.2 6328.3 6351.3 6047.6 6228.6 6230.0 6234.3 6241.5 6176.1 6235.2 6237.9 6299.8 6336.6 6107.9 6177.2 6194.7 6196.9 6206.1 6151.9 6175.2 6184.5 6188.6 6191.8 5735.0 5816.8 5854.7 5938.3 5970.8 6177.7 6187.7 6199.2 6201.1 6203.9 6015.2 6018.1 6043.5 6048.6 6072.4 10:44:22 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:67207.19 Median:39766.40 stddev:141373.02 (355.5%) stddev-sans-first:141680.71 10:44:22 INFO - PROCESS | 1636 | Values: 433.7 447.8 481.8 487.8 528.5 30560.0 32481.7 39772.2 41571.8 41678.4 43070.9 43365.3 43437.0 43462.0 43521.6 9229.4 9365.7 9460.0 9467.0 9474.4 8451.6 8692.2 8696.3 8761.1 9351.8 8602.3 11183.9 11435.1 11471.3 11667.5 11390.5 11480.7 11481.2 11509.0 11512.4 8385.5 11351.1 11417.6 11423.8 11429.5 32027.7 39760.6 40314.0 40490.4 42688.6 4031.5 4037.1 4047.6 4055.3 4071.5 28846.4 29531.9 30516.7 31226.9 31639.0 3132.1 3179.1 3243.4 3280.8 3284.6 364532.0 468324.0 473886.3 476171.0 497204.5 62107.4 62366.7 62402.0 62423.4 62476.7 65538.0 65552.0 65561.0 65563.3 65665.3 16229.8 16773.2 16819.7 16822.9 16826.1 12002.3 12479.4 12733.7 12781.4 12990.5 14750.2 15133.9 16637.0 17027.0 17047.5 6760.6 7061.4 7483.9 7909.6 7949.2 873.1 882.6 886.6 896.7 919.2 22909.5 23438.0 29806.4 30334.5 30450.4 1079.1 1081.7 1085.1 1106.0 1190.5 12637.1 15630.6 15658.2 15677.9 15759.1 597806.0 600026.0 601982.0 602315.0 603452.0 60745.7 60796.4 60804.0 60911.5 60917.0 1446.6 1472.1 1485.8 1496.3 1503.1 28379.4 29523.6 29543.0 30411.7 30470.2 50902.5 51319.0 51324.7 51389.0 51476.0 519293.0 521817.0 523337.0 523987.0 524345.0 39574.4 40205.0 40421.0 40435.2 40648.8 36558.0 46670.5 46802.6 48070.8 50042.7 37532.8 38446.0 38989.6 40143.0 40242.0 38627.4 38683.0 38996.6 40245.4 40389.2 37944.8 38519.6 38757.8 38776.2 38796.0 33633.0 33870.5 34874.5 34980.7 34990.8 22443.7 24705.3 25290.2 25510.2 26108.3 10:44:22 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:40092.37 Median:21306.84 stddev:125562.59 (589.3%) stddev-sans-first:125879.44 10:44:22 INFO - PROCESS | 1636 | Values: 392.6 393.4 395.8 402.6 422.3 18754.8 20885.1 21000.4 21168.0 21181.9 17302.7 21523.8 21621.9 22019.7 22329.6 5964.9 6193.8 6263.0 6276.4 6283.6 5945.9 6072.3 6199.3 6204.2 6209.9 6269.5 6274.1 6274.8 6280.0 6280.8 6267.4 6355.6 6779.1 6826.1 6854.3 5357.7 6128.5 6152.8 6156.3 6163.8 13082.4 13818.2 13823.7 13849.3 16627.9 2531.9 2558.7 2584.1 2667.3 2670.6 10124.4 10422.3 10549.7 12887.5 15350.1 1879.6 1884.5 1887.4 1893.0 1904.5 102167.0 106303.2 108654.0 118306.0 139679.0 11406.4 11851.6 12162.8 12251.6 12265.7 13014.3 15887.8 15899.3 15901.9 15928.1 8531.5 8765.6 8990.4 9004.0 9085.6 7659.4 7854.0 8032.9 8039.7 8387.5 4738.6 4784.8 4839.7 4887.4 4907.8 1725.9 1847.0 1918.9 1969.6 2020.2 1000.4 1011.8 1013.1 1016.7 1065.9 19547.9 20986.3 21431.8 21569.4 21586.8 921.5 953.8 1008.3 1014.5 1027.2 10480.8 10816.7 10910.8 10954.6 10971.8 675137.0 753023.2 773136.0 774517.0 798588.0 126184.4 152722.0 155225.0 155473.0 163619.0 983.5 993.8 1021.1 1022.5 1025.0 17865.7 18130.5 18461.3 18573.5 18762.8 57132.0 57239.0 57957.0 58659.0 58780.0 82129.2 83049.0 87485.0 87771.1 89686.1 6062.9 7960.9 8011.5 8541.3 8573.9 9127.0 12028.8 12453.5 12778.6 12879.7 8814.7 10406.5 10669.2 10842.0 10891.5 10385.5 10717.3 10910.9 11271.3 11450.1 11336.4 11381.4 11488.7 11614.9 11752.4 7114.1 8143.4 8150.8 8447.7 8484.0 8475.7 10308.7 10721.2 11136.6 11157.2 10:44:22 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5433.31 Median:5211.21 stddev:3007.80 (57.7%) stddev-sans-first:3001.31 10:44:22 INFO - PROCESS | 1636 | Values: 1446.1 1446.1 1464.0 1465.6 1472.7 8102.1 8482.7 8484.6 8485.4 8505.3 7497.6 7709.1 7729.0 8095.7 8303.0 2277.7 2312.8 2320.6 2323.2 2332.3 2317.2 2345.1 2353.2 2360.6 2361.7 2308.7 2317.2 2338.5 2344.3 2346.3 2283.3 2288.5 2310.4 2313.2 2315.7 2278.1 2342.9 2346.4 2353.8 2356.7 8355.7 8421.9 8425.7 8442.3 8450.0 5079.1 5208.1 5214.3 5228.0 5253.5 3067.2 3352.9 3507.0 3543.2 3545.4 1162.7 1164.6 1212.7 1215.1 1228.6 10882.1 10986.9 11016.4 11017.1 11068.6 4449.4 4636.0 4731.9 4735.7 4736.1 9594.7 9612.9 9617.4 9620.0 9625.0 5273.7 5289.2 5289.4 5294.5 5296.8 4201.7 4525.3 4755.0 4774.7 4871.1 3034.9 3478.4 3855.4 3933.3 3972.6 1135.6 1250.4 1273.5 1279.7 1292.6 637.7 771.1 794.5 797.6 821.8 5363.9 5434.5 5472.2 5599.8 5665.7 566.3 570.5 572.2 590.7 592.2 3596.3 3631.1 3668.9 3711.8 3851.9 9674.4 10063.4 10169.8 10191.5 10259.6 10128.7 10140.2 10153.9 10165.0 10169.2 7837.0 7892.2 7894.6 7932.5 7993.0 5358.2 5408.7 5493.1 5592.2 5692.1 9181.5 9204.2 9385.4 9393.2 9505.3 7830.8 9218.4 9261.5 9580.2 9583.3 5850.5 6851.8 7070.9 7237.3 7245.3 7546.4 7712.9 8002.2 8027.6 8130.6 7150.8 7227.1 7265.9 7309.3 7395.1 6899.9 7038.6 7041.5 7122.0 7150.0 6342.2 6348.7 6427.8 6435.5 6442.9 4877.1 4885.6 4923.5 4936.0 4952.5 4892.9 4907.3 4909.4 4986.2 5066.3 10:44:22 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4642.42 Median:5187.31 stddev:1423.39 (27.4%) stddev-sans-first:1389.49 10:44:22 INFO - PROCESS | 1636 | Values: 295.5 359.1 366.5 373.3 376.8 5498.6 5499.6 5552.1 5554.5 5556.3 4382.9 5116.4 5253.7 5285.7 5484.9 3691.6 4250.8 4277.7 4317.4 4318.0 4007.6 4021.6 4266.8 4333.5 4337.2 3462.5 3556.8 4096.8 4197.6 4307.0 3858.0 3893.3 4198.2 4223.0 4265.7 3267.8 3665.4 3727.2 3733.2 3751.8 4051.6 4668.9 5326.4 5390.4 5474.3 4922.6 4930.8 4933.9 4942.0 4978.7 5112.3 5512.8 5797.6 5799.9 5822.9 3672.2 4032.1 4158.7 4180.6 4205.8 4963.0 5039.0 5314.3 6020.3 6069.2 3566.5 3860.6 3957.7 4219.6 4235.5 4272.6 4273.6 4303.4 4324.7 4331.4 4037.7 4046.8 4415.7 4717.0 4758.9 4902.7 5826.4 5837.1 5841.5 5852.1 1211.6 1211.7 1274.8 1308.5 1379.5 917.7 940.9 982.9 990.4 997.9 4649.6 5135.6 5146.5 5185.9 5188.7 5096.3 5147.4 5601.0 5670.9 5839.9 4247.6 4367.4 4458.5 4661.4 5100.7 2597.5 2676.8 2695.6 2932.5 2934.5 5355.4 5636.5 5714.5 5836.8 5863.5 5503.3 5740.9 6116.7 6119.5 6149.7 5231.4 5888.4 5939.3 6152.8 6179.6 5311.4 5882.0 5951.0 5966.9 5969.1 5847.9 5868.6 5876.7 5882.2 5889.8 5424.9 5899.9 5947.8 6187.5 6374.5 3193.0 3941.7 3961.3 3974.7 3975.2 5209.9 5920.3 5927.7 5933.8 5936.8 4970.9 5557.5 5758.3 5794.3 5795.7 5711.6 5717.8 5740.4 5749.2 5799.3 5251.8 5385.7 5451.2 5536.2 5537.2 5629.2 5731.5 5755.3 5767.3 5781.5 4735.0 5458.0 5619.6 5639.3 5644.4 10:44:22 INFO - PROCESS | 1636 | 10:44:22 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20500.59 Median:3804.52 stddev:29443.42 (773.9%) stddev-sans-first:29488.46 10:44:22 INFO - PROCESS | 1636 | Values: 696.4 700.3 742.4 758.6 865.8 14888.4 15699.9 15700.2 15838.0 15898.7 14254.0 14348.9 14472.0 15731.9 16689.2 866.6 890.1 890.4 890.9 892.0 883.3 884.6 884.7 886.8 887.1 924.2 924.6 924.9 927.0 927.5 920.0 922.1 923.3 924.0 924.6 680.9 683.1 683.2 684.5 686.2 15457.6 15869.8 15872.2 15887.8 15904.7 14073.8 14112.4 14267.3 14268.2 14308.4 9640.8 9946.9 11591.4 11746.5 11885.3 3798.4 3800.5 3808.6 3887.6 3912.2 100681.0 102634.0 105732.0 105875.9 107774.0 10241.6 10678.7 10977.0 11061.4 11169.0 22243.3 22322.1 22332.4 22352.7 22359.9 7637.1 7712.5 7775.9 7877.6 7940.0 4221.4 4267.0 4386.2 4398.6 4496.8 3416.5 3515.2 3556.6 3568.1 3576.9 641.4 650.3 655.3 666.3 668.6 683.8 703.4 716.4 738.7 738.8 8106.8 9137.3 9284.1 10322.4 10579.2 677.9 712.0 715.9 722.5 724.6 4470.8 4632.6 4666.6 4671.5 4681.0 107295.5 107743.5 109436.0 110129.0 113003.9 56107.4 60556.7 61816.1 83022.0 96962.2 81152.2 81762.6 83222.6 84315.6 84730.2 10338.9 10880.0 11078.8 11177.4 11200.4 51253.2 51309.5 51490.7 52593.0 52721.7 69310.7 70355.3 71012.6 72528.3 73597.3 11194.0 12194.5 12507.4 12961.5 13444.9 14177.4 14355.9 14628.9 14901.8 15400.0 12015.6 12717.7 13318.3 13842.0 14486.6 13073.1 13493.7 13667.6 13817.8 14275.1 11508.5 12810.7 13341.5 13452.9 14047.5 11315.2 11406.7 11630.4 11751.6 12222.4 9764.8 10408.7 10977.5 11068.5 11231.0 10:44:22 INFO - PROCESS | 1636 | -------- Summary: end -------- 10:44:22 INFO - PROCESS | 1636 | 10:44:23 INFO - TEST-INFO | 1636: exit 0 10:44:23 INFO - TEST-OK | dromaeo_css | took 1118313ms 10:44:23 INFO - TEST-START | kraken 10:44:23 INFO - operating with platform_type : mac_ 10:44:23 INFO - Initialising browser for kraken test... 10:44:23 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmprEtis_/profile http://localhost:49236/getInfo.html) 10:44:25 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 10:44:25 INFO - PROCESS | 1646 | colorDepth:24 10:44:25 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 10:44:25 INFO - PROCESS | 1646 | __metrics 10:44:25 INFO - PROCESS | 1646 | 1486493065673 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 10:44:25 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:44:25 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:44:26 INFO - TEST-INFO | 1646: exit 0 10:44:26 INFO - Browser initialized. 10:44:26 INFO - Running cycle 1/1 for kraken test... 10:44:26 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmprEtis_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:44:38 INFO - PROCESS | 1647 | RSS: Main: 213090304 10:44:38 INFO - PROCESS | 1647 | 10:44:41 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 10:44:42 INFO - PROCESS | 1647 | RSS: Main: 265842688 10:44:42 INFO - PROCESS | 1647 | 10:44:44 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 10:44:45 INFO - PROCESS | 1647 | RSS: Main: 274817024 10:44:45 INFO - PROCESS | 1647 | 10:44:48 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 10:44:49 INFO - PROCESS | 1647 | RSS: Main: 371752960 10:44:49 INFO - PROCESS | 1647 | 10:44:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 10:44:51 INFO - PROCESS | 1647 | RSS: Main: 274825216 10:44:51 INFO - PROCESS | 1647 | 10:44:53 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:44:54 INFO - PROCESS | 1647 | RSS: Main: 277549056 10:44:54 INFO - PROCESS | 1647 | 10:44:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 10:44:57 INFO - PROCESS | 1647 | RSS: Main: 317399040 10:44:57 INFO - PROCESS | 1647 | 10:45:00 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 10:45:01 INFO - PROCESS | 1647 | RSS: Main: 317423616 10:45:01 INFO - PROCESS | 1647 | 10:45:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 10:45:04 INFO - PROCESS | 1647 | RSS: Main: 314621952 10:45:04 INFO - PROCESS | 1647 | 10:45:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:45:06 INFO - PROCESS | 1647 | RSS: Main: 294830080 10:45:06 INFO - PROCESS | 1647 | 10:45:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:45:09 INFO - PROCESS | 1647 | RSS: Main: 295854080 10:45:09 INFO - PROCESS | 1647 | 10:45:11 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:45:12 INFO - PROCESS | 1647 | RSS: Main: 298250240 10:45:12 INFO - PROCESS | 1647 | 10:45:15 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:45:16 INFO - PROCESS | 1647 | RSS: Main: 312623104 10:45:16 INFO - PROCESS | 1647 | 10:45:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:45:19 INFO - PROCESS | 1647 | RSS: Main: 311353344 10:45:19 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 10:45:21 INFO - PROCESS | 1647 | RSS: Main: 356052992 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | __start_tp_report 10:45:21 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 10:45:21 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 10:45:21 INFO - PROCESS | 1647 | |i|pagename|runs| 10:45:21 INFO - PROCESS | 1647 | |0;ai-astar;116;115;114;114;114;118;164;174;114;114 10:45:21 INFO - PROCESS | 1647 | |1;audio-beat-detection;137;112;108;140;112;108;109;110;127;108 10:45:21 INFO - PROCESS | 1647 | |2;audio-dft;182;185;182;184;181;183;181;183;182;184 10:45:21 INFO - PROCESS | 1647 | |3;audio-fft;81;59;58;89;62;60;58;60;82;85 10:45:21 INFO - PROCESS | 1647 | |4;audio-oscillator;87;70;86;70;68;70;83;68;69;81 10:45:21 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;90;88;89;86;89;85;89;86;89;86 10:45:21 INFO - PROCESS | 1647 | |6;imaging-darkroom;94;94;96;95;94;94;95;94;95;94 10:45:21 INFO - PROCESS | 1647 | |7;imaging-desaturate;86;85;87;88;86;85;87;84;86;85 10:45:21 INFO - PROCESS | 1647 | |8;json-parse-financial;56;54;54;55;58;58;58;56;57;57 10:45:21 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;74;75;75;66;70;69;72;70;71;72 10:45:21 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;81;84;83;80;75;97;93;103;83;83 10:45:21 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;137;147;146;152;138;140;141;140;224;202 10:45:21 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;167;163;167;166;166;168;167;168;167;167 10:45:21 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;67;67;67;66;65;65;65;65;65;65 10:45:21 INFO - PROCESS | 1647 | __end_tp_report 10:45:21 INFO - PROCESS | 1647 | __start_cc_report 10:45:21 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1966 10:45:21 INFO - PROCESS | 1647 | __end_cc_report 10:45:21 INFO - PROCESS | 1647 | __startTimestamp1486493121466__endTimestamp 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | ------- Summary: start ------- 10:45:21 INFO - PROCESS | 1647 | Number of tests: 14 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:125.70 Median:115.50 stddev:22.98 (19.9%) stddev-sans-first:24.10 10:45:21 INFO - PROCESS | 1647 | Values: 116.0 115.0 114.0 114.0 114.0 118.0 164.0 174.0 114.0 114.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:112.00 stddev:12.63 (11.3%) stddev-sans-first:11.15 10:45:21 INFO - PROCESS | 1647 | Values: 137.0 112.0 108.0 140.0 112.0 108.0 109.0 110.0 127.0 108.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.34 (0.7%) stddev-sans-first:1.39 10:45:21 INFO - PROCESS | 1647 | Values: 182.0 185.0 182.0 184.0 181.0 183.0 181.0 183.0 182.0 184.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:69.40 Median:71.50 stddev:13.00 (18.2%) stddev-sans-first:13.09 10:45:21 INFO - PROCESS | 1647 | Values: 81.0 59.0 58.0 89.0 62.0 60.0 58.0 60.0 82.0 85.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:75.50 stddev:7.98 (10.6%) stddev-sans-first:7.24 10:45:21 INFO - PROCESS | 1647 | Values: 87.0 70.0 86.0 70.0 68.0 70.0 83.0 68.0 69.0 81.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:89.00 stddev:1.77 (2.0%) stddev-sans-first:1.67 10:45:21 INFO - PROCESS | 1647 | Values: 90.0 88.0 89.0 86.0 89.0 85.0 89.0 86.0 89.0 86.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 10:45:21 INFO - PROCESS | 1647 | Values: 94.0 94.0 96.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:1.20 (1.4%) stddev-sans-first:1.27 10:45:21 INFO - PROCESS | 1647 | Values: 86.0 85.0 87.0 88.0 86.0 85.0 87.0 84.0 86.0 85.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.57 (2.7%) stddev-sans-first:1.66 10:45:21 INFO - PROCESS | 1647 | Values: 56.0 54.0 54.0 55.0 58.0 58.0 58.0 56.0 57.0 57.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:2.84 (3.9%) stddev-sans-first:2.85 10:45:21 INFO - PROCESS | 1647 | Values: 74.0 75.0 75.0 66.0 70.0 69.0 72.0 70.0 71.0 72.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:83.00 stddev:8.64 (10.4%) stddev-sans-first:8.96 10:45:21 INFO - PROCESS | 1647 | Values: 81.0 84.0 83.0 80.0 75.0 97.0 93.0 103.0 83.0 83.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:156.70 Median:146.50 stddev:30.47 (20.8%) stddev-sans-first:31.47 10:45:21 INFO - PROCESS | 1647 | Values: 137.0 147.0 146.0 152.0 138.0 140.0 141.0 140.0 224.0 202.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.00 stddev:1.43 (0.9%) stddev-sans-first:1.51 10:45:21 INFO - PROCESS | 1647 | Values: 167.0 163.0 167.0 166.0 166.0 168.0 167.0 168.0 167.0 167.0 10:45:21 INFO - PROCESS | 1647 | 10:45:21 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:65.50 stddev:0.95 (1.4%) stddev-sans-first:0.88 10:45:21 INFO - PROCESS | 1647 | Values: 67.0 67.0 67.0 66.0 65.0 65.0 65.0 65.0 65.0 65.0 10:45:21 INFO - PROCESS | 1647 | -------- Summary: end -------- 10:45:21 INFO - PROCESS | 1647 | 10:45:22 INFO - TEST-INFO | 1647: exit 0 10:45:22 INFO - TEST-OK | kraken | took 59205ms 10:45:22 INFO - SUITE-END | took 1177s 10:45:22 INFO - Completed test suite (00:19:37) 10:45:22 INFO - javascript benchmark 10:45:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3314.4862546305326, 3331.500274958756, 3355.228297491575, 3375.0, 3400.5409269748593, 5680.57395695323, 5710.114932185513, 5727.684977326246, 5737.655740655564, 5740.885182296346, 5709.486146246837, 5716.314042314928, 5788.5658575606785, 5793.420657934215, 5802.187693722872, 5239.607029472786, 5291.947080529184, 5296.788128474856, 5340.866478338034, 5341.973290133544, 4612.515685852987, 4691.733232027351, 5327.334083239595, 5334.679919204841, 5342.693184027771, 5229.084910140729, 5234.293370395005, 5255.290535777659, 5271.736413179345, 5288.233206185101, 5292.761825717852, 5310.0441920454305, 5330.7334633268565, 5331.067063263933, 5340.813071542516, 5147.613928955306, 5227.320448341729, 5274.340707411574, 5275.894482110375, 5316.680999140064, 5599.8880022399735, 5623.212750214973, 5627.409122042168, 5662.773489060432, 5794.768209271624, 5273.86815329616, 6240.937590624081, 6303.968480157616, 6309.179806625155, 6324.715387807513, 6198.443676503466, 6290.119383286344, 6292.842678933018, 6296.0, 6313.147725057104, 5778.422157784188, 5834.358220595731, 5892.646441213456, 5937.376575459558, 5969.850753731148, 6215.595986260879, 6246.312905580383, 6288.434040936246, 6290.528210384239, 6306.432421082125, 6151.0, 6155.076738489173, 6157.0456579230295, 6166.260048794174, 6170.10533472644, 6252.187215661935, 6282.057691346335, 6290.905636415458, 6298.874022519524, 6317.778877739257, 6116.602420842631, 6126.548209435026, 6132.324915438508, 6155.1075094112, 6223.004319308889, 5266.578673706094, 6319.431251187415, 6319.842003949846, 6340.302566717657, 6342.936570634327, 4296.5273819879785, 5050.217216331474, 5051.015052064812, 5069.5944324454695, 5091.669041512364, 4412.624926881251, 4421.557844215553, 4472.910541789146, 4497.347884556786, 4521.163584736834, 5696.458836410535, 5716.342620598602, 5724.77100915967, 5735.655860648374, 5826.912596311059, 6188.597741146811, 6214.347493513206, 6230.906536401958, 6277.717847297114, 6325.841853953596, 5651.4348565143155, 5717.971410142923, 5778.3354914184565, 5794.449527294901, 5804.390538993345, 5376.193570964387, 5523.530499907552, 5536.667799931936, 5544.750486228129, 5545.334559852763, 5259.027079990214, 6227.844303892439, 6271.278802937538, 6300.527460440577, 6309.1167236586, 6241.687915604292, 6332.366763323631, 6335.303116657256, 6335.588186767845, 6351.618902865843, 6299.96850015747, 6323.399277068671, 6326.240851097898, 6328.335524769833, 6351.2696039956, 6047.637141771508, 6228.5951413158, 6230.00319948806, 6234.314225435289, 6241.469475094668, 6176.135341052166, 6235.158253635701, 6237.937620623736, 6299.811005669837, 6336.556441049082, 6107.938920610737, 6177.227846519185, 6194.690265486617, 6196.907046394398, 6206.131141640084, 6151.876962460815, 6175.166352542349, 6184.505239580732, 6188.597741146811, 6191.81424557264, 5735.0, 5816.796412125725, 5854.677992710442, 5938.317093534126, 5970.76116955317, 6177.6911154443, 6187.690615469119, 6199.225096862892, 6201.10084037821, 6203.875922481435, 6015.180076399042, 6018.127371531016, 6043.516518678583, 6048.637081775108, 6072.423119803611], "value": 5721.375280512957, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.6985794872565, 447.7514979186551, 481.76634332348783, 487.8414515282534, 528.484727390794, 30560.0, 32481.67518324819, 39772.2045559089, 41571.792141039325, 41678.37482437766, 43070.923226919265, 43365.34951975723, 43437.0, 43462.0, 43521.564784352224, 9229.35394522382, 9365.719028429157, 9460.0, 9466.952665236664, 9474.431534107976, 8451.577421128919, 8692.217700406962, 8696.304295656333, 8761.080086590911, 9351.766205844875, 8602.311815054813, 11183.888161118366, 11435.085193184525, 11471.311721296706, 11667.533298668086, 11390.487428065715, 11480.655580332603, 11481.196316257865, 11509.0, 11512.366819824865, 8385.454945428588, 11351.148663850245, 11417.600383986604, 11423.828642570368, 11429.542818287258, 32027.679723202935, 39760.602393975976, 40314.0, 40490.39264411036, 42688.57311426877, 4031.455753473273, 4037.071473561068, 4047.635712785834, 4055.3308704063793, 4071.450354202204, 28846.423071538662, 29531.852340738373, 30516.694833051606, 31226.90705825308, 31639.0, 3132.1386618679817, 3179.0621766578915, 3243.4161850866835, 3280.753943454227, 3284.638689744126, 364532.0, 468324.0, 473886.2611373911, 476171.0, 497204.5419318713, 62107.378926211066, 62366.68816655887, 62402.0, 62423.375766241756, 62476.68761656163, 65538.0, 65552.0, 65561.0, 65563.34436655667, 65665.34334656592, 16229.756553651468, 16773.161341932853, 16819.66360672804, 16822.915885420494, 16826.074565898747, 12002.339871307162, 12479.438425270884, 12733.681657958625, 12781.360931953364, 12990.480380784662, 14750.188739619209, 15133.924330378277, 16637.0, 17027.0, 17047.48857534276, 6760.560563563353, 7061.399781018671, 7483.8877416838795, 7909.644066017043, 7949.165337639581, 873.1399571422144, 882.6160620130264, 886.6497733395273, 896.6503063805121, 919.237033262404, 22909.541809164057, 23438.0, 29806.403871922874, 30334.54498182529, 30450.390992180033, 1079.0989523747758, 1081.7457897394106, 1085.10478854945, 1106.0432725692378, 1190.5356910804794, 12637.052221083455, 15630.609234769221, 15658.175154858893, 15677.921610392103, 15759.054456732632, 597806.0, 600025.9998700066, 601982.0, 602315.0, 603452.0, 60745.69627151836, 60796.39203607996, 60804.0, 60911.47721306914, 60917.0, 1446.6094154578204, 1472.1167299620142, 1485.8217013958395, 1496.2817847433075, 1503.1432083712398, 28379.43241135207, 29523.557146642386, 29543.0, 30411.695883040888, 30470.238244044078, 50902.49097508978, 51319.0, 51324.74337628288, 51389.0, 51476.0, 519293.0, 521817.0, 523337.0, 523987.0, 524345.0, 39574.40638390484, 40205.0, 40421.0, 40435.1912961745, 40648.79675601603, 36558.0, 46670.53329466662, 46802.59592212239, 48070.75964620154, 50042.74893127701, 37532.81233593814, 38446.038849029, 38989.610103898594, 40142.99642508961, 40242.0, 38627.42058869063, 38683.03292417712, 38996.6100338993, 40245.39631905465, 40389.1922161561, 37944.810275948446, 38519.614803851604, 38757.80621096877, 38776.2244755109, 38796.0, 33632.99101026973, 33870.491942620385, 34874.47688284627, 34980.65019349774, 34990.825045874604, 22443.663345050165, 24705.270631056374, 25290.241292761246, 25510.234692959242, 26108.34729131787], "value": 20222.296647137122, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.55641125528166, 393.41970593374776, 395.8357281728083, 402.5612082829715, 422.33271431138803, 18754.812451875478, 20885.060172292244, 21000.36998890034, 21168.0, 21181.894090529564, 17302.653946921055, 21523.784762152372, 21621.91890405481, 22019.66970495444, 22329.55340893181, 5964.940350596493, 6193.81418557444, 6262.9686851565675, 6276.37236276371, 6283.622982621035, 5945.88108237835, 6072.332043475214, 6199.318075011745, 6204.193454850885, 6209.875802483948, 6269.529785266123, 6274.058891166316, 6274.811755647338, 6279.968600156993, 6280.811575652715, 6267.43593076623, 6355.586886852363, 6779.050932869427, 6826.112605361272, 6854.3145685431555, 5357.678539287655, 6128.509719222451, 6152.784652537184, 6156.26124865019, 6163.845903852395, 13082.411291491906, 13818.239996800172, 13823.723525529535, 13849.307534623329, 16627.916860415742, 2531.886065127074, 2558.692956845172, 2584.1213987244428, 2667.2798344447083, 2670.5994100884827, 10124.443155626437, 10422.270441069128, 10549.683509494727, 12887.548935787296, 15350.07899526032, 1879.6146789908037, 1884.547708549938, 1887.4054672778032, 1892.9621407571772, 1904.5143488410506, 102167.0, 106303.15332656779, 108654.0, 118306.0, 139679.0, 11406.372649504357, 11851.585194518322, 12162.756744865053, 12251.632451026484, 12265.693357665952, 13014.349282535835, 15887.841121588868, 15899.28453219608, 15901.920490397473, 15928.123953182687, 8531.53076580794, 8765.561721913879, 8990.415622984616, 9004.0, 9085.636574536944, 7659.387249020177, 7854.036490877281, 8032.919670803217, 8039.718609848627, 8387.538685372241, 4738.597219236334, 4784.808607655655, 4839.661223714306, 4887.437944636412, 4907.828226012072, 1725.9309627615005, 1846.9722954155698, 1918.9232430702616, 1969.606078784249, 2020.1616329223207, 1000.4497526360473, 1011.7774089700254, 1013.1287093099928, 1016.7051555049101, 1065.8667666541683, 19547.902260488605, 20986.2654807081, 21431.785682142978, 21569.352919412442, 21586.78413215848, 921.5115988526092, 953.826793044559, 1008.2589296866797, 1014.518103900656, 1027.1833892055683, 10480.842787358195, 10816.67549973502, 10910.836337454944, 10954.561817527368, 10971.83542246867, 675137.0, 753023.234883833, 773136.0, 774517.0, 798588.0, 126184.36907815402, 152722.0, 155225.0, 155473.0, 163619.0, 983.4787562591838, 993.8161440133598, 1021.0504231065032, 1022.483645758887, 1024.9852645880524, 17865.73201401954, 18130.546736331697, 18461.261549538405, 18573.514118870728, 18762.812371876105, 57132.0, 57239.0, 57957.0, 58659.0, 58780.0, 82129.17870821216, 83049.0, 87485.0, 87771.1222887763, 89686.10313897039, 6062.90905636407, 7960.920390796018, 8011.519308841488, 8541.316694664536, 8573.91426085756, 9126.954365228132, 12028.819567706667, 12453.501859925502, 12778.552750653682, 12879.742405151657, 8814.691485797966, 10406.531706073094, 10669.199810014125, 10841.970012848768, 10891.509882055485, 10385.532651030873, 10717.303375280582, 10910.94544527304, 11271.288388694058, 11450.141239407245, 11336.433178340885, 11381.374024428736, 11488.655340339803, 11614.941925290657, 11752.412379381169, 7114.14630244374, 8143.429959902987, 8150.83698326042, 8447.704330348408, 8483.957580212307, 8475.660973560989, 10308.742281443025, 10721.195910306915, 11136.610218642309, 11157.218994670295], "value": 9784.589223579043, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.0817380963085, 1446.1178681004592, 1463.9560813175608, 1465.6042868425525, 1472.7496325624645, 8102.0682621498545, 8482.660693572258, 8484.618192181366, 8485.406021578492, 8505.319574434046, 7497.587632680201, 7709.113451953027, 7728.96135519323, 8095.7166499172545, 8303.045149807775, 2277.6811246425514, 2312.7918487336137, 2320.5938960681897, 2323.2101085630884, 2332.3352844439337, 2317.177402022283, 2345.0736958901225, 2353.1999120299097, 2360.6459031145296, 2361.657559653849, 2308.676785250058, 2317.212147869724, 2338.462153704649, 2344.3318654183563, 2346.3195673254736, 2283.337832028718, 2288.496530763229, 2310.387747246964, 2313.1672597864754, 2315.668490617893, 2278.111536500766, 2342.906283748648, 2346.448584582639, 2353.7881590656925, 2356.740758516562, 8355.707550235713, 8421.915780842175, 8425.70510032152, 8442.324614030862, 8450.0, 5079.111155547765, 5208.062548741225, 5214.348206474191, 5227.980543793961, 5253.474652534755, 3067.2485241115846, 3352.8994130176134, 3507.0, 3543.2382037861726, 3545.3618348697223, 1162.67445115367, 1164.6331405607207, 1212.7392610588765, 1215.0886834873845, 1228.6068458093414, 10882.075023623001, 10986.890131098666, 11016.394098324588, 11017.063549598293, 11068.557257709683, 4449.354843547732, 4635.976820115945, 4731.905361892743, 4735.715857048588, 4736.100140973204, 9594.664186753569, 9612.855807162898, 9617.422954622745, 9620.037996200464, 9625.0, 5273.736313184326, 5289.233061206103, 5289.3917199521775, 5294.54996325309, 5296.814611488579, 4201.7268877522865, 4525.275955847049, 4755.0489902019735, 4774.665773395829, 4871.123197824355, 3034.863431145604, 3478.443449048191, 3855.363864962306, 3933.331333673282, 3972.6424621784176, 1135.5741596901162, 1250.3998080921208, 1273.5478904988595, 1279.6672865055152, 1292.6251387097825, 637.6620391192675, 771.067008919214, 794.5391672829745, 797.5812698333422, 821.7781199076215, 5363.892722145535, 5434.510894019478, 5472.2338872557875, 5599.80400685974, 5665.688387138747, 566.2610293566852, 570.5378643298941, 572.2303501790086, 590.6574186971546, 592.1975722895414, 3596.3166998270767, 3631.092226943264, 3668.944965825515, 3711.758735682172, 3851.9422208666892, 9674.371165874196, 10063.446510441996, 10169.796604067877, 10191.490425478696, 10259.58961641526, 10128.746781330377, 10140.188784897191, 10153.94923025395, 10165.0, 10169.23730720199, 7837.0, 7892.210778922062, 7894.64474098667, 7932.484388514728, 7992.960035199787, 5358.196270559447, 5408.702521361363, 5493.066178749542, 5592.217089607377, 5692.1461780733225, 9181.54092295396, 9204.217641500547, 9385.38994965325, 9393.201577865959, 9505.334626576074, 7830.765077047698, 9218.400803947721, 9261.490618016074, 9580.23358131334, 9583.281253906125, 5850.473457388855, 6851.760188393382, 7070.893936591058, 7237.3486386225495, 7245.275472452712, 7546.434017448603, 7712.92287077122, 8002.199780022184, 8027.5584842834205, 8130.552819594979, 7150.785476435715, 7227.060482137288, 7265.891011634724, 7309.269073092648, 7395.075615548057, 6899.89650155238, 7038.577685338896, 7041.471889608198, 7121.9643901780155, 7149.964250178716, 6342.175517182736, 6348.650824204528, 6427.807165785034, 6435.485161187187, 6442.935570644234, 4877.1221180186485, 4885.633577481632, 4923.458419573915, 4935.950640493549, 4952.455229924633, 4892.877678058077, 4907.263910413467, 4909.4354149273295, 4986.202207646759, 5066.265391518283], "value": 4347.201298464655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.4681573168297, 359.1147820622166, 366.5326708446731, 373.2683939478622, 376.80971109589655, 5498.642588231772, 5499.58753093518, 5552.084530836865, 5554.50009499145, 5556.277683901092, 4382.934255986155, 5116.360454943132, 5253.658512196705, 5285.709285989269, 5484.890302193964, 3691.630836916307, 4250.788525270299, 4277.721948073381, 4317.409554267446, 4318.0, 4007.639312461878, 4021.5777343378963, 4266.8293268269235, 4333.523312435629, 4337.197618440583, 3462.4806279058216, 3556.8043757593323, 4096.754194748325, 4197.628339987912, 4306.956930430687, 3858.0, 3893.3186692329014, 4198.215758302739, 4222.978885105555, 4265.722728022669, 3267.8202698851555, 3665.3952097903816, 3727.1986522897705, 3733.234686889208, 3751.7936513491745, 4051.6353528182312, 4668.883277918079, 5326.414094449646, 5390.407055223922, 5474.288342515487, 4922.556969872729, 4930.827421040282, 4933.876653083702, 4942.0, 4978.6514943953935, 5112.335396398482, 5512.779488820443, 5797.565182611279, 5799.854054000005, 5822.912656310159, 3672.2471893262086, 4032.112935154291, 4158.7296825706235, 4180.561041090672, 4205.789710514462, 4962.950370496285, 5038.974805125951, 5314.335708036534, 6020.277566692025, 6069.241344831896, 3566.5185199998186, 3860.613938606173, 3957.703172262092, 4219.599138081878, 4235.53409124996, 4272.636825869774, 4273.572642735701, 4303.354496825501, 4324.740515569076, 4331.393604895317, 4037.6581582681974, 4046.7571945683353, 4415.735055896656, 4716.971702168032, 4758.905451746118, 4902.730349830794, 5826.359100498942, 5837.066069428871, 5841.465913122584, 5852.063669812809, 1211.5884993981913, 1211.6970757310673, 1274.8335273224895, 1308.4700696218047, 1379.4964837834318, 917.6833992272656, 940.8823897012874, 982.931194816356, 990.4206039466992, 997.8602995580625, 4649.560702875372, 5135.584836084037, 5146.511081447256, 5185.870353241199, 5188.740562971836, 5096.286519887219, 5147.433782283946, 5600.991821472093, 5670.886582268332, 5839.854003649943, 4247.638950689165, 4367.388565600819, 4458.464984201916, 4661.394018777605, 5100.719460429637, 2597.467519158568, 2676.8126231163633, 2695.636089127982, 2932.4868148074, 2934.457125431802, 5355.4376790436445, 5636.464535869086, 5714.457126572969, 5836.795712150054, 5863.5309175265875, 5503.293978866564, 5740.933264024948, 6116.724747386378, 6119.5104391648565, 6149.692515374213, 5231.398389185292, 5888.411158884077, 5939.287285525765, 6152.753889844356, 6179.598326108789, 5311.379100182866, 5882.0, 5951.0, 5966.910496342472, 5969.134815288599, 5847.883042339045, 5868.559858010581, 5876.679675983056, 5882.205902203319, 5889.764409423747, 5424.945750542444, 5899.941000589939, 5947.791827286024, 6187.474064704551, 6374.4900407966325, 3192.968070319267, 3941.7240793144206, 3961.28696834561, 3974.6621537169663, 3975.204959008152, 5209.869753256199, 5920.25352521016, 5927.733252003756, 5933.757363485129, 5936.7922122725495, 4970.900581988412, 5557.472040156179, 5758.2514273144225, 5794.275715535558, 5795.710214489174, 5711.571632127524, 5717.828465146053, 5740.39725828782, 5749.1663708762835, 5799.2750906136735, 5251.842444726664, 5385.6768593882925, 5451.20110363587, 5536.2249285099315, 5537.197106419627, 5629.211910332639, 5731.484166425042, 5755.251817263729, 5767.336756273083, 5781.4507621775865, 4735.0, 5457.972710136424, 5619.550435965031, 5639.3232812062815, 5644.4195625225], "value": 4200.818713686448, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.4324075878162, 700.3311837195482, 742.4320394897902, 758.6282721466483, 865.8008658008656, 14888.404463821447, 15699.921500392511, 15700.21498925055, 15838.049717016973, 15898.682026359465, 14254.0, 14348.856511434882, 14472.0, 15731.92134039331, 16689.24898379573, 866.5580553917484, 890.0520945193348, 890.3589415620775, 890.9287257019455, 891.9866202006975, 883.3463237204454, 884.5621417398397, 884.6992022712277, 886.8315020146151, 887.0597167002998, 924.2421214604027, 924.6347692661394, 924.856647219682, 927.0219917986522, 927.5455027036771, 920.0155833258416, 922.1101636920404, 923.3121324613206, 923.9722808315761, 924.5700749151644, 680.8876535371658, 683.1187767779608, 683.2143035509155, 684.490054909091, 686.1868685607521, 15457.613559660986, 15869.841301587066, 15872.206389680585, 15887.761683574756, 15904.681906361808, 14073.788893166611, 14112.435502579885, 14267.286635668175, 14268.215248161347, 14308.356123974447, 9640.807183856283, 9946.850797238047, 11591.362475063868, 11746.530138794438, 11885.34630595317, 3798.449224862407, 3800.4869342638667, 3808.5620153682403, 3887.552931412867, 3912.217556488714, 100681.0, 102633.97366026319, 105732.0, 105875.94124058737, 107774.0, 10241.557374208476, 10678.679639610824, 10977.0, 11061.446927653746, 11169.0, 22243.332700019026, 22322.107115715513, 22332.441688957904, 22352.664710029363, 22359.888200559217, 7637.121731001006, 7712.460127791001, 7775.883361749578, 7877.606119693993, 7940.0, 4221.387898754663, 4266.978665106654, 4386.152234047275, 4398.5821346972625, 4496.752678602708, 3416.4533674611944, 3515.226650136966, 3556.626554211822, 3568.107973006748, 3576.910577235538, 641.3842710997401, 650.3301599352675, 655.3315618069543, 666.2837449741451, 668.6355936014884, 683.8119517132828, 703.4229949967862, 716.4304378019383, 738.6565247160096, 738.8102879579917, 8106.797330066795, 9137.314701397421, 9284.11800878929, 10322.380657160595, 10579.153667706565, 677.9186497620218, 711.9857602847914, 715.9212486626467, 722.523134731075, 724.6340597997977, 4470.843520476833, 4632.559906808848, 4666.603338716247, 4671.48613652498, 4680.976595117003, 107295.46352268345, 107743.46128269308, 109436.0, 110129.0, 113003.86996129934, 56107.43892561104, 60556.69721651363, 61816.07275890865, 83022.0, 96962.22004675068, 81152.18847811446, 81762.59118704368, 83222.58388708139, 84315.57842210872, 84730.15269847223, 10338.948305258426, 10880.0, 11078.833817492905, 11177.385243811668, 11200.43997800123, 51253.231201532755, 51309.48690513047, 51490.74254628703, 52593.0, 52721.7363913178, 69310.65344673244, 70355.29644703692, 71012.64493677499, 72528.27471725426, 73597.26402735904, 11194.0, 12194.51221951112, 12507.437165327765, 12961.481540738265, 13444.924406047689, 14177.362018708976, 14355.856441435451, 14628.853711462749, 14901.776473353126, 15400.0, 12015.639530814089, 12717.745645086861, 13318.267495287851, 13841.984573691954, 14486.637834054232, 13073.084884058024, 13493.662658433617, 13667.589972300846, 13817.79273310881, 14275.072120312147, 11508.539658413905, 12810.743785124432, 13341.466341346237, 13452.865471345161, 14047.508337208148, 11315.168525372013, 11406.657800266004, 11630.360330181922, 11751.588694395656, 12222.388880555758, 9764.80291934309, 10408.687739367831, 10977.454872294402, 11068.467610774083, 11231.0], "value": 7590.171531759757, "unit": "score"}], "name": "dromaeo_css", "value": 7344.338894708412, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 114.0, 114.0, 114.0, 118.0, 164.0, 174.0, 114.0, 114.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 112.0, 108.0, 140.0, 112.0, 108.0, 109.0, 110.0, 127.0, 108.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 182.0, 184.0, 181.0, 183.0, 181.0, 183.0, 182.0, 184.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 89.0, 62.0, 60.0, 58.0, 60.0, 82.0, 85.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 86.0, 70.0, 68.0, 70.0, 83.0, 68.0, 69.0, 81.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 86.0, 89.0, 85.0, 89.0, 86.0, 89.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 88.0, 86.0, 85.0, 87.0, 84.0, 86.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 55.0, 58.0, 58.0, 58.0, 56.0, 57.0, 57.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 66.0, 70.0, 69.0, 72.0, 70.0, 71.0, 72.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 83.0, 80.0, 75.0, 97.0, 93.0, 103.0, 83.0, 83.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 147.0, 146.0, 152.0, 138.0, 140.0, 141.0, 140.0, 224.0, 202.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 163.0, 167.0, 166.0, 166.0, 168.0, 167.0, 168.0, 167.0, 167.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1441.1, "alertThreshold": 2.0}]} 10:45:22 INFO - Return code: 0 10:45:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:45:22 ERROR - # TBPL SUCCESS # 10:45:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:45:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:45:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:45:22 INFO - 2017-02-07 10:45:22.811 screenresolution[1651:9705] starting screenresolution argv=screenresolution get 10:45:22 INFO - 2017-02-07 10:45:22.813 screenresolution[1651:9705] Display 0: 1600x1200x32@60 10:45:22 INFO - 2017-02-07 10:45:22.819 screenresolution[1652:9708] starting screenresolution argv=screenresolution list 10:45:22 INFO - Available Modes on Display 0 10:45:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:45:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:45:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:45:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:45:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:45:23 INFO - Intel Iris: 10:45:23 INFO - Chipset Model: Intel Iris 10:45:23 INFO - Type: GPU 10:45:23 INFO - Bus: Built-In 10:45:23 INFO - VRAM (Dynamic, Max): 1536 MB 10:45:23 INFO - Vendor: Intel (0x8086) 10:45:23 INFO - Device ID: 0x0a2e 10:45:23 INFO - Revision ID: 0x0009 10:45:23 INFO - Displays: 10:45:23 INFO - Display: 10:45:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:45:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:45:23 INFO - Display Serial Number: 1600x1200 60 10:45:23 INFO - Main Display: Yes 10:45:23 INFO - Mirror: Off 10:45:23 INFO - Online: Yes 10:45:23 INFO - Rotation: Supported 10:45:23 INFO - Return code: 0 10:45:23 INFO - Running post-action listener: _resource_record_post_action 10:45:23 INFO - [mozharness: 2017-02-07 18:45:23.061902Z] Finished run-tests step (success) 10:45:23 INFO - Running post-run listener: _resource_record_post_run 10:45:23 INFO - Total resource usage - Wall time: 1198s; CPU: 53.0%; Read bytes: 37151744; Write bytes: 508637696; Read time: 236; Write time: 1605 10:45:23 INFO - TinderboxPrint: CPU usage
53.4% 10:45:23 INFO - TinderboxPrint: I/O read bytes / time
37,151,744 / 236 10:45:23 INFO - TinderboxPrint: I/O write bytes / time
508,637,696 / 1,605 10:45:23 INFO - TinderboxPrint: CPU idle
2,231.8 (46.6%) 10:45:23 INFO - TinderboxPrint: CPU system
958.2 (20.0%) 10:45:23 INFO - TinderboxPrint: CPU user
1,603.6 (33.5%) 10:45:23 INFO - TinderboxPrint: Swap in / out
244,506,624 / 0 10:45:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203264512; Write bytes: 201295872; Read time: 15646; Write time: 454 10:45:23 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35173376; Write bytes: 296589824; Read time: 218; Write time: 1115 10:45:23 INFO - Running post-run listener: _upload_blobber_files 10:45:23 INFO - Blob upload gear active. 10:45:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:45:23 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 10:45:23 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'] 10:45:23 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 10:45:23 INFO - (blobuploader) - INFO - Open directory for files ... 10:45:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:45:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:45:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:25 INFO - (blobuploader) - INFO - Done attempting. 10:45:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:45:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:45:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:26 INFO - (blobuploader) - INFO - Done attempting. 10:45:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:45:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:45:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:27 INFO - (blobuploader) - INFO - Done attempting. 10:45:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:45:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:45:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:29 INFO - (blobuploader) - INFO - Done attempting. 10:45:29 INFO - (blobuploader) - INFO - Iteration through files over. 10:45:29 INFO - Return code: 0 10:45:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:45:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:45:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3013514d31b48bc0f1f950e1e6fe66a638e80b7f95e7cfc47ff71f4f10aa50acf710ed1ecea0471eba08879c08681128e0ee719433893a57f3408fbde77048f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3122f085875c2ac26aa9162fb396290d5c6bc0a71d2f2a2a23d713ba0807424b523c0466ad0f20bc7db108ffa0e646acd064e7788aa6e3e7416fab9a7b20e1f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45edae817135a27860ea4294247fe80a30b616b23abbab22d223ff2aec257d296fb632b2a948957da276f859175c44d4607c2d0e8b65677528ecb2c2ecd77061", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574c52224e2d2214150cfb4bf2f0b8513f0132a79b5b227d83dcdddedbb06c320a2879fb7f2e2e3f6f03436559d35d85c2157847f303d201a73f439399d03414"} 10:45:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:45:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:45:29 INFO - Contents: 10:45:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3013514d31b48bc0f1f950e1e6fe66a638e80b7f95e7cfc47ff71f4f10aa50acf710ed1ecea0471eba08879c08681128e0ee719433893a57f3408fbde77048f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3122f085875c2ac26aa9162fb396290d5c6bc0a71d2f2a2a23d713ba0807424b523c0466ad0f20bc7db108ffa0e646acd064e7788aa6e3e7416fab9a7b20e1f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45edae817135a27860ea4294247fe80a30b616b23abbab22d223ff2aec257d296fb632b2a948957da276f859175c44d4607c2d0e8b65677528ecb2c2ecd77061", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574c52224e2d2214150cfb4bf2f0b8513f0132a79b5b227d83dcdddedbb06c320a2879fb7f2e2e3f6f03436559d35d85c2157847f303d201a73f439399d03414"} 10:45:29 INFO - Running post-run listener: copy_logs_to_upload_dir 10:45:29 INFO - Copying logs to upload dir... 10:45:29 INFO - mkdir: /builds/slave/test/build/upload/logs 10:45:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.096938 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-07 10:45:29.574700) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 10:45:29.578235) ========= 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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3013514d31b48bc0f1f950e1e6fe66a638e80b7f95e7cfc47ff71f4f10aa50acf710ed1ecea0471eba08879c08681128e0ee719433893a57f3408fbde77048f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3122f085875c2ac26aa9162fb396290d5c6bc0a71d2f2a2a23d713ba0807424b523c0466ad0f20bc7db108ffa0e646acd064e7788aa6e3e7416fab9a7b20e1f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45edae817135a27860ea4294247fe80a30b616b23abbab22d223ff2aec257d296fb632b2a948957da276f859175c44d4607c2d0e8b65677528ecb2c2ecd77061", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574c52224e2d2214150cfb4bf2f0b8513f0132a79b5b227d83dcdddedbb06c320a2879fb7f2e2e3f6f03436559d35d85c2157847f303d201a73f439399d03414"} build_url:https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010958 build_url: 'https://queue.taskcluster.net/v1/task/NOERDN4qRdCid62JaRBvEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3013514d31b48bc0f1f950e1e6fe66a638e80b7f95e7cfc47ff71f4f10aa50acf710ed1ecea0471eba08879c08681128e0ee719433893a57f3408fbde77048f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3122f085875c2ac26aa9162fb396290d5c6bc0a71d2f2a2a23d713ba0807424b523c0466ad0f20bc7db108ffa0e646acd064e7788aa6e3e7416fab9a7b20e1f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45edae817135a27860ea4294247fe80a30b616b23abbab22d223ff2aec257d296fb632b2a948957da276f859175c44d4607c2d0e8b65677528ecb2c2ecd77061", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574c52224e2d2214150cfb4bf2f0b8513f0132a79b5b227d83dcdddedbb06c320a2879fb7f2e2e3f6f03436559d35d85c2157847f303d201a73f439399d03414"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 10:45:29.612193) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 10:45:29.612523) ========= 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.w2CT6Dz3vV/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.NJgLO5ElPg/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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.005048 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 10:45:29.665655) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 10:45:29.666434) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 10:45:29.667129) ========= ========= Total master_lag: 0.18 =========