builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0367 starttime: 1487612033.72 results: success (0) buildid: 20170220075356 builduid: fae64d779cf54cc6a6f1b5a6a4845479 revision: d57841320b5495f190734e16e3f5eb25a008379a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:53.716338) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:53.716812) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:53.717089) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:53.744704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:53.745140) ========= 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.a7wSQhjXHz/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.vedm70HLhn/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-20 09:33:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.1M=0.001s 2017-02-20 09:33:53 (17.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083497 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:53.853891) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:53.854209) ========= 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.a7wSQhjXHz/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.vedm70HLhn/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.039685 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:53.920322) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 09:33:53.920673) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d57841320b5495f190734e16e3f5eb25a008379a --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 d57841320b5495f190734e16e3f5eb25a008379a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.a7wSQhjXHz/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.vedm70HLhn/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-20 09:33:54,048 truncating revision to first 12 chars 2017-02-20 09:33:54,048 Setting DEBUG logging. 2017-02-20 09:33:54,048 attempt 1/10 2017-02-20 09:33:54,048 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d57841320b54?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 09:33:55,673 unpacking tar archive at: mozilla-aurora-d57841320b54/testing/mozharness/ program finished with exit code 0 elapsedTime=1.922256 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 09:33:55.874250) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:55.874687) ========= script_repo_revision: d57841320b5495f190734e16e3f5eb25a008379a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:55.875164) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:55.875444) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 09:33:55.896116) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-20 09:33:55.896501) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.a7wSQhjXHz/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.vedm70HLhn/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:33:56 INFO - MultiFileLogger online at 20170220 09:33:56 in /builds/slave/test 09:33:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:33:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:56 INFO - {'append_to_log': False, 09:33:56 INFO - 'base_work_dir': '/builds/slave/test', 09:33:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:33:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:56 INFO - 'branch': 'Mozilla-Aurora', 09:33:56 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:56 INFO - 'config_files': ('talos/mac_config.py',), 09:33:56 INFO - 'default_actions': ('clobber', 09:33:56 INFO - 'read-buildbot-config', 09:33:56 INFO - 'download-and-extract', 09:33:56 INFO - 'populate-webroot', 09:33:56 INFO - 'create-virtualenv', 09:33:56 INFO - 'install', 09:33:56 INFO - 'run-tests'), 09:33:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:56 INFO - 'download_minidump_stackwalk': True, 09:33:56 INFO - 'download_symbols': 'ondemand', 09:33:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:56 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:56 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:56 INFO - 'installer_path': 'installer.exe', 09:33:56 INFO - 'log_level': 'info', 09:33:56 INFO - 'log_name': 'talos', 09:33:56 INFO - 'log_to_console': True, 09:33:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:33:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:33:56 INFO - 'opt_config_files': (), 09:33:56 INFO - 'pip_index': False, 09:33:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:33:56 INFO - 'cmd': ('bash', 09:33:56 INFO - '-c', 09:33:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:33:56 INFO - 'enabled': True, 09:33:56 INFO - 'halt_on_failure': False, 09:33:56 INFO - 'name': 'check_screen_resolution'},), 09:33:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:33:56 INFO - 'cmd': ('bash', 09:33:56 INFO - '-c', 09:33:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:33:56 INFO - 'enabled': True, 09:33:56 INFO - 'halt_on_failure': False, 09:33:56 INFO - 'name': 'check_screen_resolution'},), 09:33:56 INFO - 'run_cmd_checks_enabled': True, 09:33:56 INFO - 'sps_profile': False, 09:33:56 INFO - 'sps_profile_interval': 0, 09:33:56 INFO - 'suite': 'dromaeojs-e10s', 09:33:56 INFO - 'system_bits': '32', 09:33:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:56 INFO - 'title': 't-yosemite-r7-0367', 09:33:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:56 INFO - 'use_talos_json': True, 09:33:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:56 INFO - 'work_dir': 'build'} 09:33:56 INFO - [mozharness: 2017-02-20 17:33:56.110158Z] Running clobber step. 09:33:56 INFO - Running pre-action listener: _resource_record_pre_action 09:33:56 INFO - Running main action method: clobber 09:33:56 INFO - rmtree: /builds/slave/test/build 09:33:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:58 INFO - Running post-action listener: _resource_record_post_action 09:33:58 INFO - [mozharness: 2017-02-20 17:33:58.585015Z] Finished clobber step (success) 09:33:58 INFO - [mozharness: 2017-02-20 17:33:58.585187Z] Running read-buildbot-config step. 09:33:58 INFO - Running pre-action listener: _resource_record_pre_action 09:33:58 INFO - Running main action method: read_buildbot_config 09:33:58 INFO - Using buildbot properties: 09:33:58 INFO - { 09:33:58 INFO - "project": "", 09:33:58 INFO - "product": "firefox", 09:33:58 INFO - "script_repo_revision": "production", 09:33:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:33:58 INFO - "repository": "", 09:33:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:33:58 INFO - "buildid": "20170220075356", 09:33:58 INFO - "pgo_build": "False", 09:33:58 INFO - "basedir": "/builds/slave/test", 09:33:58 INFO - "buildnumber": 89, 09:33:58 INFO - "slavename": "t-yosemite-r7-0367", 09:33:58 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:33:58 INFO - "platform": "yosemite_r7", 09:33:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:33:58 INFO - "branch": "mozilla-aurora", 09:33:58 INFO - "repo_path": "releases/mozilla-aurora", 09:33:58 INFO - "revision": "d57841320b5495f190734e16e3f5eb25a008379a", 09:33:58 INFO - "stage_platform": "macosx64", 09:33:58 INFO - "builduid": "fae64d779cf54cc6a6f1b5a6a4845479", 09:33:58 INFO - "slavebuilddir": "test" 09:33:58 INFO - } 09:33:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:33:58 INFO - Running post-action listener: _resource_record_post_action 09:33:58 INFO - [mozharness: 2017-02-20 17:33:58.588538Z] Finished read-buildbot-config step (success) 09:33:58 INFO - [mozharness: 2017-02-20 17:33:58.588672Z] Running download-and-extract step. 09:33:58 INFO - Running pre-action listener: _resource_record_pre_action 09:33:58 INFO - Running main action method: download_and_extract 09:33:58 INFO - mkdir: /builds/slave/test/build/tests 09:33:58 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')]} 09:33:58 INFO - trying https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:33:58 INFO - Downloading https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/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 09:33:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/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 09:33:59 INFO - Downloaded 1199 bytes. 09:33:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:33:59 INFO - Using the following test package requirements: 09:33:59 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:33:59 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:33:59 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:33:59 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:33:59 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:33:59 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:33:59 INFO - u'jsshell-mac.zip'], 09:33:59 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:33:59 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:33:59 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:33:59 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:33:59 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:33:59 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:33:59 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:33:59 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:33:59 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:33:59 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:33:59 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:33:59 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:33:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:33:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:33:59 INFO - Fetch https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:34:00 INFO - Expected file size: 16359151 09:34:00 INFO - Obtained file size: 16359151 09:34:07 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 09:34:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:34:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:34:07 INFO - Fetch https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:34:08 INFO - Expected file size: 16359151 09:34:08 INFO - Obtained file size: 16359151 09:34:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:34:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:34:15 INFO - Fetch https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:34:16 INFO - Expected file size: 14403485 09:34:16 INFO - Obtained file size: 14403485 09:34: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')]} 09:34:16 INFO - trying https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:34:16 INFO - Downloading https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/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 09:34:18 INFO - Downloaded 73963666 bytes. 09:34:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:34:18 INFO - mkdir: /builds/slave/test/properties 09:34:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:34:18 INFO - Writing to file /builds/slave/test/properties/build_url 09:34:18 INFO - Contents: 09:34:18 INFO - build_url:https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:34:19 INFO - Running post-action listener: _resource_record_post_action 09:34:19 INFO - Running post-action listener: set_extra_try_arguments 09:34:19 INFO - [mozharness: 2017-02-20 17:34:19.385862Z] Finished download-and-extract step (success) 09:34:19 INFO - [mozharness: 2017-02-20 17:34:19.385989Z] Running populate-webroot step. 09:34:19 INFO - Running pre-action listener: _resource_record_pre_action 09:34:19 INFO - Running main action method: populate_webroot 09:34:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:34:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:34:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:34:19 INFO - '--tppagecycles', 09:34:19 INFO - '7'], 09:34:19 INFO - 'tests': ['tsvgm']}}, 09:34:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:34:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:34:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:34:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:34:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:34:19 INFO - 'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:34:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:34:19 INFO - 'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['damp', 'tps']}, 09:34:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:34:19 INFO - 'tests': ['damp', 'tps']}, 09:34:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['dromaeo_dom']}, 09:34:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:34:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['basic_compositor_video']}, 09:34:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:34:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['a11yr', 09:34:19 INFO - 'ts_paint', 09:34:19 INFO - 'tpaint', 09:34:19 INFO - 'sessionrestore', 09:34:19 INFO - 'sessionrestore_no_auto_restore', 09:34:19 INFO - 'tabpaint']}, 09:34:19 INFO - 'other-e10s': {'tests': ['a11yr', 09:34:19 INFO - 'ts_paint', 09:34:19 INFO - 'tpaint', 09:34:19 INFO - 'sessionrestore', 09:34:19 INFO - 'sessionrestore_no_auto_restore', 09:34:19 INFO - 'tabpaint']}, 09:34:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:34:19 INFO - 'ts_paint', 09:34:19 INFO - 'tpaint', 09:34:19 INFO - 'sessionrestore', 09:34:19 INFO - 'sessionrestore_no_auto_restore', 09:34:19 INFO - 'tabpaint']}, 09:34:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:34:19 INFO - 'ts_paint', 09:34:19 INFO - 'tpaint', 09:34:19 INFO - 'sessionrestore', 09:34:19 INFO - 'sessionrestore_no_auto_restore', 09:34:19 INFO - 'tabpaint']}, 09:34:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['a11yr', 09:34:19 INFO - 'ts_paint', 09:34:19 INFO - 'tpaint', 09:34:19 INFO - 'sessionrestore', 09:34:19 INFO - 'sessionrestore_no_auto_restore', 09:34:19 INFO - 'tabpaint']}, 09:34:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['a11yr', 09:34:19 INFO - 'ts_paint', 09:34:19 INFO - 'tpaint', 09:34:19 INFO - 'sessionrestore', 09:34:19 INFO - 'sessionrestore_no_auto_restore', 09:34:19 INFO - 'tabpaint']}, 09:34:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['tsvgx', 09:34:19 INFO - 'tsvgr_opacity', 09:34:19 INFO - 'tart', 09:34:19 INFO - 'tscrollx', 09:34:19 INFO - 'cart', 09:34:19 INFO - 'tsvg_static']}, 09:34:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:34:19 INFO - 'tsvgr_opacity', 09:34:19 INFO - 'tart', 09:34:19 INFO - 'tscrollx', 09:34:19 INFO - 'cart', 09:34:19 INFO - 'tsvg_static']}, 09:34:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:34:19 INFO - 'talos_options': ['--disable-e10s'], 09:34:19 INFO - 'tests': ['tp5o']}, 09:34:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:34:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:34:19 INFO - 'talos_options': ['--disable-e10s', 09:34:19 INFO - '--xperf_path', 09:34:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:19 INFO - 'tests': ['tp5n']}, 09:34:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:34:19 INFO - 'talos_options': ['--xperf_path', 09:34:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:19 INFO - 'tests': ['tp5n']}}, 09:34:19 INFO - 'talos.zip': {'path': '', 09:34:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:34:19 INFO - Running post-action listener: _resource_record_post_action 09:34:19 INFO - [mozharness: 2017-02-20 17:34:19.404128Z] Finished populate-webroot step (success) 09:34:19 INFO - [mozharness: 2017-02-20 17:34:19.404240Z] Running create-virtualenv step. 09:34:19 INFO - Running pre-action listener: _resource_record_pre_action 09:34:19 INFO - Running main action method: create_virtualenv 09:34:19 INFO - Creating virtualenv /builds/slave/test/build/venv 09:34:19 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 09:34:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:34:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:34:19 INFO - Using real prefix '/tools/python27' 09:34:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:34:20 INFO - Installing distribute.............................................................................................................................................................................................done. 09:34:23 INFO - Installing pip.................done. 09:34:23 INFO - Return code: 0 09:34:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:34:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:34:23 INFO - Reading from file tmpfile_stdout 09:34:23 INFO - Output received: 09:34:23 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) 09:34:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:34: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')]} 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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')]} 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34: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', 'pip>=1.5'] in /builds/slave/test/build 09:34: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 pip>=1.5 09:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:23 INFO - 'HOME': '/Users/cltbld', 09:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:23 INFO - 'LOGNAME': 'cltbld', 09:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:23 INFO - 'MOZ_NO_REMOTE': '1', 09:34:23 INFO - 'NO_EM_RESTART': '1', 09:34:23 INFO - 'PAGER': '/bin/cat', 09:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:23 INFO - 'PWD': '/builds/slave/test', 09:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:23 INFO - 'SHELL': '/bin/bash', 09:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:23 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:23 INFO - 'USER': 'cltbld', 09:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:23 INFO - 'XPC_FLAGS': '0x0', 09:34:23 INFO - 'XPC_SERVICE_NAME': '0', 09:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:23 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 09:34:23 INFO - Cleaning up... 09:34:23 INFO - Return code: 0 09:34:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:34: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')]} 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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')]} 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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', '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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:34: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 psutil>=3.1.1 09:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:23 INFO - 'HOME': '/Users/cltbld', 09:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:23 INFO - 'LOGNAME': 'cltbld', 09:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:23 INFO - 'MOZ_NO_REMOTE': '1', 09:34:23 INFO - 'NO_EM_RESTART': '1', 09:34:23 INFO - 'PAGER': '/bin/cat', 09:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:23 INFO - 'PWD': '/builds/slave/test', 09:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:23 INFO - 'SHELL': '/bin/bash', 09:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:23 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:23 INFO - 'USER': 'cltbld', 09:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:23 INFO - 'XPC_FLAGS': '0x0', 09:34:23 INFO - 'XPC_SERVICE_NAME': '0', 09:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:23 INFO - Downloading/unpacking psutil>=3.1.1 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:34:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:27 INFO - Installing collected packages: psutil 09:34:27 INFO - Running setup.py install for psutil 09:34:27 INFO - building 'psutil._psutil_osx' extension 09:34:27 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 09:34:27 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 09:34:28 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 09:34:28 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 09:34:28 INFO - building 'psutil._psutil_posix' extension 09:34:28 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 09:34:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:34:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:34:28 INFO - ^ 09:34:28 INFO - 1 warning generated. 09:34:28 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 09:34:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:28 INFO - Successfully installed psutil 09:34:28 INFO - Cleaning up... 09:34:28 INFO - Return code: 0 09:34:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:28 INFO - 'HOME': '/Users/cltbld', 09:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:28 INFO - 'LOGNAME': 'cltbld', 09:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:28 INFO - 'MOZ_NO_REMOTE': '1', 09:34:28 INFO - 'NO_EM_RESTART': '1', 09:34:28 INFO - 'PAGER': '/bin/cat', 09:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:28 INFO - 'PWD': '/builds/slave/test', 09:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:28 INFO - 'SHELL': '/bin/bash', 09:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:28 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:28 INFO - 'USER': 'cltbld', 09:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:28 INFO - 'XPC_FLAGS': '0x0', 09:34:28 INFO - 'XPC_SERVICE_NAME': '0', 09:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:34:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:34:31 INFO - Installing collected packages: mozsystemmonitor 09:34:31 INFO - Running setup.py install for mozsystemmonitor 09:34:31 INFO - Successfully installed mozsystemmonitor 09:34:31 INFO - Cleaning up... 09:34:31 INFO - Return code: 0 09:34:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:34:31 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')]} 09:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:31 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')]} 09:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:31 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34:31 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 09:34:31 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 09:34:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:31 INFO - 'HOME': '/Users/cltbld', 09:34:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:31 INFO - 'LOGNAME': 'cltbld', 09:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:31 INFO - 'MOZ_NO_REMOTE': '1', 09:34:31 INFO - 'NO_EM_RESTART': '1', 09:34:31 INFO - 'PAGER': '/bin/cat', 09:34:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:31 INFO - 'PWD': '/builds/slave/test', 09:34:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:31 INFO - 'SHELL': '/bin/bash', 09:34:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:31 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:31 INFO - 'USER': 'cltbld', 09:34:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:31 INFO - 'XPC_FLAGS': '0x0', 09:34:31 INFO - 'XPC_SERVICE_NAME': '0', 09:34:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:31 INFO - Downloading/unpacking jsonschema==2.5.1 09:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:34:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:34:34 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 09:34:34 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 09:34:34 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 09:34:34 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 09:34:34 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 09:34:34 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 09:34:34 INFO - Downloading functools32-3.2.3-2.tar.gz 09:34:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:34:35 INFO - warning: no files found matching '*.txt' 09:34:35 INFO - no previously-included directories found matching 'build' 09:34:35 INFO - no previously-included directories found matching 'dist' 09:34:35 INFO - no previously-included directories found matching '.git*' 09:34:35 INFO - Installing collected packages: jsonschema, functools32 09:34:35 INFO - Running setup.py install for functools32 09:34:35 INFO - warning: no files found matching '*.txt' 09:34:35 INFO - no previously-included directories found matching 'build' 09:34:35 INFO - no previously-included directories found matching 'dist' 09:34:35 INFO - no previously-included directories found matching '.git*' 09:34:35 INFO - Successfully installed jsonschema functools32 09:34:35 INFO - Cleaning up... 09:34:35 INFO - Return code: 0 09:34:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:34:35 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')]} 09:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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')]} 09:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34:35 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 09:34:35 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 09:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:35 INFO - 'HOME': '/Users/cltbld', 09:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:35 INFO - 'LOGNAME': 'cltbld', 09:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:35 INFO - 'MOZ_NO_REMOTE': '1', 09:34:35 INFO - 'NO_EM_RESTART': '1', 09:34:35 INFO - 'PAGER': '/bin/cat', 09:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:35 INFO - 'PWD': '/builds/slave/test', 09:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:35 INFO - 'SHELL': '/bin/bash', 09:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:35 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:35 INFO - 'USER': 'cltbld', 09:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:35 INFO - 'XPC_FLAGS': '0x0', 09:34:35 INFO - 'XPC_SERVICE_NAME': '0', 09:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:34:35 INFO - Cleaning up... 09:34:35 INFO - Return code: 0 09:34:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:34:35 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')]} 09:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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')]} 09:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34:35 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 09:34:35 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 09:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:35 INFO - 'HOME': '/Users/cltbld', 09:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:35 INFO - 'LOGNAME': 'cltbld', 09:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:35 INFO - 'MOZ_NO_REMOTE': '1', 09:34:35 INFO - 'NO_EM_RESTART': '1', 09:34:35 INFO - 'PAGER': '/bin/cat', 09:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:35 INFO - 'PWD': '/builds/slave/test', 09:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:35 INFO - 'SHELL': '/bin/bash', 09:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:35 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:35 INFO - 'USER': 'cltbld', 09:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:35 INFO - 'XPC_FLAGS': '0x0', 09:34:35 INFO - 'XPC_SERVICE_NAME': '0', 09:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:35 INFO - Downloading/unpacking blobuploader==1.2.4 09:34:35 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 09:34:35 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 09:34:35 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 09:34:35 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 09:34:35 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 09:34:35 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 09:34:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:34:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:34:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:34:38 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 09:34:38 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 09:34:38 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 09:34:38 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 09:34:38 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 09:34:38 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 09:34:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:34:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:34:39 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 09:34:39 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 09:34:39 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 09:34:39 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 09:34:39 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 09:34:39 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 09:34:39 INFO - Downloading docopt-0.6.1.tar.gz 09:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:34:39 INFO - Installing collected packages: blobuploader, requests, docopt 09:34:39 INFO - Running setup.py install for blobuploader 09:34:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:34:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:34:39 INFO - Running setup.py install for requests 09:34:40 INFO - Running setup.py install for docopt 09:34:40 INFO - Successfully installed blobuploader requests docopt 09:34:40 INFO - Cleaning up... 09:34:40 INFO - Return code: 0 09:34:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:40 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34:40 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 09:34:40 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 09:34:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:40 INFO - 'HOME': '/Users/cltbld', 09:34:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:40 INFO - 'LOGNAME': 'cltbld', 09:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:40 INFO - 'MOZ_NO_REMOTE': '1', 09:34:40 INFO - 'NO_EM_RESTART': '1', 09:34:40 INFO - 'PAGER': '/bin/cat', 09:34:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:40 INFO - 'PWD': '/builds/slave/test', 09:34:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:40 INFO - 'SHELL': '/bin/bash', 09:34:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:40 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:40 INFO - 'USER': 'cltbld', 09:34:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:40 INFO - 'XPC_FLAGS': '0x0', 09:34:40 INFO - 'XPC_SERVICE_NAME': '0', 09:34:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ZJULq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-1b5j70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-gEEA1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-KI6GEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-pGATAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-7i7UPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-64T6vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Q1T8Xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-sfc2_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-PzugCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-j_0h4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-h2iZNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-GSnueg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-r3lT48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-GSw9Ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-olc7Ek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-q4bNkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:34:42 INFO - Running setup.py install for manifestparser 09:34:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:34:42 INFO - Running setup.py install for mozcrash 09:34:42 INFO - Running setup.py install for mozdebug 09:34:43 INFO - Running setup.py install for mozdevice 09:34:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:34:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:34:43 INFO - Running setup.py install for mozfile 09:34:43 INFO - Running setup.py install for mozhttpd 09:34:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:34:43 INFO - Running setup.py install for mozinfo 09:34:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:34:43 INFO - Running setup.py install for mozInstall 09:34:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:34:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:34:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:34:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:34:43 INFO - Running setup.py install for mozleak 09:34:43 INFO - Running setup.py install for mozlog 09:34:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:44 INFO - Running setup.py install for moznetwork 09:34:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:44 INFO - Running setup.py install for mozprocess 09:34:44 INFO - Running setup.py install for mozprofile 09:34:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:44 INFO - Running setup.py install for mozrunner 09:34:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:44 INFO - Running setup.py install for mozscreenshot 09:34:44 INFO - Running setup.py install for moztest 09:34:45 INFO - Running setup.py install for mozversion 09:34:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:45 INFO - Cleaning up... 09:34:45 INFO - Return code: 0 09:34:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:45 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34:45 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 09:34:45 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 09:34:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:45 INFO - 'HOME': '/Users/cltbld', 09:34:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:45 INFO - 'LOGNAME': 'cltbld', 09:34:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:45 INFO - 'MOZ_NO_REMOTE': '1', 09:34:45 INFO - 'NO_EM_RESTART': '1', 09:34:45 INFO - 'PAGER': '/bin/cat', 09:34:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:45 INFO - 'PWD': '/builds/slave/test', 09:34:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:45 INFO - 'SHELL': '/bin/bash', 09:34:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:45 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:45 INFO - 'USER': 'cltbld', 09:34:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:45 INFO - 'XPC_FLAGS': '0x0', 09:34:45 INFO - 'XPC_SERVICE_NAME': '0', 09:34:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-oEdypB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:45 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)) 09:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-4zVeEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:45 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)) 09:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-8FyIjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:45 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)) 09:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-u18msM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:45 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)) 09:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-mFWA2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-u8NPby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-_hG8nQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-saic_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-8plq3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-pOj95j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-TVxBX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-4Hemwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-DDeuH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:46 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)) 09:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-YZyPhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:47 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)) 09:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-elRhEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:47 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)) 09:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-neVNS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:47 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)) 09:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-svDd9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:47 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)) 09:34:47 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)) 09:34:47 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)) 09:34:47 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)) 09:34:47 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)) 09:34:47 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)) 09:34:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:47 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 09:34:47 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 09:34:47 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 09:34:47 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 09:34:47 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 09:34:47 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 09:34:49 INFO - Downloading blessings-1.6.tar.gz 09:34:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:50 INFO - Installing collected packages: blessings 09:34:50 INFO - Running setup.py install for blessings 09:34:50 INFO - Successfully installed blessings 09:34:50 INFO - Cleaning up... 09:34:50 INFO - Return code: 0 09:34:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:50 INFO - Reading from file tmpfile_stdout 09:34:50 INFO - Current package versions: 09:34:50 INFO - blessings == 1.6 09:34:50 INFO - blobuploader == 1.2.4 09:34:50 INFO - docopt == 0.6.1 09:34:50 INFO - functools32 == 3.2.3-2 09:34:50 INFO - jsonschema == 2.5.1 09:34:50 INFO - manifestparser == 1.1 09:34:50 INFO - mozInstall == 1.12 09:34:50 INFO - mozcrash == 1.0 09:34:50 INFO - mozdebug == 0.1 09:34:50 INFO - mozdevice == 0.49 09:34:50 INFO - mozfile == 1.2 09:34:50 INFO - mozhttpd == 0.7 09:34:50 INFO - mozinfo == 0.9 09:34:50 INFO - mozleak == 0.1 09:34:50 INFO - mozlog == 3.4 09:34:50 INFO - moznetwork == 0.27 09:34:50 INFO - mozprocess == 0.24 09:34:50 INFO - mozprofile == 0.28 09:34:50 INFO - mozrunner == 6.13 09:34:50 INFO - mozscreenshot == 0.1 09:34:50 INFO - mozsystemmonitor == 0.3 09:34:50 INFO - moztest == 0.8 09:34:50 INFO - mozversion == 1.4 09:34:50 INFO - psutil == 3.1.1 09:34:50 INFO - requests == 1.2.3 09:34:50 INFO - wsgiref == 0.1.2 09:34:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:51 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34:51 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 09:34:51 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 09:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:51 INFO - 'HOME': '/Users/cltbld', 09:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:51 INFO - 'LOGNAME': 'cltbld', 09:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:51 INFO - 'MOZ_NO_REMOTE': '1', 09:34:51 INFO - 'NO_EM_RESTART': '1', 09:34:51 INFO - 'PAGER': '/bin/cat', 09:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:51 INFO - 'PWD': '/builds/slave/test', 09:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:51 INFO - 'SHELL': '/bin/bash', 09:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:51 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:51 INFO - 'USER': 'cltbld', 09:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:51 INFO - 'XPC_FLAGS': '0x0', 09:34:51 INFO - 'XPC_SERVICE_NAME': '0', 09:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 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)) 09:34:51 INFO - Cleaning up... 09:34:51 INFO - Return code: 0 09:34:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34:51 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')]} 09:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:51 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')]} 09:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:51 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2f9c78f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.a7wSQhjXHz/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.vedm70HLhn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 09:34:51 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 09:34:51 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 09:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:51 INFO - 'HOME': '/Users/cltbld', 09:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:51 INFO - 'LOGNAME': 'cltbld', 09:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:51 INFO - 'MOZ_NO_REMOTE': '1', 09:34:51 INFO - 'NO_EM_RESTART': '1', 09:34:51 INFO - 'PAGER': '/bin/cat', 09:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:51 INFO - 'PWD': '/builds/slave/test', 09:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:51 INFO - 'SHELL': '/bin/bash', 09:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:34:51 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:51 INFO - 'USER': 'cltbld', 09:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:51 INFO - 'XPC_FLAGS': '0x0', 09:34:51 INFO - 'XPC_SERVICE_NAME': '0', 09:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:34:52 INFO - Cleaning up... 09:34:52 INFO - Return code: 0 09:34:52 INFO - Running post-action listener: _resource_record_post_action 09:34:52 INFO - Running post-action listener: _start_resource_monitoring 09:34:52 INFO - Starting resource monitoring. 09:34:52 INFO - [mozharness: 2017-02-20 17:34:52.168370Z] Finished create-virtualenv step (success) 09:34:52 INFO - [mozharness: 2017-02-20 17:34:52.169367Z] Running install step. 09:34:52 INFO - Running pre-action listener: _resource_record_pre_action 09:34:52 INFO - Running main action method: install 09:34:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:52 INFO - Reading from file tmpfile_stdout 09:34:52 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:52 INFO - Reading from file tmpfile_stdout 09:34:52 INFO - Output received: 09:34:52 INFO - Usage: mozinstall [options] installer 09:34:52 INFO - Options: 09:34:52 INFO - -h, --help show this help message and exit 09:34:52 INFO - -d DEST, --destination=DEST 09:34:52 INFO - Directory to install application into. [default: 09:34:52 INFO - "/builds/slave/test"] 09:34:52 INFO - --app=APP Application being installed. [default: firefox] 09:34:52 INFO - mkdir: /builds/slave/test/build/application 09:34:52 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'] 09:34:52 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 09:35:12 INFO - Reading from file tmpfile_stdout 09:35:12 INFO - Output received: 09:35:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:35:12 INFO - Running post-action listener: _resource_record_post_action 09:35:12 INFO - [mozharness: 2017-02-20 17:35:12.402600Z] Finished install step (success) 09:35:12 INFO - [mozharness: 2017-02-20 17:35:12.402756Z] Running run-tests step. 09:35:12 INFO - Running pre-action listener: _resource_record_pre_action 09:35:12 INFO - Running main action method: run_tests 09:35:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:35:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:35:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:35:12 INFO - 2017-02-20 09:35:12.417 screenresolution[1624:7296] starting screenresolution argv=screenresolution get 09:35:12 INFO - 2017-02-20 09:35:12.420 screenresolution[1624:7296] Display 0: 1600x1200x32@60 09:35:12 INFO - 2017-02-20 09:35:12.426 screenresolution[1625:7298] starting screenresolution argv=screenresolution list 09:35:12 INFO - Available Modes on Display 0 09:35:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:35:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:35:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:35:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:35:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:35:12 INFO - Intel Iris: 09:35:12 INFO - Chipset Model: Intel Iris 09:35:12 INFO - Type: GPU 09:35:12 INFO - Bus: Built-In 09:35:12 INFO - VRAM (Dynamic, Max): 1536 MB 09:35:12 INFO - Vendor: Intel (0x8086) 09:35:12 INFO - Device ID: 0x0a2e 09:35:12 INFO - Revision ID: 0x0009 09:35:12 INFO - Displays: 09:35:12 INFO - Display: 09:35:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:35:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:35:12 INFO - Display Serial Number: 1600x1200 60 09:35:12 INFO - Main Display: Yes 09:35:12 INFO - Mirror: Off 09:35:12 INFO - Online: Yes 09:35:12 INFO - Rotation: Supported 09:35:12 INFO - Return code: 0 09:35:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:35:12 INFO - Python 2.7.3 09:35:12 INFO - Return code: 0 09:35:12 INFO - grabbing minidump binary from tooltool 09:35: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')]} 09:35:12 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 0x10428cd30>, '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 0x1041b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:35:12 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 09:35:12 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 09:35:12 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 09:35:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:35:12 INFO - Return code: 0 09:35:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:35:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:35:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:35:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:35:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:35:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0367 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a7wSQhjXHz/Render', 09:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:12 INFO - 'HOME': '/Users/cltbld', 09:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:12 INFO - 'LOGNAME': 'cltbld', 09:35:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:35:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:12 INFO - 'MOZ_NO_REMOTE': '1', 09:35:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:35:12 INFO - 'NO_EM_RESTART': '1', 09:35:12 INFO - 'PAGER': '/bin/cat', 09:35:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:12 INFO - 'PWD': '/builds/slave/test', 09:35:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:12 INFO - 'SHELL': '/bin/bash', 09:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vedm70HLhn/Listeners', 09:35:12 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 09:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:12 INFO - 'USER': 'cltbld', 09:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:12 INFO - 'XPC_FLAGS': '0x0', 09:35:12 INFO - 'XPC_SERVICE_NAME': '0', 09:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:35:13 INFO - mozversion application_buildid: 20170220075356 09:35:13 INFO - mozversion application_changeset: d57841320b5495f190734e16e3f5eb25a008379a 09:35:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:35:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:35:13 INFO - mozversion application_name: Firefox 09:35:13 INFO - mozversion application_remotingname: firefox-dev 09:35:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:35:13 INFO - mozversion application_vendor: Mozilla 09:35:13 INFO - mozversion application_version: 53.0a2 09:35:13 INFO - mozversion platform_buildid: 20170220075356 09:35:13 INFO - mozversion platform_changeset: d57841320b5495f190734e16e3f5eb25a008379a 09:35:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:35:13 INFO - mozversion platform_version: 53.0a2 09:35:13 INFO - using testdate: 1487612113 09:35:13 INFO - actual date: 1487612113 09:35:13 INFO - starting webserver on 'localhost:49242' 09:35:13 INFO - SUITE-START | Running 2 tests 09:35:13 INFO - TEST-START | dromaeo_css 09:35:13 INFO - operating with platform_type : mac_ 09:35:13 INFO - Initialising browser for dromaeo_css test... 09:35:13 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpHYz7F5/profile http://localhost:49242/getInfo.html) 09:35:15 INFO - PROCESS | 1635 | 2017-02-20 09:35:15.885 plugin-container[1637:7480] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 09:35:15 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:15 INFO - PROCESS | 1635 | 2017-02-20 09:35:15.888 plugin-container[1637:7480] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 09:35:15 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:15 INFO - PROCESS | 1635 | 2017-02-20 09:35:15.888 plugin-container[1637:7480] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:35:15 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 09:35:15 INFO - PROCESS | 1635 | colorDepth:24 09:35:15 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 09:35:15 INFO - PROCESS | 1635 | __metrics 09:35:16 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:35:16 INFO - TEST-INFO | 1635: exit 0 09:35:16 INFO - Browser initialized. 09:35:16 INFO - Running cycle 1/1 for dromaeo_css test... 09:35:16 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpHYz7F5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:19 INFO - PROCESS | 1638 | 2017-02-20 09:35:19.266 plugin-container[1639:7607] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 09:35:19 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:19 INFO - PROCESS | 1638 | 2017-02-20 09:35:19.267 plugin-container[1639:7607] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 09:35:19 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:19 INFO - PROCESS | 1638 | 2017-02-20 09:35:19.267 plugin-container[1639:7607] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:35:28 INFO - PROCESS | 1638 | RSS: Main: 219471872 09:35:28 INFO - PROCESS | 1638 | 09:38:31 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:38:33 INFO - PROCESS | 1638 | RSS: Main: 239845376 09:38:33 INFO - PROCESS | 1638 | 09:40:17 INFO - PROCESS | 1638 | ************************* 09:40:17 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 09:40:17 INFO - PROCESS | 1638 | 09:40:17 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:40:17 INFO - PROCESS | 1638 | Full stack: 09:40:17 INFO - PROCESS | 1638 | ************************* 09:41:35 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:41:36 INFO - PROCESS | 1638 | RSS: Main: 249401344 09:41:36 INFO - PROCESS | 1638 | 09:44:38 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:44:38 INFO - PROCESS | 1638 | RSS: Main: 255733760 09:44:38 INFO - PROCESS | 1638 | 09:47:40 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:47:41 INFO - PROCESS | 1638 | RSS: Main: 252542976 09:47:41 INFO - PROCESS | 1638 | 09:50:44 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:50:44 INFO - PROCESS | 1638 | RSS: Main: 256569344 09:50:44 INFO - PROCESS | 1638 | 09:53:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:53:48 INFO - PROCESS | 1638 | RSS: Main: 257777664 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - PROCESS | 1638 | __start_tp_report 09:53:48 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 09:53:48 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 09:53:48 INFO - PROCESS | 1638 | |i|pagename|runs| 09:53:48 INFO - PROCESS | 1638 | |0;dojo.html;2781.2351603309075;2828.6039954406374;3357.143928298284;3364.125327414873;3411.8805841795534;4728.338032675424;5728.398518155591;5763.740631671574;5765.884682306351;5867.413258674131;5695.74369153388;5774.220480235167;5793.449622285887;5819.301683797939;5838;4164.375343698439;4593.517680643517;5261.368635763713;5262.526372626472;5316.175992721134;5302.575793936476;5324.973375133119;5339.119045357511;5350.30446042014;5354.116570765838;5136.383633963929;5340.439253878326;5346.4118946915805;5350.30446042014;5397.163439666858;5150.639455238116;5212.244224587452;5219.164933610604;5260;5329.2006199070065;5109.131447653871;5248.107821670325;5248.317718696583;5257.605679574009;5287.735613219324;5737.483626473638;5790.7973220937065;5903.527717782567;5904.675242861641;5937.762489500415;5091.949080509185;6154.7230374633245;6162.106494558308;6223.751049957997;6242.250929888397;6304.401081897213;6306.621602703853;6314.116023756677;6327.019312006646;6357.141785858896;5869.735861886225;5888.617239879394;5907.379725128885;5974.074018527135;6025.126356678257;5160.612954028463;6165.722542485599;6177.69111544421;6227.719752611143;6302.810915672537;6147.44672979434;6165.938340616536;6199.876002480015;6205.875882482325;6211.81364559064;6259.280182778948;6278.717457714414;6291.842703932438;6292.968535157386;6293.118963345135;6079.148919151322;6167.414095660905;6203.627782333075;6262.405071518198;6280.780172693934;6188.350223226496;6287.276963149205;6303.243610766737;6307.747690092345;6308.621482711052;5023.196288593807;5032.119379108642;5042.672226305279;5047.545720885138;5051.1413059779925;4407.316865885793;4416.205083085076;4468.329750537446;4487.775611219426;4508.45898492183;5872.618279811799;5905.616134951214;5932.169496270526;5987.580869339192;6037.003894357382;5673.290838645169;6222.160008398899;6244.375562443719;6260.092286618415;6282.340354262828;5710.114932185518;5777.24895763548;5851.502622277154;5875.383084776123;5915.674637894957;5495.368032676213;5517.475839795213;5523.088690366045;5524.558035357162;5539.695316757617;6324.367563243731;6367.3314301997625;6367.586106903036;6392.968035159794;6459.838504037342;6169.598976066542;6217.440430361289;6266.561340706289;6274.780382686584;6297.433231009231;6226.40849119333;6228.4705800005695;6240;6326.936730632819;6332.050192471166;6195.721192546436;6204.5967012144065;6208.2860471044605;6218.471429928507;6222.191115155001;6148.415900489446;6177.413145751147;6296.307406185224;6296.905546416899;6297.023961286008;6077.756889724538;6111.682900513672;6113.388661133851;6153.938460615336;6222.751089956351;6131.478824299806;6171.352008039091;6189.535784816067;6197.96901015492;6215.9432896406815;5979.402059793986;5994.340622531431;6006;6055.848603784941;6066.272047354169;5698;5877.911831322448;6032.065029920369;6105.145279660762;6129.540284478771;5158.819441319536;5991.970040149771;6011.72947217385;6030.5778595497895;6050.30421501533 09:53:48 INFO - PROCESS | 1638 | |1;ext.html;431.89202699325176;458.8669285907087;502.06615694807675;520.7916833266694;530.8142150247412;34922.47616285754;36960.81519592405;37109.44335834964;37125;37140.25719485615;38322.4251636225;38669.806650966704;38703.61296387035;38759.03102422441;39014.21971560567;8446.282066024365;9285.164335209847;9459.905400946005;9481.33630645855;9492.81014379712;8640.567971601426;11243.100551955824;11531;11552.075833933266;11647.592334268302;9789;11471.59849405271;11526.884731152706;11539.942300288529;11540.076793856513;11429.257098288626;11493.195476316661;11497.540098396054;11517.48171332292;11523.769524609545;9666.95166524163;11312.94343528277;11337;11388.60139895108;11397.829032564518;38271.23457530862;39407.60592394068;40518;40671.18657626831;40820;3919.7648141111627;3940.4089386591954;3981.9601803981877;4012.7592344459426;4044.2518134144984;23537.646935295983;30166.396672066654;30327.545086823717;30456.390872182656;30611.38777224443;3158.8894388696285;3164.4462219111565;3191.2181515528846;3193.96806031939;3200.0399880035898;452939;454326;456241.9999999967;457428;457854;57894;60618;61063.389366105774;61066.69466652638;61227.38772612217;63362.683186583774;63433.36566634275;63488.73022539524;63536;63758.36241637617;15080.773788393182;15381.846181538265;15393.383694711894;15400.845991540165;15443.691126177413;9035.548222588845;11037.392943388191;11380;11568.190226684219;11752;15494.612634684223;15618.531444056696;16031.839681603034;16035.11806850611;16135.19324033817;6857.417119544894;7074.7170113195925;7448.217937116633;7525.623718814038;7534.623268836646;874.2612492443875;890.701614958996;891.3404080980029;911.2254583603884;926.9814603707888;30179.849100754356;30186.396272074435;30186.54720179199;30201.848990755345;30530.54204186939;1022.728976821143;1029.799189158107;1100.5817789240039;1122.1639878290725;1158.2529268621693;12888.677783055311;15519.913606047468;15852.96955697876;15878.761818572731;16312.429064982669;626569;627308;628289;629249;634236;60421.395786042456;60705.69647151736;60715.08927366093;60947.78104437886;61017.779644406866;1432.8638779315947;1447.3631602095045;1466.7359875222348;1467.39103272143;1467.6037469883072;23928.88035559811;29149.417011660073;29582.704172957994;30161.69838301589;30258.394832103673;49716.50283497119;49755.25367119424;50076;50256;50358;541030;542330;542393;543116;544219;24443.38891527726;25340.11309604229;25374.492510150063;26131.346716332242;26589.335266617716;40961.590384095776;42913.35629965615;43580.91047723832;43733.34399983939;44088;35260.647393526764;35277.47083793694;35649.10877227986;35683.46474802828;36802.447963280036;35123.82438087793;35437.46843797397;36100.27799443944;36195.63804361923;36450;34368.82815585906;34626.134346641535;34735.652643473244;35420.11449713778;35478;26631.600525991737;29812.403751924405;31495.055148345586;31799.364012719154;31909.36181276408;23768.40578985539;23942.664401507795;23958.640620391056;24280.271591852274;24320 09:53:48 INFO - PROCESS | 1638 | |2;jquery.html;385.4122463243551;388.56868875548156;397.15009878859246;397.8826246257354;418.0927387568975;13504.729905401886;13690.17858928465;16799.916000419995;17873.55316117096;17891.463256102306;17242.137893105326;20844.332453403727;21094.57810843782;21692.132314707393;22025.449363765914;5152.716600586966;5535.557155427577;5597.188407680884;5702.030654788696;5757.884842303151;5694.800681976131;5755.45323194298;5798.884022319551;5801.970990145064;5815.156802263669;5552.888942221153;5746.655200687951;5757.395473475288;5811.6803575803315;5841.970790146064;5775.509081728058;5783.971080144573;5803.738831752581;5820.243368362107;5825.999999999979;5554.1946417769195;5768.394318596529;5805.7097145143;5805.941940580625;5814.534837213013;12616.873831261753;12728.490860365575;12760.234385936874;21816.781832181634;23587.88206058976;2538.669972903529;2620.2532278300782;2632.3419145213697;2665.8533780642056;2681.4637072585365;8698.869516957251;9712.320137590372;9799.510024498746;10195.592176312939;10293.53679084443;1805.2147315917487;1807.6836553603198;1810.7646006019133;1816.9370918012846;1821.5992481654016;121326.39336803347;131497;135859.32070339585;136143.31928340395;139139.99999999898;11432.199746017865;12103.33431661267;12276.693082672826;12308.692282693004;12330.63008109758;15675.921620392053;15801.841981580266;15827.604309892346;15831.52505424816;15847.128407937675;8250.092489826115;8451.771084577418;8466.365022623328;8469.830603387898;8593.398462107592;7395.445341599401;8079.070906845788;8209.466384684976;8217.83564328722;8234.300084492885;4727.24364101749;4785.59322457588;4793.1372352976105;4863.294822250825;4884.877878053077;1784.4022252545542;1942.756635753119;2061.5361543652857;2062.257587268582;2066.121898193262;812.9959350203211;846.6190214403543;876.6449587916904;880.8161234137846;886.1714297132202;16233.83766162347;17914.641707165785;20234.898825506072;21262.362129136152;21301.939608107878;771.9614019299013;790.4348390900534;865.718641441534;917.7567944494714;919.797644518205;8310.878031465796;8435.662573496991;10228.897711022943;10828.891711082946;11311.773764524663;723310;732969;736093;740919.2954035195;759017;121741;127347;146491;146926;154492.27538623248;932.7714709896119;969.9224062075017;995.875515560555;997.6408492942535;1004.1213937804275;19612.215511379385;19953.301634442723;20246.291379801638;20294.492637684176;20376.79623203749;59829;59938;59989.40010599838;60695;60718.392816071275;83490;85791;88684;89505;89537;8257;8362.038365587792;8376.12050734664;8543.718442233714;8579.571021448777;12145.453454594388;12951.481940722266;12999.480020799063;13017.739645206853;13297.335133243492;10860.728481788019;11071;11317.773644526898;11453.396524486745;11547.538098475967;10876.945615271872;11006.95433933775;11057.72355691082;11367.033802126913;11381.43092845338;11038.834417483904;11271.887281127083;11299.3785341807;11528.365939873052;11737.237079589799;6552.3447655236005;8256.215659512336;8338.541380224146;8579.87130193035;8639.524826134622;8231.09457959636;10693.14454843595;11013.229073964745;11120.443977800916;11206.935341142578 09:53:48 INFO - PROCESS | 1638 | |3;mootools.html;1407.7536431124552;1430.5565274764824;1464.5167094858698;1472.5508719840452;1474.3291802229983;7459.4032477401815;7737.994060772092;7982.960085199573;8436.915630843698;8447.281981031612;7864.96067519663;8148.7147949821765;8165.142660020701;8262.834743305146;8271.627776750045;2198.835087368446;2245.8427910046303;2252.031626400655;2252.234240358278;2253.098760495807;2256.379495638696;2271.931842044741;2276.6926464927187;2283.942901427461;2284.8743319117443;2143.582001509706;2151.3008272311563;2168.609650262952;2170.880601566921;2171.8045375916163;2154.924577639775;2160.2655097266925;2171.294329342962;2181.6727490876497;2184.1263494602126;2211.6461366181334;2271.7273927128686;2273.079402841852;2273.7044184256106;2279.5099053703534;8468.237858592756;8502.78743031429;8527.232549070613;8543.060263371024;8543.957280213559;4996.775145118479;5146.6911985281;5149.742512874342;5154.9484505155215;5156.639035267533;3330.8501117449773;3393.541871847294;3535.9469607955903;3582.731295152874;3602.279544091166;1223.528941357575;1232.617888454573;1742.6514697060638;1778.342013455017;1856.6565185440736;10365.118964887919;10795.5681772729;10799.028087472165;10807.027367536959;10865.565377384895;4438.112377524508;4518.932216016762;4553.1121431321235;4569.9314510282375;4570.81716731327;8730.214280714767;8755.56222188888;8789.736307910773;8793.692220772242;8806.77983050416;5024.422191447957;5042.067217564762;5043.949560504421;5050.393952725696;5059.56993655536;4574.039451715111;4693.929591056137;4696.272077827942;4730.361401210793;4775.140474714584;3499.107727529464;3547.166415892263;3673;3781.621837816196;3870.4774855394726;1110.2838669058574;1189.5182451107316;1193.242291145129;1204.9216800907914;1204.9698757531132;829.3903980574273;879.0725784297499;893.5398269891011;908.8915907596038;917.1974522292994;5394.514493695587;5554.389017208104;5581.720913954286;5583.469570390806;5661.943380566224;551.5670198893929;559.9440055994368;560.8177342363748;565.0535353283234;574.9626274292158;3647.507586475845;3704.870329538453;3724.404095344786;3811.104390468238;3862.304785138646;9586.185174260127;9966.103050725469;10005.399676019317;10072.546735396925;10159.4920253987;9921.950390248003;10021.849672254923;10027.548760305805;10048.648297309559;10070.798584028278;6501.8699626008165;6815.250322464525;7927.841443171104;7958.124606293303;7972.720954766672;5328.307320048369;5375.3012108425655;5464.371597266207;5507.146392309199;5535.446455354432;7790.675585150651;9047.050059743633;9163.725088247364;9176;9234.538273086453;6767.883299255568;8052.597370131587;9247.676331328372;9369.484678342757;9494.905050949403;6492.253390860112;6728;6734.696938637675;6738.696758645775;6767.830804229737;7593.658285377061;7771.92228077712;7860.096088949844;7871.48835325702;7961.6019199040975;6295.842603934938;7054.224035356209;7163.3552980232025;7219.205887352282;7261.891071634035;6580.025992202416;6708.396244338033;7139.607321597362;7182;7255.709771609288;6318.462930650762;6390.584612000205;6432.485401167989;6471.255805582418;6476.676166191577;4007;4262.232798096248;4983.028309479617;4999.0251900878975;5036.219385995177;3973.384125460558;4861.465238823797;5051.924221136759;5085.677723791915;5150.48495150482 09:53:48 INFO - PROCESS | 1638 | |4;prototype.html;308.4017007006408;355.7065421027653;365.4572959155655;368.5006815764637;372.1737742212289;4511.368408422823;5083.898322033557;5520.101946478101;5547.9736248794;5567.9977604031255;5368.758405871736;5382;5399.784008639659;5400.243965844785;5443.863903402407;3656.945145822815;3911.119998000456;4004.9799751001206;4173.478315210598;4254.595813397722;3488.7732297400717;3628.365036118682;4199.958000419987;4242.215190189825;4242.257604919142;3730.992631989361;4018.3771515415156;4234.767087810169;4238.088810905655;4261.637760790337;4026.295398305285;4189.486308215077;4241.300185469409;4245.299525578276;4289.270823959934;3245.7727959042877;3711.6102809204913;3723.1250656095795;3723.7393382463238;3723.832427540767;5364.91952620711;5367.677939323614;5369.40936496985;5370.248165256867;5374.516293533562;4429.557044295577;4673.766311684436;4967.403911530603;4983.775730092155;4999.275105109738;5775.422457754233;5794.768209271624;5798.217240672498;5816.418358164192;5818.429024163494;3891.2606604745292;3900.941485877714;3902.765834049938;3908.511436070491;3908.9413658795143;4626.097910907408;5556.444355564452;5825.912611310834;6062.696865156769;6157.96921015392;3217;3770.377887648507;3977.8607748728655;3978.5225772906856;3983.083890705154;3573.8391772370305;4026.5570787213383;4032.6169013943627;4043.211573743151;4055.8986025349013;4362.17118747437;4604.861854144381;4685.8594242172785;4718.386609740712;4734.75669166622;5075.974620126875;5100.566451851634;5761.020626493506;5812.418758124239;5850.268716410449;1101.4194630704965;1175.6590588729173;1264.8735126487277;1315.3886489050835;1332.4536939854613;810.6352141536332;835.6782638684028;885.3758100539234;888.7912439082842;893.5710858787818;3904.5509766376663;5290.761915713802;5404.162354835007;5415.593830462731;5424.3691809472375;5122;5348.411674715778;5482.780688772485;5951.315598706117;5985.491233245136;4401.449818772653;5122.874007287009;5466.562674986071;5467.1799230115;5467.917981230285;2632.8288661236957;2678.504476671783;2970;2974.598429212072;3017.125033740235;5838.970805146032;5899.439553242435;5939.406059394112;5945.137954996588;6006.729697163638;5654.134135735198;5896.852578685482;6101.969490152521;6134.938650613437;6138.324784273726;5266.526012658956;5475.795325028487;5987.982043052692;6185.84535386619;6190.969045154746;4861.399151454097;5128.461511541234;5603.859903502282;5692.544596432358;6093.4461712264865;4610.631149508098;5919.822405327847;5961.672108034099;5981.3719559445635;5988.700564971647;6105.908411373922;6167.784127555514;6193.473554747717;6217.316095229431;6282.748690052346;3356.942563092618;3391.4404123319378;3902.6535845133385;3927.2341893330527;3971.7687516869864;5027.818462661198;5834.299884013945;6009.94825905456;6016.77379369636;6018.495376155961;5313.884084342331;5667.227662942744;5802.216700745345;5833.445822646841;5862.853428664317;4306.50475195344;4495.977520112378;4896.583790377716;4921.335619691439;5759.366469688247;4968.453470118357;5425.182563840999;5651.717414129359;5660.915086273627;5671.319441667027;4541.72749635023;5455.1271796512365;5503.147012213113;5661.915071273852;5731.541476681772;5606.299212598426;5629.6903670298525;5641.5204707600315;5653.858653533694;5659.377468478382 09:53:48 INFO - PROCESS | 1638 | |5;yui.html;692.4218277738089;698.1656919980625;762.3596179209463;798.5178873951078;800.4324517335265;14467.927660361696;14701.485448009322;15155.545333639995;15327.616809579738;15348.15585142818;13795.862041379583;14036.789448158286;14055.508057218;14061.578152655411;15136.848631513682;861.6338056326052;886.5301390263138;887.7203680840547;888.8933328000649;889.2219308105408;845.7927807687095;875.1729615513342;883.8320719063389;884.1114679746845;886.1847100667402;931.3061768982118;934.2059249637788;934.700895713372;938.8122375524888;939.4316438554679;906.9455832650062;927.4759760735196;930.1303281431883;933.0529512544772;934.2572654739464;695.987338422599;699.2343383994518;699.4369532526314;699.8705239530652;701.4809041309445;15031.248437578186;15289.388424463008;15694.979826311253;15723.842761572352;15910.363585456567;14035.087719298213;14217.004809663327;14239.85760142406;14275.928620356832;14311;11354.772904541946;11390.658280251522;11426.714332141762;11592.362420066893;12030.037596992219;3113.237256872058;3267.5098735189913;3316.005198440458;3383.7631365804405;3407.2333724911946;101125;108273;108313;109106.45446772795;110597.44701276523;9076.8638470423;10666.093382062612;10780.29928054674;11253.212275140826;11292;20905.53661243729;21748.782512174992;21783.12867485283;21823;22002.559948800936;7640.121386040565;7694.038245219348;7754.7673569793;7759.806004849811;7946.562939038408;3569.607343192247;4191.769452680071;4262.936055959163;4295.978520107442;4316.287812510964;3627.546556680415;3719.2189640175325;3724.1620635356826;3729.291434627412;3733.216024634857;553.2116733654526;653.4804830160034;660.4089340040733;664.8836453620694;684.5926673629183;671.2549070531705;672.4217173231017;690.8549204666975;691.9688614012382;692.5221597097986;9641.42151470914;10086.394816311045;10258.743531411625;10345.37927724339;10349.844752328721;659.970301336441;672.7174586673511;693.8352735941098;696.0429409561852;702.9086218791524;4044.090079732037;4281.409538040095;4838.758062096951;4902.264660300983;4956.529129732669;105086.4745676282;105203.94796052095;105426;106291;106353;79937.60031199807;91117;94266;94566.52716736372;97153;81545.59227203827;82159;82273.17726822656;85180;85458;9658.082482164184;9717.85423218638;9761.463119528495;9784;9825.803483930138;53569;54508.72745636247;54721.1791823115;55644.443555565544;56710.432895670514;71372;71583;72853.27146728677;73715.63142184255;75264;11425.714357141138;11483.368414736935;11506.252093613888;11700.180987330805;11870.465829037887;12455.065870059601;12582.87417125817;12790.552330668383;13212.669683257995;13366.866331336561;11530.82703759461;11621.535138594361;11948.581799636971;12023.158378913393;12345.506179752709;11621.709457263636;11911.626294149632;11933.940330298294;11935.283882967049;12321.876781232073;11415.942920285346;11822.881771182178;11862.525498979945;12120.454579544117;12210.633680989584;9760.85358719634;9948.154406875206;9956.303058785816;10113.69658910234;10236.590536378462;9788.706338809847;9822.263330250116;9826.361286516354;9831.950840245752;9858.359206651545 09:53:48 INFO - PROCESS | 1638 | __end_tp_report 09:53:48 INFO - PROCESS | 1638 | __start_cc_report 09:53:48 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,386 09:53:48 INFO - PROCESS | 1638 | __end_cc_report 09:53:48 INFO - PROCESS | 1638 | __startTimestamp1487613228018__endTimestamp 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - PROCESS | 1638 | ------- Summary: start ------- 09:53:48 INFO - PROCESS | 1638 | Number of tests: 6 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5757.79 Median:6006.00 stddev:680.78 (11.3%) stddev-sans-first:641.57 09:53:48 INFO - PROCESS | 1638 | Values: 2781.2 2828.6 3357.1 3364.1 3411.9 4728.3 5728.4 5763.7 5765.9 5867.4 5695.7 5774.2 5793.4 5819.3 5838.0 4164.4 4593.5 5261.4 5262.5 5316.2 5302.6 5325.0 5339.1 5350.3 5354.1 5136.4 5340.4 5346.4 5350.3 5397.2 5150.6 5212.2 5219.2 5260.0 5329.2 5109.1 5248.1 5248.3 5257.6 5287.7 5737.5 5790.8 5903.5 5904.7 5937.8 5091.9 6154.7 6162.1 6223.8 6242.3 6304.4 6306.6 6314.1 6327.0 6357.1 5869.7 5888.6 5907.4 5974.1 6025.1 5160.6 6165.7 6177.7 6227.7 6302.8 6147.4 6165.9 6199.9 6205.9 6211.8 6259.3 6278.7 6291.8 6293.0 6293.1 6079.1 6167.4 6203.6 6262.4 6280.8 6188.4 6287.3 6303.2 6307.7 6308.6 5023.2 5032.1 5042.7 5047.5 5051.1 4407.3 4416.2 4468.3 4487.8 4508.5 5872.6 5905.6 5932.2 5987.6 6037.0 5673.3 6222.2 6244.4 6260.1 6282.3 5710.1 5777.2 5851.5 5875.4 5915.7 5495.4 5517.5 5523.1 5524.6 5539.7 6324.4 6367.3 6367.6 6393.0 6459.8 6169.6 6217.4 6266.6 6274.8 6297.4 6226.4 6228.5 6240.0 6326.9 6332.1 6195.7 6204.6 6208.3 6218.5 6222.2 6148.4 6177.4 6296.3 6296.9 6297.0 6077.8 6111.7 6113.4 6153.9 6222.8 6131.5 6171.4 6189.5 6198.0 6215.9 5979.4 5994.3 6006.0 6055.8 6066.3 5698.0 5877.9 6032.1 6105.1 6129.5 5158.8 5992.0 6011.7 6030.6 6050.3 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:67349.26 Median:35563.55 stddev:146169.31 (411.0%) stddev-sans-first:146493.00 09:53:48 INFO - PROCESS | 1638 | Values: 431.9 458.9 502.1 520.8 530.8 34922.5 36960.8 37109.4 37125.0 37140.3 38322.4 38669.8 38703.6 38759.0 39014.2 8446.3 9285.2 9459.9 9481.3 9492.8 8640.6 11243.1 11531.0 11552.1 11647.6 9789.0 11471.6 11526.9 11539.9 11540.1 11429.3 11493.2 11497.5 11517.5 11523.8 9667.0 11312.9 11337.0 11388.6 11397.8 38271.2 39407.6 40518.0 40671.2 40820.0 3919.8 3940.4 3982.0 4012.8 4044.3 23537.6 30166.4 30327.5 30456.4 30611.4 3158.9 3164.4 3191.2 3194.0 3200.0 452939.0 454326.0 456242.0 457428.0 457854.0 57894.0 60618.0 61063.4 61066.7 61227.4 63362.7 63433.4 63488.7 63536.0 63758.4 15080.8 15381.8 15393.4 15400.8 15443.7 9035.5 11037.4 11380.0 11568.2 11752.0 15494.6 15618.5 16031.8 16035.1 16135.2 6857.4 7074.7 7448.2 7525.6 7534.6 874.3 890.7 891.3 911.2 927.0 30179.8 30186.4 30186.5 30201.8 30530.5 1022.7 1029.8 1100.6 1122.2 1158.3 12888.7 15519.9 15853.0 15878.8 16312.4 626569.0 627308.0 628289.0 629249.0 634236.0 60421.4 60705.7 60715.1 60947.8 61017.8 1432.9 1447.4 1466.7 1467.4 1467.6 23928.9 29149.4 29582.7 30161.7 30258.4 49716.5 49755.3 50076.0 50256.0 50358.0 541030.0 542330.0 542393.0 543116.0 544219.0 24443.4 25340.1 25374.5 26131.3 26589.3 40961.6 42913.4 43580.9 43733.3 44088.0 35260.6 35277.5 35649.1 35683.5 36802.4 35123.8 35437.5 36100.3 36195.6 36450.0 34368.8 34626.1 34735.7 35420.1 35478.0 26631.6 29812.4 31495.1 31799.4 31909.4 23768.4 23942.7 23958.6 24280.3 24320.0 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:39763.07 Median:386.99 stddev:122881.53 (31753.1%) stddev-sans-first:123190.67 09:53:48 INFO - PROCESS | 1638 | Values: 385.4 388.6 397.2 397.9 418.1 13504.7 13690.2 16799.9 17873.6 17891.5 17242.1 20844.3 21094.6 21692.1 22025.4 5152.7 5535.6 5597.2 5702.0 5757.9 5694.8 5755.5 5798.9 5802.0 5815.2 5552.9 5746.7 5757.4 5811.7 5842.0 5775.5 5784.0 5803.7 5820.2 5826.0 5554.2 5768.4 5805.7 5805.9 5814.5 12616.9 12728.5 12760.2 21816.8 23587.9 2538.7 2620.3 2632.3 2665.9 2681.5 8698.9 9712.3 9799.5 10195.6 10293.5 1805.2 1807.7 1810.8 1816.9 1821.6 121326.4 131497.0 135859.3 136143.3 139140.0 11432.2 12103.3 12276.7 12308.7 12330.6 15675.9 15801.8 15827.6 15831.5 15847.1 8250.1 8451.8 8466.4 8469.8 8593.4 7395.4 8079.1 8209.5 8217.8 8234.3 4727.2 4785.6 4793.1 4863.3 4884.9 1784.4 1942.8 2061.5 2062.3 2066.1 813.0 846.6 876.6 880.8 886.2 16233.8 17914.6 20234.9 21262.4 21301.9 772.0 790.4 865.7 917.8 919.8 8310.9 8435.7 10228.9 10828.9 11311.8 723310.0 732969.0 736093.0 740919.3 759017.0 121741.0 127347.0 146491.0 146926.0 154492.3 932.8 969.9 995.9 997.6 1004.1 19612.2 19953.3 20246.3 20294.5 20376.8 59829.0 59938.0 59989.4 60695.0 60718.4 83490.0 85791.0 88684.0 89505.0 89537.0 8257.0 8362.0 8376.1 8543.7 8579.6 12145.5 12951.5 12999.5 13017.7 13297.3 10860.7 11071.0 11317.8 11453.4 11547.5 10876.9 11007.0 11057.7 11367.0 11381.4 11038.8 11271.9 11299.4 11528.4 11737.2 6552.3 8256.2 8338.5 8579.9 8639.5 8231.1 10693.1 11013.2 11120.4 11206.9 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5319.02 Median:5072.62 stddev:2907.33 (57.3%) stddev-sans-first:2900.63 09:53:48 INFO - PROCESS | 1638 | Values: 1407.8 1430.6 1464.5 1472.6 1474.3 7459.4 7738.0 7983.0 8436.9 8447.3 7865.0 8148.7 8165.1 8262.8 8271.6 2198.8 2245.8 2252.0 2252.2 2253.1 2256.4 2271.9 2276.7 2283.9 2284.9 2143.6 2151.3 2168.6 2170.9 2171.8 2154.9 2160.3 2171.3 2181.7 2184.1 2211.6 2271.7 2273.1 2273.7 2279.5 8468.2 8502.8 8527.2 8543.1 8544.0 4996.8 5146.7 5149.7 5154.9 5156.6 3330.9 3393.5 3535.9 3582.7 3602.3 1223.5 1232.6 1742.7 1778.3 1856.7 10365.1 10795.6 10799.0 10807.0 10865.6 4438.1 4518.9 4553.1 4569.9 4570.8 8730.2 8755.6 8789.7 8793.7 8806.8 5024.4 5042.1 5043.9 5050.4 5059.6 4574.0 4693.9 4696.3 4730.4 4775.1 3499.1 3547.2 3673.0 3781.6 3870.5 1110.3 1189.5 1193.2 1204.9 1205.0 829.4 879.1 893.5 908.9 917.2 5394.5 5554.4 5581.7 5583.5 5661.9 551.6 559.9 560.8 565.1 575.0 3647.5 3704.9 3724.4 3811.1 3862.3 9586.2 9966.1 10005.4 10072.5 10159.5 9922.0 10021.8 10027.5 10048.6 10070.8 6501.9 6815.3 7927.8 7958.1 7972.7 5328.3 5375.3 5464.4 5507.1 5535.4 7790.7 9047.1 9163.7 9176.0 9234.5 6767.9 8052.6 9247.7 9369.5 9494.9 6492.3 6728.0 6734.7 6738.7 6767.8 7593.7 7771.9 7860.1 7871.5 7961.6 6295.8 7054.2 7163.4 7219.2 7261.9 6580.0 6708.4 7139.6 7182.0 7255.7 6318.5 6390.6 6432.5 6471.3 6476.7 4007.0 4262.2 4983.0 4999.0 5036.2 3973.4 4861.5 5051.9 5085.7 5150.5 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4631.65 Median:5302.32 stddev:1450.85 (27.4%) stddev-sans-first:1418.17 09:53:48 INFO - PROCESS | 1638 | Values: 308.4 355.7 365.5 368.5 372.2 4511.4 5083.9 5520.1 5548.0 5568.0 5368.8 5382.0 5399.8 5400.2 5443.9 3656.9 3911.1 4005.0 4173.5 4254.6 3488.8 3628.4 4200.0 4242.2 4242.3 3731.0 4018.4 4234.8 4238.1 4261.6 4026.3 4189.5 4241.3 4245.3 4289.3 3245.8 3711.6 3723.1 3723.7 3723.8 5364.9 5367.7 5369.4 5370.2 5374.5 4429.6 4673.8 4967.4 4983.8 4999.3 5775.4 5794.8 5798.2 5816.4 5818.4 3891.3 3900.9 3902.8 3908.5 3908.9 4626.1 5556.4 5825.9 6062.7 6158.0 3217.0 3770.4 3977.9 3978.5 3983.1 3573.8 4026.6 4032.6 4043.2 4055.9 4362.2 4604.9 4685.9 4718.4 4734.8 5076.0 5100.6 5761.0 5812.4 5850.3 1101.4 1175.7 1264.9 1315.4 1332.5 810.6 835.7 885.4 888.8 893.6 3904.6 5290.8 5404.2 5415.6 5424.4 5122.0 5348.4 5482.8 5951.3 5985.5 4401.4 5122.9 5466.6 5467.2 5467.9 2632.8 2678.5 2970.0 2974.6 3017.1 5839.0 5899.4 5939.4 5945.1 6006.7 5654.1 5896.9 6102.0 6134.9 6138.3 5266.5 5475.8 5988.0 6185.8 6191.0 4861.4 5128.5 5603.9 5692.5 6093.4 4610.6 5919.8 5961.7 5981.4 5988.7 6105.9 6167.8 6193.5 6217.3 6282.7 3356.9 3391.4 3902.7 3927.2 3971.8 5027.8 5834.3 6009.9 6016.8 6018.5 5313.9 5667.2 5802.2 5833.4 5862.9 4306.5 4496.0 4896.6 4921.3 5759.4 4968.5 5425.2 5651.7 5660.9 5671.3 4541.7 5455.1 5503.1 5661.9 5731.5 5606.3 5629.7 5641.5 5653.9 5659.4 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20809.96 Median:4306.13 stddev:30667.03 (712.2%) stddev-sans-first:30715.86 09:53:48 INFO - PROCESS | 1638 | Values: 692.4 698.2 762.4 798.5 800.4 14467.9 14701.5 15155.5 15327.6 15348.2 13795.9 14036.8 14055.5 14061.6 15136.8 861.6 886.5 887.7 888.9 889.2 845.8 875.2 883.8 884.1 886.2 931.3 934.2 934.7 938.8 939.4 906.9 927.5 930.1 933.1 934.3 696.0 699.2 699.4 699.9 701.5 15031.2 15289.4 15695.0 15723.8 15910.4 14035.1 14217.0 14239.9 14275.9 14311.0 11354.8 11390.7 11426.7 11592.4 12030.0 3113.2 3267.5 3316.0 3383.8 3407.2 101125.0 108273.0 108313.0 109106.5 110597.4 9076.9 10666.1 10780.3 11253.2 11292.0 20905.5 21748.8 21783.1 21823.0 22002.6 7640.1 7694.0 7754.8 7759.8 7946.6 3569.6 4191.8 4262.9 4296.0 4316.3 3627.5 3719.2 3724.2 3729.3 3733.2 553.2 653.5 660.4 664.9 684.6 671.3 672.4 690.9 692.0 692.5 9641.4 10086.4 10258.7 10345.4 10349.8 660.0 672.7 693.8 696.0 702.9 4044.1 4281.4 4838.8 4902.3 4956.5 105086.5 105203.9 105426.0 106291.0 106353.0 79937.6 91117.0 94266.0 94566.5 97153.0 81545.6 82159.0 82273.2 85180.0 85458.0 9658.1 9717.9 9761.5 9784.0 9825.8 53569.0 54508.7 54721.2 55644.4 56710.4 71372.0 71583.0 72853.3 73715.6 75264.0 11425.7 11483.4 11506.3 11700.2 11870.5 12455.1 12582.9 12790.6 13212.7 13366.9 11530.8 11621.5 11948.6 12023.2 12345.5 11621.7 11911.6 11933.9 11935.3 12321.9 11415.9 11822.9 11862.5 12120.5 12210.6 9760.9 9948.2 9956.3 10113.7 10236.6 9788.7 9822.3 9826.4 9832.0 9858.4 09:53:48 INFO - PROCESS | 1638 | -------- Summary: end -------- 09:53:48 INFO - PROCESS | 1638 | 09:53:48 INFO - TEST-INFO | 1638: exit 0 09:53:48 INFO - TEST-OK | dromaeo_css | took 1115620ms 09:53:48 INFO - TEST-START | kraken 09:53:48 INFO - operating with platform_type : mac_ 09:53:48 INFO - Initialising browser for kraken test... 09:53:48 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpcP_LAP/profile http://localhost:49242/getInfo.html) 09:53:51 INFO - PROCESS | 1650 | 2017-02-20 09:53:51.545 plugin-container[1651:9840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:53:51 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:51 INFO - PROCESS | 1650 | 2017-02-20 09:53:51.549 plugin-container[1651:9840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1f, name = 'com.apple.CFPasteboardClient' 09:53:51 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:51 INFO - PROCESS | 1650 | 2017-02-20 09:53:51.549 plugin-container[1651:9840] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:51 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 09:53:51 INFO - PROCESS | 1650 | colorDepth:24 09:53:51 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 09:53:51 INFO - PROCESS | 1650 | __metrics 09:53:51 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:53:52 INFO - TEST-INFO | 1650: exit 0 09:53:52 INFO - Browser initialized. 09:53:52 INFO - Running cycle 1/1 for kraken test... 09:53:52 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpcP_LAP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:54 INFO - PROCESS | 1652 | 2017-02-20 09:53:54.850 plugin-container[1653:9969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 09:53:54 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:54 INFO - PROCESS | 1652 | 2017-02-20 09:53:54.850 plugin-container[1653:9969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 09:53:54 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:54 INFO - PROCESS | 1652 | 2017-02-20 09:53:54.850 plugin-container[1653:9969] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:54:04 INFO - PROCESS | 1652 | RSS: Main: 219164672 09:54:04 INFO - PROCESS | 1652 | 09:54:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:54:08 INFO - PROCESS | 1652 | RSS: Main: 233492480 09:54:08 INFO - PROCESS | 1652 | 09:54:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:54:12 INFO - PROCESS | 1652 | RSS: Main: 241770496 09:54:12 INFO - PROCESS | 1652 | 09:54:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:54:15 INFO - PROCESS | 1652 | RSS: Main: 245436416 09:54:15 INFO - PROCESS | 1652 | 09:54:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:54:18 INFO - PROCESS | 1652 | RSS: Main: 245633024 09:54:18 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:54:20 INFO - PROCESS | 1652 | RSS: Main: 245694464 09:54:20 INFO - PROCESS | 1652 | 09:54:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:54:24 INFO - PROCESS | 1652 | RSS: Main: 249786368 09:54:24 INFO - PROCESS | 1652 | 09:54:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:54:28 INFO - PROCESS | 1652 | RSS: Main: 250667008 09:54:28 INFO - PROCESS | 1652 | 09:54:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:54:31 INFO - PROCESS | 1652 | RSS: Main: 250785792 09:54:31 INFO - PROCESS | 1652 | 09:54:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:54:33 INFO - PROCESS | 1652 | RSS: Main: 249339904 09:54:33 INFO - PROCESS | 1652 | 09:54:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:54:36 INFO - PROCESS | 1652 | RSS: Main: 249516032 09:54:36 INFO - PROCESS | 1652 | 09:54:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:54:39 INFO - PROCESS | 1652 | RSS: Main: 249999360 09:54:39 INFO - PROCESS | 1652 | 09:54:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:54:43 INFO - PROCESS | 1652 | RSS: Main: 250064896 09:54:43 INFO - PROCESS | 1652 | 09:54:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:54:47 INFO - PROCESS | 1652 | RSS: Main: 247447552 09:54:47 INFO - PROCESS | 1652 | 09:54:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:54:49 INFO - PROCESS | 1652 | RSS: Main: 249217024 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | __start_tp_report 09:54:49 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:54:49 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:54:49 INFO - PROCESS | 1652 | |i|pagename|runs| 09:54:49 INFO - PROCESS | 1652 | |0;ai-astar;109;119;119;124;169;101;174;175;165;117 09:54:49 INFO - PROCESS | 1652 | |1;audio-beat-detection;158;148;143;150;113;115;114;115;132;114 09:54:49 INFO - PROCESS | 1652 | |2;audio-dft;195;200;199;196;198;201;198;198;198;193 09:54:49 INFO - PROCESS | 1652 | |3;audio-fft;80;59;59;87;59;60;59;60;71;60 09:54:49 INFO - PROCESS | 1652 | |4;audio-oscillator;88;71;84;73;67;67;82;69;70;82 09:54:49 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;90;88;90;80;91;87;91;78;137;118 09:54:49 INFO - PROCESS | 1652 | |6;imaging-darkroom;95;96;97;95;100;96;100;156;101;154 09:54:49 INFO - PROCESS | 1652 | |7;imaging-desaturate;113;115;93;93;91;93;92;92;94;91 09:54:49 INFO - PROCESS | 1652 | |8;json-parse-financial;62;62;62;64;64;64;65;64;62;63 09:54:49 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;69;74;72;82;72;72;71;72;71;68 09:54:49 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;85;104;108;82;86;85;88;83;83;107 09:54:49 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;148;150;149;136;147;137;143;145;196;146 09:54:49 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;194;232;231;189;173;154;208;193;215;232 09:54:49 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;69;67;70;67;72;64;62;86;61;83 09:54:49 INFO - PROCESS | 1652 | __end_tp_report 09:54:49 INFO - PROCESS | 1652 | __start_cc_report 09:54:49 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,986 09:54:49 INFO - PROCESS | 1652 | __end_cc_report 09:54:49 INFO - PROCESS | 1652 | __startTimestamp1487613289015__endTimestamp 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:54:49 INFO - PROCESS | 1652 | Number of tests: 14 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:137.20 Median:144.50 stddev:29.66 (20.5%) stddev-sans-first:29.65 09:54:49 INFO - PROCESS | 1652 | Values: 109.0 119.0 119.0 124.0 169.0 101.0 174.0 175.0 165.0 117.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:137.50 stddev:18.05 (13.1%) stddev-sans-first:16.10 09:54:49 INFO - PROCESS | 1652 | Values: 158.0 148.0 143.0 150.0 113.0 115.0 114.0 115.0 132.0 114.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:197.60 Median:198.00 stddev:2.37 (1.2%) stddev-sans-first:2.32 09:54:49 INFO - PROCESS | 1652 | Values: 195.0 200.0 199.0 196.0 198.0 201.0 198.0 198.0 198.0 193.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.00 stddev:10.34 (17.2%) stddev-sans-first:9.52 09:54:49 INFO - PROCESS | 1652 | Values: 80.0 59.0 59.0 87.0 59.0 60.0 59.0 60.0 71.0 60.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:77.50 stddev:7.86 (10.1%) stddev-sans-first:6.86 09:54:49 INFO - PROCESS | 1652 | Values: 88.0 71.0 84.0 73.0 67.0 67.0 82.0 69.0 70.0 82.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:89.00 stddev:18.26 (20.5%) stddev-sans-first:19.28 09:54:49 INFO - PROCESS | 1652 | Values: 90.0 88.0 90.0 80.0 91.0 87.0 91.0 78.0 137.0 118.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:109.00 Median:95.00 stddev:24.34 (25.6%) stddev-sans-first:25.29 09:54:49 INFO - PROCESS | 1652 | Values: 95.0 96.0 97.0 95.0 100.0 96.0 100.0 156.0 101.0 154.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:92.50 stddev:9.18 (9.9%) stddev-sans-first:7.61 09:54:49 INFO - PROCESS | 1652 | Values: 113.0 115.0 93.0 93.0 91.0 93.0 92.0 92.0 94.0 91.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:64.00 stddev:1.14 (1.8%) stddev-sans-first:1.12 09:54:49 INFO - PROCESS | 1652 | Values: 62.0 62.0 62.0 64.0 64.0 64.0 65.0 64.0 62.0 63.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:72.00 stddev:3.80 (5.3%) stddev-sans-first:3.84 09:54:49 INFO - PROCESS | 1652 | Values: 69.0 74.0 72.0 82.0 72.0 72.0 71.0 72.0 71.0 68.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:84.00 stddev:10.69 (12.7%) stddev-sans-first:11.11 09:54:49 INFO - PROCESS | 1652 | Values: 85.0 104.0 108.0 82.0 86.0 85.0 88.0 83.0 83.0 107.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:147.50 stddev:16.94 (11.5%) stddev-sans-first:17.96 09:54:49 INFO - PROCESS | 1652 | Values: 148.0 150.0 149.0 136.0 147.0 137.0 143.0 145.0 196.0 146.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.10 Median:211.50 stddev:26.47 (12.5%) stddev-sans-first:27.91 09:54:49 INFO - PROCESS | 1652 | Values: 194.0 232.0 231.0 189.0 173.0 154.0 208.0 193.0 215.0 232.0 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:69.50 stddev:8.36 (12.0%) stddev-sans-first:8.86 09:54:49 INFO - PROCESS | 1652 | Values: 69.0 67.0 70.0 67.0 72.0 64.0 62.0 86.0 61.0 83.0 09:54:49 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:54:49 INFO - PROCESS | 1652 | 09:54:49 INFO - TEST-INFO | 1652: exit 0 09:54:49 INFO - TEST-OK | kraken | took 61346ms 09:54:49 INFO - SUITE-END | took 1176s 09:54:50 INFO - Completed test suite (00:19:37) 09:54:50 INFO - javascript benchmark 09:54:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7227.631334828862, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2781.2351603309075, 2828.6039954406374, 3357.143928298284, 3364.125327414873, 3411.8805841795534, 4728.338032675424, 5728.398518155591, 5763.740631671574, 5765.884682306351, 5867.413258674131, 5695.74369153388, 5774.220480235167, 5793.449622285887, 5819.301683797939, 5838.0, 4164.375343698439, 4593.517680643517, 5261.368635763713, 5262.526372626472, 5316.175992721134, 5302.575793936476, 5324.973375133119, 5339.119045357511, 5350.30446042014, 5354.116570765838, 5136.383633963929, 5340.439253878326, 5346.4118946915805, 5350.30446042014, 5397.163439666858, 5150.639455238116, 5212.244224587452, 5219.164933610604, 5260.0, 5329.2006199070065, 5109.131447653871, 5248.107821670325, 5248.317718696583, 5257.605679574009, 5287.735613219324, 5737.483626473638, 5790.7973220937065, 5903.527717782567, 5904.675242861641, 5937.762489500415, 5091.949080509185, 6154.7230374633245, 6162.106494558308, 6223.751049957997, 6242.250929888397, 6304.401081897213, 6306.621602703853, 6314.116023756677, 6327.019312006646, 6357.141785858896, 5869.735861886225, 5888.617239879394, 5907.379725128885, 5974.074018527135, 6025.126356678257, 5160.612954028463, 6165.722542485599, 6177.69111544421, 6227.719752611143, 6302.810915672537, 6147.44672979434, 6165.938340616536, 6199.876002480015, 6205.875882482325, 6211.81364559064, 6259.280182778948, 6278.717457714414, 6291.842703932438, 6292.968535157386, 6293.118963345135, 6079.148919151322, 6167.414095660905, 6203.627782333075, 6262.405071518198, 6280.780172693934, 6188.350223226496, 6287.276963149205, 6303.243610766737, 6307.747690092345, 6308.621482711052, 5023.196288593807, 5032.119379108642, 5042.672226305279, 5047.545720885138, 5051.1413059779925, 4407.316865885793, 4416.205083085076, 4468.329750537446, 4487.775611219426, 4508.45898492183, 5872.618279811799, 5905.616134951214, 5932.169496270526, 5987.580869339192, 6037.003894357382, 5673.290838645169, 6222.160008398899, 6244.375562443719, 6260.092286618415, 6282.340354262828, 5710.114932185518, 5777.24895763548, 5851.502622277154, 5875.383084776123, 5915.674637894957, 5495.368032676213, 5517.475839795213, 5523.088690366045, 5524.558035357162, 5539.695316757617, 6324.367563243731, 6367.3314301997625, 6367.586106903036, 6392.968035159794, 6459.838504037342, 6169.598976066542, 6217.440430361289, 6266.561340706289, 6274.780382686584, 6297.433231009231, 6226.40849119333, 6228.4705800005695, 6240.0, 6326.936730632819, 6332.050192471166, 6195.721192546436, 6204.5967012144065, 6208.2860471044605, 6218.471429928507, 6222.191115155001, 6148.415900489446, 6177.413145751147, 6296.307406185224, 6296.905546416899, 6297.023961286008, 6077.756889724538, 6111.682900513672, 6113.388661133851, 6153.938460615336, 6222.751089956351, 6131.478824299806, 6171.352008039091, 6189.535784816067, 6197.96901015492, 6215.9432896406815, 5979.402059793986, 5994.340622531431, 6006.0, 6055.848603784941, 6066.272047354169, 5698.0, 5877.911831322448, 6032.065029920369, 6105.145279660762, 6129.540284478771, 5158.819441319536, 5991.970040149771, 6011.72947217385, 6030.5778595497895, 6050.30421501533], "value": 5711.7415873640575, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.89202699325176, 458.8669285907087, 502.06615694807675, 520.7916833266694, 530.8142150247412, 34922.47616285754, 36960.81519592405, 37109.44335834964, 37125.0, 37140.25719485615, 38322.4251636225, 38669.806650966704, 38703.61296387035, 38759.03102422441, 39014.21971560567, 8446.282066024365, 9285.164335209847, 9459.905400946005, 9481.33630645855, 9492.81014379712, 8640.567971601426, 11243.100551955824, 11531.0, 11552.075833933266, 11647.592334268302, 9789.0, 11471.59849405271, 11526.884731152706, 11539.942300288529, 11540.076793856513, 11429.257098288626, 11493.195476316661, 11497.540098396054, 11517.48171332292, 11523.769524609545, 9666.95166524163, 11312.94343528277, 11337.0, 11388.60139895108, 11397.829032564518, 38271.23457530862, 39407.60592394068, 40518.0, 40671.18657626831, 40820.0, 3919.7648141111627, 3940.4089386591954, 3981.9601803981877, 4012.7592344459426, 4044.2518134144984, 23537.646935295983, 30166.396672066654, 30327.545086823717, 30456.390872182656, 30611.38777224443, 3158.8894388696285, 3164.4462219111565, 3191.2181515528846, 3193.96806031939, 3200.0399880035898, 452939.0, 454326.0, 456241.9999999967, 457428.0, 457854.0, 57894.0, 60618.0, 61063.389366105774, 61066.69466652638, 61227.38772612217, 63362.683186583774, 63433.36566634275, 63488.73022539524, 63536.0, 63758.36241637617, 15080.773788393182, 15381.846181538265, 15393.383694711894, 15400.845991540165, 15443.691126177413, 9035.548222588845, 11037.392943388191, 11380.0, 11568.190226684219, 11752.0, 15494.612634684223, 15618.531444056696, 16031.839681603034, 16035.11806850611, 16135.19324033817, 6857.417119544894, 7074.7170113195925, 7448.217937116633, 7525.623718814038, 7534.623268836646, 874.2612492443875, 890.701614958996, 891.3404080980029, 911.2254583603884, 926.9814603707888, 30179.849100754356, 30186.396272074435, 30186.54720179199, 30201.848990755345, 30530.54204186939, 1022.728976821143, 1029.799189158107, 1100.5817789240039, 1122.1639878290725, 1158.2529268621693, 12888.677783055311, 15519.913606047468, 15852.96955697876, 15878.761818572731, 16312.429064982669, 626569.0, 627308.0, 628289.0, 629249.0, 634236.0, 60421.395786042456, 60705.69647151736, 60715.08927366093, 60947.78104437886, 61017.779644406866, 1432.8638779315947, 1447.3631602095045, 1466.7359875222348, 1467.39103272143, 1467.6037469883072, 23928.88035559811, 29149.417011660073, 29582.704172957994, 30161.69838301589, 30258.394832103673, 49716.50283497119, 49755.25367119424, 50076.0, 50256.0, 50358.0, 541030.0, 542330.0, 542393.0, 543116.0, 544219.0, 24443.38891527726, 25340.11309604229, 25374.492510150063, 26131.346716332242, 26589.335266617716, 40961.590384095776, 42913.35629965615, 43580.91047723832, 43733.34399983939, 44088.0, 35260.647393526764, 35277.47083793694, 35649.10877227986, 35683.46474802828, 36802.447963280036, 35123.82438087793, 35437.46843797397, 36100.27799443944, 36195.63804361923, 36450.0, 34368.82815585906, 34626.134346641535, 34735.652643473244, 35420.11449713778, 35478.0, 26631.600525991737, 29812.403751924405, 31495.055148345586, 31799.364012719154, 31909.36181276408, 23768.40578985539, 23942.664401507795, 23958.640620391056, 24280.271591852274, 24320.0], "value": 19625.925710934283, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.4122463243551, 388.56868875548156, 397.15009878859246, 397.8826246257354, 418.0927387568975, 13504.729905401886, 13690.17858928465, 16799.916000419995, 17873.55316117096, 17891.463256102306, 17242.137893105326, 20844.332453403727, 21094.57810843782, 21692.132314707393, 22025.449363765914, 5152.716600586966, 5535.557155427577, 5597.188407680884, 5702.030654788696, 5757.884842303151, 5694.800681976131, 5755.45323194298, 5798.884022319551, 5801.970990145064, 5815.156802263669, 5552.888942221153, 5746.655200687951, 5757.395473475288, 5811.6803575803315, 5841.970790146064, 5775.509081728058, 5783.971080144573, 5803.738831752581, 5820.243368362107, 5825.999999999979, 5554.1946417769195, 5768.394318596529, 5805.7097145143, 5805.941940580625, 5814.534837213013, 12616.873831261753, 12728.490860365575, 12760.234385936874, 21816.781832181634, 23587.88206058976, 2538.669972903529, 2620.2532278300782, 2632.3419145213697, 2665.8533780642056, 2681.4637072585365, 8698.869516957251, 9712.320137590372, 9799.510024498746, 10195.592176312939, 10293.53679084443, 1805.2147315917487, 1807.6836553603198, 1810.7646006019133, 1816.9370918012846, 1821.5992481654016, 121326.39336803347, 131497.0, 135859.32070339585, 136143.31928340395, 139139.99999999898, 11432.199746017865, 12103.33431661267, 12276.693082672826, 12308.692282693004, 12330.63008109758, 15675.921620392053, 15801.841981580266, 15827.604309892346, 15831.52505424816, 15847.128407937675, 8250.092489826115, 8451.771084577418, 8466.365022623328, 8469.830603387898, 8593.398462107592, 7395.445341599401, 8079.070906845788, 8209.466384684976, 8217.83564328722, 8234.300084492885, 4727.24364101749, 4785.59322457588, 4793.1372352976105, 4863.294822250825, 4884.877878053077, 1784.4022252545542, 1942.756635753119, 2061.5361543652857, 2062.257587268582, 2066.121898193262, 812.9959350203211, 846.6190214403543, 876.6449587916904, 880.8161234137846, 886.1714297132202, 16233.83766162347, 17914.641707165785, 20234.898825506072, 21262.362129136152, 21301.939608107878, 771.9614019299013, 790.4348390900534, 865.718641441534, 917.7567944494714, 919.797644518205, 8310.878031465796, 8435.662573496991, 10228.897711022943, 10828.891711082946, 11311.773764524663, 723310.0, 732969.0, 736093.0, 740919.2954035195, 759017.0, 121741.0, 127347.0, 146491.0, 146926.0, 154492.27538623248, 932.7714709896119, 969.9224062075017, 995.875515560555, 997.6408492942535, 1004.1213937804275, 19612.215511379385, 19953.301634442723, 20246.291379801638, 20294.492637684176, 20376.79623203749, 59829.0, 59938.0, 59989.40010599838, 60695.0, 60718.392816071275, 83490.0, 85791.0, 88684.0, 89505.0, 89537.0, 8257.0, 8362.038365587792, 8376.12050734664, 8543.718442233714, 8579.571021448777, 12145.453454594388, 12951.481940722266, 12999.480020799063, 13017.739645206853, 13297.335133243492, 10860.728481788019, 11071.0, 11317.773644526898, 11453.396524486745, 11547.538098475967, 10876.945615271872, 11006.95433933775, 11057.72355691082, 11367.033802126913, 11381.43092845338, 11038.834417483904, 11271.887281127083, 11299.3785341807, 11528.365939873052, 11737.237079589799, 6552.3447655236005, 8256.215659512336, 8338.541380224146, 8579.87130193035, 8639.524826134622, 8231.09457959636, 10693.14454843595, 11013.229073964745, 11120.443977800916, 11206.935341142578], "value": 9562.18116232354, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.7536431124552, 1430.5565274764824, 1464.5167094858698, 1472.5508719840452, 1474.3291802229983, 7459.4032477401815, 7737.994060772092, 7982.960085199573, 8436.915630843698, 8447.281981031612, 7864.96067519663, 8148.7147949821765, 8165.142660020701, 8262.834743305146, 8271.627776750045, 2198.835087368446, 2245.8427910046303, 2252.031626400655, 2252.234240358278, 2253.098760495807, 2256.379495638696, 2271.931842044741, 2276.6926464927187, 2283.942901427461, 2284.8743319117443, 2143.582001509706, 2151.3008272311563, 2168.609650262952, 2170.880601566921, 2171.8045375916163, 2154.924577639775, 2160.2655097266925, 2171.294329342962, 2181.6727490876497, 2184.1263494602126, 2211.6461366181334, 2271.7273927128686, 2273.079402841852, 2273.7044184256106, 2279.5099053703534, 8468.237858592756, 8502.78743031429, 8527.232549070613, 8543.060263371024, 8543.957280213559, 4996.775145118479, 5146.6911985281, 5149.742512874342, 5154.9484505155215, 5156.639035267533, 3330.8501117449773, 3393.541871847294, 3535.9469607955903, 3582.731295152874, 3602.279544091166, 1223.528941357575, 1232.617888454573, 1742.6514697060638, 1778.342013455017, 1856.6565185440736, 10365.118964887919, 10795.5681772729, 10799.028087472165, 10807.027367536959, 10865.565377384895, 4438.112377524508, 4518.932216016762, 4553.1121431321235, 4569.9314510282375, 4570.81716731327, 8730.214280714767, 8755.56222188888, 8789.736307910773, 8793.692220772242, 8806.77983050416, 5024.422191447957, 5042.067217564762, 5043.949560504421, 5050.393952725696, 5059.56993655536, 4574.039451715111, 4693.929591056137, 4696.272077827942, 4730.361401210793, 4775.140474714584, 3499.107727529464, 3547.166415892263, 3673.0, 3781.621837816196, 3870.4774855394726, 1110.2838669058574, 1189.5182451107316, 1193.242291145129, 1204.9216800907914, 1204.9698757531132, 829.3903980574273, 879.0725784297499, 893.5398269891011, 908.8915907596038, 917.1974522292994, 5394.514493695587, 5554.389017208104, 5581.720913954286, 5583.469570390806, 5661.943380566224, 551.5670198893929, 559.9440055994368, 560.8177342363748, 565.0535353283234, 574.9626274292158, 3647.507586475845, 3704.870329538453, 3724.404095344786, 3811.104390468238, 3862.304785138646, 9586.185174260127, 9966.103050725469, 10005.399676019317, 10072.546735396925, 10159.4920253987, 9921.950390248003, 10021.849672254923, 10027.548760305805, 10048.648297309559, 10070.798584028278, 6501.8699626008165, 6815.250322464525, 7927.841443171104, 7958.124606293303, 7972.720954766672, 5328.307320048369, 5375.3012108425655, 5464.371597266207, 5507.146392309199, 5535.446455354432, 7790.675585150651, 9047.050059743633, 9163.725088247364, 9176.0, 9234.538273086453, 6767.883299255568, 8052.597370131587, 9247.676331328372, 9369.484678342757, 9494.905050949403, 6492.253390860112, 6728.0, 6734.696938637675, 6738.696758645775, 6767.830804229737, 7593.658285377061, 7771.92228077712, 7860.096088949844, 7871.48835325702, 7961.6019199040975, 6295.842603934938, 7054.224035356209, 7163.3552980232025, 7219.205887352282, 7261.891071634035, 6580.025992202416, 6708.396244338033, 7139.607321597362, 7182.0, 7255.709771609288, 6318.462930650762, 6390.584612000205, 6432.485401167989, 6471.255805582418, 6476.676166191577, 4007.0, 4262.232798096248, 4983.028309479617, 4999.0251900878975, 5036.219385995177, 3973.384125460558, 4861.465238823797, 5051.924221136759, 5085.677723791915, 5150.48495150482], "value": 4298.4749457989965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.4017007006408, 355.7065421027653, 365.4572959155655, 368.5006815764637, 372.1737742212289, 4511.368408422823, 5083.898322033557, 5520.101946478101, 5547.9736248794, 5567.9977604031255, 5368.758405871736, 5382.0, 5399.784008639659, 5400.243965844785, 5443.863903402407, 3656.945145822815, 3911.119998000456, 4004.9799751001206, 4173.478315210598, 4254.595813397722, 3488.7732297400717, 3628.365036118682, 4199.958000419987, 4242.215190189825, 4242.257604919142, 3730.992631989361, 4018.3771515415156, 4234.767087810169, 4238.088810905655, 4261.637760790337, 4026.295398305285, 4189.486308215077, 4241.300185469409, 4245.299525578276, 4289.270823959934, 3245.7727959042877, 3711.6102809204913, 3723.1250656095795, 3723.7393382463238, 3723.832427540767, 5364.91952620711, 5367.677939323614, 5369.40936496985, 5370.248165256867, 5374.516293533562, 4429.557044295577, 4673.766311684436, 4967.403911530603, 4983.775730092155, 4999.275105109738, 5775.422457754233, 5794.768209271624, 5798.217240672498, 5816.418358164192, 5818.429024163494, 3891.2606604745292, 3900.941485877714, 3902.765834049938, 3908.511436070491, 3908.9413658795143, 4626.097910907408, 5556.444355564452, 5825.912611310834, 6062.696865156769, 6157.96921015392, 3217.0, 3770.377887648507, 3977.8607748728655, 3978.5225772906856, 3983.083890705154, 3573.8391772370305, 4026.5570787213383, 4032.6169013943627, 4043.211573743151, 4055.8986025349013, 4362.17118747437, 4604.861854144381, 4685.8594242172785, 4718.386609740712, 4734.75669166622, 5075.974620126875, 5100.566451851634, 5761.020626493506, 5812.418758124239, 5850.268716410449, 1101.4194630704965, 1175.6590588729173, 1264.8735126487277, 1315.3886489050835, 1332.4536939854613, 810.6352141536332, 835.6782638684028, 885.3758100539234, 888.7912439082842, 893.5710858787818, 3904.5509766376663, 5290.761915713802, 5404.162354835007, 5415.593830462731, 5424.3691809472375, 5122.0, 5348.411674715778, 5482.780688772485, 5951.315598706117, 5985.491233245136, 4401.449818772653, 5122.874007287009, 5466.562674986071, 5467.1799230115, 5467.917981230285, 2632.8288661236957, 2678.504476671783, 2970.0, 2974.598429212072, 3017.125033740235, 5838.970805146032, 5899.439553242435, 5939.406059394112, 5945.137954996588, 6006.729697163638, 5654.134135735198, 5896.852578685482, 6101.969490152521, 6134.938650613437, 6138.324784273726, 5266.526012658956, 5475.795325028487, 5987.982043052692, 6185.84535386619, 6190.969045154746, 4861.399151454097, 5128.461511541234, 5603.859903502282, 5692.544596432358, 6093.4461712264865, 4610.631149508098, 5919.822405327847, 5961.672108034099, 5981.3719559445635, 5988.700564971647, 6105.908411373922, 6167.784127555514, 6193.473554747717, 6217.316095229431, 6282.748690052346, 3356.942563092618, 3391.4404123319378, 3902.6535845133385, 3927.2341893330527, 3971.7687516869864, 5027.818462661198, 5834.299884013945, 6009.94825905456, 6016.77379369636, 6018.495376155961, 5313.884084342331, 5667.227662942744, 5802.216700745345, 5833.445822646841, 5862.853428664317, 4306.50475195344, 4495.977520112378, 4896.583790377716, 4921.335619691439, 5759.366469688247, 4968.453470118357, 5425.182563840999, 5651.717414129359, 5660.915086273627, 5671.319441667027, 4541.72749635023, 5455.1271796512365, 5503.147012213113, 5661.915071273852, 5731.541476681772, 5606.299212598426, 5629.6903670298525, 5641.5204707600315, 5653.858653533694, 5659.377468478382], "value": 4175.291466900891, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.4218277738089, 698.1656919980625, 762.3596179209463, 798.5178873951078, 800.4324517335265, 14467.927660361696, 14701.485448009322, 15155.545333639995, 15327.616809579738, 15348.15585142818, 13795.862041379583, 14036.789448158286, 14055.508057218, 14061.578152655411, 15136.848631513682, 861.6338056326052, 886.5301390263138, 887.7203680840547, 888.8933328000649, 889.2219308105408, 845.7927807687095, 875.1729615513342, 883.8320719063389, 884.1114679746845, 886.1847100667402, 931.3061768982118, 934.2059249637788, 934.700895713372, 938.8122375524888, 939.4316438554679, 906.9455832650062, 927.4759760735196, 930.1303281431883, 933.0529512544772, 934.2572654739464, 695.987338422599, 699.2343383994518, 699.4369532526314, 699.8705239530652, 701.4809041309445, 15031.248437578186, 15289.388424463008, 15694.979826311253, 15723.842761572352, 15910.363585456567, 14035.087719298213, 14217.004809663327, 14239.85760142406, 14275.928620356832, 14311.0, 11354.772904541946, 11390.658280251522, 11426.714332141762, 11592.362420066893, 12030.037596992219, 3113.237256872058, 3267.5098735189913, 3316.005198440458, 3383.7631365804405, 3407.2333724911946, 101125.0, 108273.0, 108313.0, 109106.45446772795, 110597.44701276523, 9076.8638470423, 10666.093382062612, 10780.29928054674, 11253.212275140826, 11292.0, 20905.53661243729, 21748.782512174992, 21783.12867485283, 21823.0, 22002.559948800936, 7640.121386040565, 7694.038245219348, 7754.7673569793, 7759.806004849811, 7946.562939038408, 3569.607343192247, 4191.769452680071, 4262.936055959163, 4295.978520107442, 4316.287812510964, 3627.546556680415, 3719.2189640175325, 3724.1620635356826, 3729.291434627412, 3733.216024634857, 553.2116733654526, 653.4804830160034, 660.4089340040733, 664.8836453620694, 684.5926673629183, 671.2549070531705, 672.4217173231017, 690.8549204666975, 691.9688614012382, 692.5221597097986, 9641.42151470914, 10086.394816311045, 10258.743531411625, 10345.37927724339, 10349.844752328721, 659.970301336441, 672.7174586673511, 693.8352735941098, 696.0429409561852, 702.9086218791524, 4044.090079732037, 4281.409538040095, 4838.758062096951, 4902.264660300983, 4956.529129732669, 105086.4745676282, 105203.94796052095, 105426.0, 106291.0, 106353.0, 79937.60031199807, 91117.0, 94266.0, 94566.52716736372, 97153.0, 81545.59227203827, 82159.0, 82273.17726822656, 85180.0, 85458.0, 9658.082482164184, 9717.85423218638, 9761.463119528495, 9784.0, 9825.803483930138, 53569.0, 54508.72745636247, 54721.1791823115, 55644.443555565544, 56710.432895670514, 71372.0, 71583.0, 72853.27146728677, 73715.63142184255, 75264.0, 11425.714357141138, 11483.368414736935, 11506.252093613888, 11700.180987330805, 11870.465829037887, 12455.065870059601, 12582.87417125817, 12790.552330668383, 13212.669683257995, 13366.866331336561, 11530.82703759461, 11621.535138594361, 11948.581799636971, 12023.158378913393, 12345.506179752709, 11621.709457263636, 11911.626294149632, 11933.940330298294, 11935.283882967049, 12321.876781232073, 11415.942920285346, 11822.881771182178, 11862.525498979945, 12120.454579544117, 12210.633680989584, 9760.85358719634, 9948.154406875206, 9956.303058785816, 10113.69658910234, 10236.590536378462, 9788.706338809847, 9822.263330250116, 9826.361286516354, 9831.950840245752, 9858.359206651545], "value": 7409.218117608826, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 119.0, 124.0, 169.0, 101.0, 174.0, 175.0, 165.0, 117.0], "value": 137.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 148.0, 143.0, 150.0, 113.0, 115.0, 114.0, 115.0, 132.0, 114.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 199.0, 196.0, 198.0, 201.0, 198.0, 198.0, 198.0, 193.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 87.0, 59.0, 60.0, 59.0, 60.0, 71.0, 60.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 84.0, 73.0, 67.0, 67.0, 82.0, 69.0, 70.0, 82.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 80.0, 91.0, 87.0, 91.0, 78.0, 137.0, 118.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 95.0, 100.0, 96.0, 100.0, 156.0, 101.0, 154.0], "value": 109.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 93.0, 93.0, 91.0, 93.0, 92.0, 92.0, 94.0, 91.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 64.0, 64.0, 64.0, 65.0, 64.0, 62.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 72.0, 82.0, 72.0, 72.0, 71.0, 72.0, 71.0, 68.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 104.0, 108.0, 82.0, 86.0, 85.0, 88.0, 83.0, 83.0, 107.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 149.0, 136.0, 147.0, 137.0, 143.0, 145.0, 196.0, 146.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 232.0, 231.0, 189.0, 173.0, 154.0, 208.0, 193.0, 215.0, 232.0], "value": 202.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 67.0, 72.0, 64.0, 62.0, 86.0, 61.0, 83.0], "value": 70.1, "unit": "score"}]}]} 09:54:50 INFO - Return code: 0 09:54:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:54:50 ERROR - # TBPL SUCCESS # 09:54:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:54:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:54:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:54:50 INFO - 2017-02-20 09:54:50.300 screenresolution[1657:10361] starting screenresolution argv=screenresolution get 09:54:50 INFO - 2017-02-20 09:54:50.303 screenresolution[1657:10361] Display 0: 1600x1200x32@60 09:54:50 INFO - 2017-02-20 09:54:50.309 screenresolution[1658:10364] starting screenresolution argv=screenresolution list 09:54:50 INFO - Available Modes on Display 0 09:54:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:54:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:54:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:54:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:54:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:54:50 INFO - Intel Iris: 09:54:50 INFO - Chipset Model: Intel Iris 09:54:50 INFO - Type: GPU 09:54:50 INFO - Bus: Built-In 09:54:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:54:50 INFO - Vendor: Intel (0x8086) 09:54:50 INFO - Device ID: 0x0a2e 09:54:50 INFO - Revision ID: 0x0009 09:54:50 INFO - Displays: 09:54:50 INFO - Display: 09:54:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:54:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:54:50 INFO - Display Serial Number: 1600x1200 60 09:54:50 INFO - Main Display: Yes 09:54:50 INFO - Mirror: Off 09:54:50 INFO - Online: Yes 09:54:50 INFO - Rotation: Supported 09:54:50 INFO - Return code: 0 09:54:50 INFO - Running post-action listener: _resource_record_post_action 09:54:50 INFO - [mozharness: 2017-02-20 17:54:50.559521Z] Finished run-tests step (success) 09:54:50 INFO - Running post-run listener: _resource_record_post_run 09:54:50 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 38803968; Write bytes: 510115328; Read time: 249; Write time: 1624 09:54:50 INFO - TinderboxPrint: CPU usage
54.4% 09:54:50 INFO - TinderboxPrint: I/O read bytes / time
38,803,968 / 249 09:54:50 INFO - TinderboxPrint: I/O write bytes / time
510,115,328 / 1,624 09:54:50 INFO - TinderboxPrint: CPU idle
2,183.2 (45.6%) 09:54:50 INFO - TinderboxPrint: CPU system
968.7 (20.2%) 09:54:50 INFO - TinderboxPrint: CPU user
1,637.7 (34.2%) 09:54:50 INFO - TinderboxPrint: Swap in / out
246,861,824 / 0 09:54:50 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208943616; Write bytes: 201721344; Read time: 16010; Write time: 442 09:54:50 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35191296; Write bytes: 298305536; Read time: 224; Write time: 1147 09:54:51 INFO - Running post-run listener: _upload_blobber_files 09:54:51 INFO - Blob upload gear active. 09:54:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:51 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 09:54:51 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'] 09:54:51 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 09:54:51 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:54:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:54:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:52 INFO - (blobuploader) - INFO - Done attempting. 09:54:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:54:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:54:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:53 INFO - (blobuploader) - INFO - Done attempting. 09:54:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:54:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:54:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:54 INFO - (blobuploader) - INFO - Done attempting. 09:54:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:54:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:54:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:56 INFO - (blobuploader) - INFO - Done attempting. 09:54:56 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:56 INFO - Return code: 0 09:54:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b81458375810d190e622a020fb7e9e9c79e46db6eb9b969164dc45a5900b337bc509ea141d0e0477faa6c801b44212058dd6787841090db9fb5612afdd90cf4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19958bcd940a6d97c8e4b6f664e16a30d27c1e85c8eb344354fd67d8bd1b004965953540b8e7d2b65b690c7d8c15a874b2dfcf3fd6cf458e4901748be3cfa90b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64ad74a5954fe369bc220c2b92bafe5209c2da6783851e71407d300532a1e20262f637ab4e52b12fb9e4bf48c764c6920c59b9bf10d47ded78d422171729ff86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6bb32cf18b49aea5a6240aa2fcf37d42cf7e57a135d2617b07a371e9ae24ed146182047a8ce257fbf533e854ce1e45f876d63641687fbedf22cce2f4e731cea3"} 09:54:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:56 INFO - Contents: 09:54:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b81458375810d190e622a020fb7e9e9c79e46db6eb9b969164dc45a5900b337bc509ea141d0e0477faa6c801b44212058dd6787841090db9fb5612afdd90cf4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19958bcd940a6d97c8e4b6f664e16a30d27c1e85c8eb344354fd67d8bd1b004965953540b8e7d2b65b690c7d8c15a874b2dfcf3fd6cf458e4901748be3cfa90b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64ad74a5954fe369bc220c2b92bafe5209c2da6783851e71407d300532a1e20262f637ab4e52b12fb9e4bf48c764c6920c59b9bf10d47ded78d422171729ff86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6bb32cf18b49aea5a6240aa2fcf37d42cf7e57a135d2617b07a371e9ae24ed146182047a8ce257fbf533e854ce1e45f876d63641687fbedf22cce2f4e731cea3"} 09:54:56 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:56 INFO - Copying logs to upload dir... 09:54:56 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.885078 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-20 09:54:56.841954) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 09:54:56.845301) ========= 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.a7wSQhjXHz/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.vedm70HLhn/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b81458375810d190e622a020fb7e9e9c79e46db6eb9b969164dc45a5900b337bc509ea141d0e0477faa6c801b44212058dd6787841090db9fb5612afdd90cf4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19958bcd940a6d97c8e4b6f664e16a30d27c1e85c8eb344354fd67d8bd1b004965953540b8e7d2b65b690c7d8c15a874b2dfcf3fd6cf458e4901748be3cfa90b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64ad74a5954fe369bc220c2b92bafe5209c2da6783851e71407d300532a1e20262f637ab4e52b12fb9e4bf48c764c6920c59b9bf10d47ded78d422171729ff86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6bb32cf18b49aea5a6240aa2fcf37d42cf7e57a135d2617b07a371e9ae24ed146182047a8ce257fbf533e854ce1e45f876d63641687fbedf22cce2f4e731cea3"} build_url:https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/X4oki7NNTKa3zMHDoWqG_g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b81458375810d190e622a020fb7e9e9c79e46db6eb9b969164dc45a5900b337bc509ea141d0e0477faa6c801b44212058dd6787841090db9fb5612afdd90cf4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19958bcd940a6d97c8e4b6f664e16a30d27c1e85c8eb344354fd67d8bd1b004965953540b8e7d2b65b690c7d8c15a874b2dfcf3fd6cf458e4901748be3cfa90b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64ad74a5954fe369bc220c2b92bafe5209c2da6783851e71407d300532a1e20262f637ab4e52b12fb9e4bf48c764c6920c59b9bf10d47ded78d422171729ff86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6bb32cf18b49aea5a6240aa2fcf37d42cf7e57a135d2617b07a371e9ae24ed146182047a8ce257fbf533e854ce1e45f876d63641687fbedf22cce2f4e731cea3"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 09:54:56.938382) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 09:54:56.938693) ========= 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.a7wSQhjXHz/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.vedm70HLhn/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.005151 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 09:54:56.987814) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 09:54:56.988129) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 09:54:56.988481) ========= ========= Total master_lag: 0.27 =========