builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0171 starttime: 1486019909.9 results: success (0) buildid: 20170202055853 builduid: 26455d3bd7f34302bf192eb4abc9e6ec revision: fe22af79bacf6db526737536eab551caf68ba440 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:29.896239) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:29.896637) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:29.896913) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:29.925671) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:29.925965) ========= 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.ntr24iVcq5/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.gB53lJs5Ty/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-01 23:18:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-02-01 23:18:30 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130891 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:30.088890) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:30.089172) ========= 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.ntr24iVcq5/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.gB53lJs5Ty/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.027426 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:30.138057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 23:18:30.138398) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fe22af79bacf6db526737536eab551caf68ba440 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fe22af79bacf6db526737536eab551caf68ba440 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ntr24iVcq5/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.gB53lJs5Ty/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-01 23:18:30,243 truncating revision to first 12 chars 2017-02-01 23:18:30,243 Setting DEBUG logging. 2017-02-01 23:18:30,243 attempt 1/10 2017-02-01 23:18:30,244 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fe22af79bacf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-01 23:18:31,555 unpacking tar archive at: mozilla-central-fe22af79bacf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.567044 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 23:18:31.731030) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:31.734350) ========= script_repo_revision: fe22af79bacf6db526737536eab551caf68ba440 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:31.734750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:31.735021) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 23:18:31.754075) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-01 23:18:31.754387) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ntr24iVcq5/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.gB53lJs5Ty/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:18:31 INFO - MultiFileLogger online at 20170201 23:18:31 in /builds/slave/test 23:18:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 23:18:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:18:31 INFO - {'append_to_log': False, 23:18:31 INFO - 'base_work_dir': '/builds/slave/test', 23:18:31 INFO - 'blob_upload_branch': 'Firefox', 23:18:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:18:31 INFO - 'branch': 'Firefox', 23:18:31 INFO - 'buildbot_json_path': 'buildprops.json', 23:18:31 INFO - 'config_files': ('talos/mac_config.py',), 23:18:31 INFO - 'default_actions': ('clobber', 23:18:31 INFO - 'read-buildbot-config', 23:18:31 INFO - 'download-and-extract', 23:18:31 INFO - 'populate-webroot', 23:18:31 INFO - 'create-virtualenv', 23:18:31 INFO - 'install', 23:18:31 INFO - 'run-tests'), 23:18:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:18:31 INFO - 'download_minidump_stackwalk': True, 23:18:31 INFO - 'download_symbols': 'ondemand', 23:18:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:18:31 INFO - 'tooltool.py': '/tools/tooltool.py', 23:18:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:18:31 INFO - '/tools/misc-python/virtualenv.py')}, 23:18:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:18:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:18:31 INFO - 'installer_path': 'installer.exe', 23:18:31 INFO - 'log_level': 'info', 23:18:31 INFO - 'log_name': 'talos', 23:18:31 INFO - 'log_to_console': True, 23:18:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:18:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:18:31 INFO - 'opt_config_files': (), 23:18:31 INFO - 'pip_index': False, 23:18:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:18:31 INFO - 'cmd': ('bash', 23:18:31 INFO - '-c', 23:18:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:18:31 INFO - 'enabled': True, 23:18:31 INFO - 'halt_on_failure': False, 23:18:31 INFO - 'name': 'check_screen_resolution'},), 23:18:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:18:31 INFO - 'cmd': ('bash', 23:18:31 INFO - '-c', 23:18:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:18:31 INFO - 'enabled': True, 23:18:31 INFO - 'halt_on_failure': False, 23:18:31 INFO - 'name': 'check_screen_resolution'},), 23:18:31 INFO - 'run_cmd_checks_enabled': True, 23:18:31 INFO - 'sps_profile': False, 23:18:31 INFO - 'sps_profile_interval': 0, 23:18:31 INFO - 'suite': 'dromaeojs-e10s', 23:18:31 INFO - 'system_bits': '32', 23:18:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:18:31 INFO - 'title': 't-yosemite-r7-0171', 23:18:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:18:31 INFO - 'use_talos_json': True, 23:18:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:18:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:18:31 INFO - 'work_dir': 'build'} 23:18:31 INFO - [mozharness: 2017-02-02 07:18:31.969722Z] Running clobber step. 23:18:31 INFO - Running pre-action listener: _resource_record_pre_action 23:18:31 INFO - Running main action method: clobber 23:18:31 INFO - rmtree: /builds/slave/test/build 23:18:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:18:34 INFO - Running post-action listener: _resource_record_post_action 23:18:34 INFO - [mozharness: 2017-02-02 07:18:34.517433Z] Finished clobber step (success) 23:18:34 INFO - [mozharness: 2017-02-02 07:18:34.517705Z] Running read-buildbot-config step. 23:18:34 INFO - Running pre-action listener: _resource_record_pre_action 23:18:34 INFO - Running main action method: read_buildbot_config 23:18:34 INFO - Using buildbot properties: 23:18:34 INFO - { 23:18:34 INFO - "project": "", 23:18:34 INFO - "product": "firefox", 23:18:34 INFO - "script_repo_revision": "production", 23:18:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 23:18:34 INFO - "repository": "", 23:18:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 23:18:34 INFO - "buildid": "20170202055853", 23:18:34 INFO - "stage_platform": "macosx64", 23:18:34 INFO - "basedir": "/builds/slave/test", 23:18:34 INFO - "nightly_build": "True", 23:18:34 INFO - "buildnumber": 39, 23:18:34 INFO - "slavename": "t-yosemite-r7-0171", 23:18:34 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 23:18:34 INFO - "platform": "yosemite_r7", 23:18:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:18:34 INFO - "branch": "mozilla-central", 23:18:34 INFO - "repo_path": "mozilla-central", 23:18:34 INFO - "revision": "fe22af79bacf6db526737536eab551caf68ba440", 23:18:34 INFO - "pgo_build": "False", 23:18:34 INFO - "builduid": "26455d3bd7f34302bf192eb4abc9e6ec", 23:18:34 INFO - "slavebuilddir": "test" 23:18:34 INFO - } 23:18:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 23:18:34 INFO - Running post-action listener: _resource_record_post_action 23:18:34 INFO - [mozharness: 2017-02-02 07:18:34.523509Z] Finished read-buildbot-config step (success) 23:18:34 INFO - [mozharness: 2017-02-02 07:18:34.523756Z] Running download-and-extract step. 23:18:34 INFO - Running pre-action listener: _resource_record_pre_action 23:18:34 INFO - Running main action method: download_and_extract 23:18:34 INFO - mkdir: /builds/slave/test/build/tests 23:18:34 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')]} 23:18:34 INFO - trying https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 23:18:34 INFO - Downloading https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:18:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 23:18:35 INFO - Downloaded 1199 bytes. 23:18:35 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:18:35 INFO - Using the following test package requirements: 23:18:35 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:18:35 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:18:35 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 23:18:35 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:18:35 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 23:18:35 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:18:35 INFO - u'jsshell-mac.zip'], 23:18:35 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:18:35 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 23:18:35 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:18:35 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:18:35 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 23:18:35 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:18:35 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 23:18:35 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:18:35 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 23:18:35 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:18:35 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 23:18:35 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:18:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:18:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:18:35 INFO - Fetch https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:18:36 INFO - Expected file size: 16344837 23:18:36 INFO - Obtained file size: 16344837 23:18:43 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:18:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:18:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:18:43 INFO - Fetch https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:18:44 INFO - Expected file size: 16344837 23:18:44 INFO - Obtained file size: 16344837 23:18:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 23:18:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:18:50 INFO - Fetch https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 23:18:52 INFO - Expected file size: 14403476 23:18:52 INFO - Obtained file size: 14403476 23:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:18:52 INFO - trying https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:18:52 INFO - Downloading https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 23:18:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 23:18:55 INFO - Downloaded 70002496 bytes. 23:18:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:18:55 INFO - mkdir: /builds/slave/test/properties 23:18:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:18:55 INFO - Writing to file /builds/slave/test/properties/build_url 23:18:55 INFO - Contents: 23:18:55 INFO - build_url:https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:18:56 INFO - Running post-action listener: _resource_record_post_action 23:18:56 INFO - Running post-action listener: set_extra_try_arguments 23:18:56 INFO - [mozharness: 2017-02-02 07:18:56.323623Z] Finished download-and-extract step (success) 23:18:56 INFO - [mozharness: 2017-02-02 07:18:56.323721Z] Running populate-webroot step. 23:18:56 INFO - Running pre-action listener: _resource_record_pre_action 23:18:56 INFO - Running main action method: populate_webroot 23:18:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:18:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:18:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:18:56 INFO - '--tppagecycles', 23:18:56 INFO - '7'], 23:18:56 INFO - 'tests': ['tsvgm']}}, 23:18:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:18:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:18:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:18:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:18:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:18:56 INFO - 'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:18:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:18:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:18:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:18:56 INFO - 'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['damp', 'tps']}, 23:18:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:18:56 INFO - 'tests': ['damp', 'tps']}, 23:18:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['dromaeo_dom']}, 23:18:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:18:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['basic_compositor_video']}, 23:18:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:18:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['a11yr', 23:18:56 INFO - 'ts_paint', 23:18:56 INFO - 'tpaint', 23:18:56 INFO - 'sessionrestore', 23:18:56 INFO - 'sessionrestore_no_auto_restore', 23:18:56 INFO - 'tabpaint']}, 23:18:56 INFO - 'other-e10s': {'tests': ['a11yr', 23:18:56 INFO - 'ts_paint', 23:18:56 INFO - 'tpaint', 23:18:56 INFO - 'sessionrestore', 23:18:56 INFO - 'sessionrestore_no_auto_restore', 23:18:56 INFO - 'tabpaint']}, 23:18:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:18:56 INFO - 'ts_paint', 23:18:56 INFO - 'tpaint', 23:18:56 INFO - 'sessionrestore', 23:18:56 INFO - 'sessionrestore_no_auto_restore', 23:18:56 INFO - 'tabpaint']}, 23:18:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:18:56 INFO - 'ts_paint', 23:18:56 INFO - 'tpaint', 23:18:56 INFO - 'sessionrestore', 23:18:56 INFO - 'sessionrestore_no_auto_restore', 23:18:56 INFO - 'tabpaint']}, 23:18:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['a11yr', 23:18:56 INFO - 'ts_paint', 23:18:56 INFO - 'tpaint', 23:18:56 INFO - 'sessionrestore', 23:18:56 INFO - 'sessionrestore_no_auto_restore', 23:18:56 INFO - 'tabpaint']}, 23:18:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['a11yr', 23:18:56 INFO - 'ts_paint', 23:18:56 INFO - 'tpaint', 23:18:56 INFO - 'sessionrestore', 23:18:56 INFO - 'sessionrestore_no_auto_restore', 23:18:56 INFO - 'tabpaint']}, 23:18:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['tsvgx', 23:18:56 INFO - 'tsvgr_opacity', 23:18:56 INFO - 'tart', 23:18:56 INFO - 'tscrollx', 23:18:56 INFO - 'cart', 23:18:56 INFO - 'tsvg_static']}, 23:18:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:18:56 INFO - 'tsvgr_opacity', 23:18:56 INFO - 'tart', 23:18:56 INFO - 'tscrollx', 23:18:56 INFO - 'cart', 23:18:56 INFO - 'tsvg_static']}, 23:18:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:18:56 INFO - 'talos_options': ['--disable-e10s'], 23:18:56 INFO - 'tests': ['tp5o']}, 23:18:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:18:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:18:56 INFO - 'talos_options': ['--disable-e10s', 23:18:56 INFO - '--xperf_path', 23:18:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:18:56 INFO - 'tests': ['tp5n']}, 23:18:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:18:56 INFO - 'talos_options': ['--xperf_path', 23:18:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:18:56 INFO - 'tests': ['tp5n']}}, 23:18:56 INFO - 'talos.zip': {'path': '', 23:18:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:18:56 INFO - Running post-action listener: _resource_record_post_action 23:18:56 INFO - [mozharness: 2017-02-02 07:18:56.342451Z] Finished populate-webroot step (success) 23:18:56 INFO - [mozharness: 2017-02-02 07:18:56.342586Z] Running create-virtualenv step. 23:18:56 INFO - Running pre-action listener: _resource_record_pre_action 23:18:56 INFO - Running main action method: create_virtualenv 23:18:56 INFO - Creating virtualenv /builds/slave/test/build/venv 23:18:56 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 23:18:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:18:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:18:56 INFO - Using real prefix '/tools/python27' 23:18:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:18:57 INFO - Installing distribute.............................................................................................................................................................................................done. 23:19:00 INFO - Installing pip.................done. 23:19:00 INFO - Return code: 0 23:19:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:19:00 INFO - Reading from file tmpfile_stdout 23:19:00 INFO - Output received: 23:19:00 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) 23:19:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:00 INFO - 'HOME': '/Users/cltbld', 23:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:00 INFO - 'LOGNAME': 'cltbld', 23:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:00 INFO - 'MOZ_NO_REMOTE': '1', 23:19:00 INFO - 'NO_EM_RESTART': '1', 23:19:00 INFO - 'PAGER': '/bin/cat', 23:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:00 INFO - 'PWD': '/builds/slave/test', 23:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:00 INFO - 'SHELL': '/bin/bash', 23:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:00 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:00 INFO - 'USER': 'cltbld', 23:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:00 INFO - 'XPC_FLAGS': '0x0', 23:19:00 INFO - 'XPC_SERVICE_NAME': '0', 23:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:00 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 23:19:00 INFO - Cleaning up... 23:19:00 INFO - Return code: 0 23:19:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:00 INFO - 'HOME': '/Users/cltbld', 23:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:00 INFO - 'LOGNAME': 'cltbld', 23:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:00 INFO - 'MOZ_NO_REMOTE': '1', 23:19:00 INFO - 'NO_EM_RESTART': '1', 23:19:00 INFO - 'PAGER': '/bin/cat', 23:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:00 INFO - 'PWD': '/builds/slave/test', 23:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:00 INFO - 'SHELL': '/bin/bash', 23:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:00 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:00 INFO - 'USER': 'cltbld', 23:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:00 INFO - 'XPC_FLAGS': '0x0', 23:19:00 INFO - 'XPC_SERVICE_NAME': '0', 23:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:00 INFO - Downloading/unpacking psutil>=3.1.1 23:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:19:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:19:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:19:04 INFO - Installing collected packages: psutil 23:19:04 INFO - Running setup.py install for psutil 23:19:04 INFO - building 'psutil._psutil_osx' extension 23:19:04 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 23:19:05 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 23:19:05 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 23:19:05 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 23:19:05 INFO - building 'psutil._psutil_posix' extension 23:19:05 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 23:19:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:19:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:19:05 INFO - ^ 23:19:05 INFO - 1 warning generated. 23:19:05 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 23:19:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:19:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:19:05 INFO - Successfully installed psutil 23:19:05 INFO - Cleaning up... 23:19:05 INFO - Return code: 0 23:19:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:05 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 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:05 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 23:19:05 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 23:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:05 INFO - 'HOME': '/Users/cltbld', 23:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:05 INFO - 'LOGNAME': 'cltbld', 23:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:05 INFO - 'MOZ_NO_REMOTE': '1', 23:19:05 INFO - 'NO_EM_RESTART': '1', 23:19:05 INFO - 'PAGER': '/bin/cat', 23:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:05 INFO - 'PWD': '/builds/slave/test', 23:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:05 INFO - 'SHELL': '/bin/bash', 23:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:05 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:05 INFO - 'USER': 'cltbld', 23:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:05 INFO - 'XPC_FLAGS': '0x0', 23:19:05 INFO - 'XPC_SERVICE_NAME': '0', 23:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:19:08 INFO - Installing collected packages: mozsystemmonitor 23:19:08 INFO - Running setup.py install for mozsystemmonitor 23:19:08 INFO - Successfully installed mozsystemmonitor 23:19:08 INFO - Cleaning up... 23:19:08 INFO - Return code: 0 23:19:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:08 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 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:08 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 23:19:08 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 23:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:08 INFO - 'HOME': '/Users/cltbld', 23:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:08 INFO - 'LOGNAME': 'cltbld', 23:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:08 INFO - 'MOZ_NO_REMOTE': '1', 23:19:08 INFO - 'NO_EM_RESTART': '1', 23:19:08 INFO - 'PAGER': '/bin/cat', 23:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:08 INFO - 'PWD': '/builds/slave/test', 23:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:08 INFO - 'SHELL': '/bin/bash', 23:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:08 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:08 INFO - 'USER': 'cltbld', 23:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:08 INFO - 'XPC_FLAGS': '0x0', 23:19:08 INFO - 'XPC_SERVICE_NAME': '0', 23:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:09 INFO - Downloading/unpacking jsonschema==2.5.1 23:19:09 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 23:19:09 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 23:19:09 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 23:19:09 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 23:19:09 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 23:19:09 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 23:19:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:19:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:19:11 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 23:19:11 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 23:19:11 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 23:19:11 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 23:19:11 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 23:19:11 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 23:19:12 INFO - Downloading functools32-3.2.3-2.tar.gz 23:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:19:12 INFO - warning: no files found matching '*.txt' 23:19:12 INFO - no previously-included directories found matching 'build' 23:19:12 INFO - no previously-included directories found matching 'dist' 23:19:12 INFO - no previously-included directories found matching '.git*' 23:19:12 INFO - Installing collected packages: jsonschema, functools32 23:19:12 INFO - Running setup.py install for functools32 23:19:12 INFO - warning: no files found matching '*.txt' 23:19:12 INFO - no previously-included directories found matching 'build' 23:19:12 INFO - no previously-included directories found matching 'dist' 23:19:12 INFO - no previously-included directories found matching '.git*' 23:19:12 INFO - Successfully installed jsonschema functools32 23:19:12 INFO - Cleaning up... 23:19:12 INFO - Return code: 0 23:19:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:19: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')]} 23:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19: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')]} 23:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:12 INFO - 'HOME': '/Users/cltbld', 23:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:12 INFO - 'LOGNAME': 'cltbld', 23:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:12 INFO - 'MOZ_NO_REMOTE': '1', 23:19:12 INFO - 'NO_EM_RESTART': '1', 23:19:12 INFO - 'PAGER': '/bin/cat', 23:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:12 INFO - 'PWD': '/builds/slave/test', 23:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:12 INFO - 'SHELL': '/bin/bash', 23:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:12 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:12 INFO - 'USER': 'cltbld', 23:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:12 INFO - 'XPC_FLAGS': '0x0', 23:19:12 INFO - 'XPC_SERVICE_NAME': '0', 23:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:19:12 INFO - Cleaning up... 23:19:12 INFO - Return code: 0 23:19:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:19: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')]} 23:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19: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')]} 23:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:12 INFO - 'HOME': '/Users/cltbld', 23:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:12 INFO - 'LOGNAME': 'cltbld', 23:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:12 INFO - 'MOZ_NO_REMOTE': '1', 23:19:12 INFO - 'NO_EM_RESTART': '1', 23:19:12 INFO - 'PAGER': '/bin/cat', 23:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:12 INFO - 'PWD': '/builds/slave/test', 23:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:12 INFO - 'SHELL': '/bin/bash', 23:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:12 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:12 INFO - 'USER': 'cltbld', 23:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:12 INFO - 'XPC_FLAGS': '0x0', 23:19:12 INFO - 'XPC_SERVICE_NAME': '0', 23:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:13 INFO - Downloading/unpacking blobuploader==1.2.4 23:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:15 INFO - Downloading blobuploader-1.2.4.tar.gz 23:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:19:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:19:15 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 23:19:15 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 23:19:15 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 23:19:15 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 23:19:15 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 23:19:15 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 23:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:19:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:16 INFO - Downloading docopt-0.6.1.tar.gz 23:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:19:17 INFO - Installing collected packages: blobuploader, requests, docopt 23:19:17 INFO - Running setup.py install for blobuploader 23:19:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:19:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:19:17 INFO - Running setup.py install for requests 23:19:17 INFO - Running setup.py install for docopt 23:19:17 INFO - Successfully installed blobuploader requests docopt 23:19:17 INFO - Cleaning up... 23:19:17 INFO - Return code: 0 23:19:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:17 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 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:17 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 23:19:17 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 23:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:17 INFO - 'HOME': '/Users/cltbld', 23:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:17 INFO - 'LOGNAME': 'cltbld', 23:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:17 INFO - 'MOZ_NO_REMOTE': '1', 23:19:17 INFO - 'NO_EM_RESTART': '1', 23:19:17 INFO - 'PAGER': '/bin/cat', 23:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:17 INFO - 'PWD': '/builds/slave/test', 23:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:17 INFO - 'SHELL': '/bin/bash', 23:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:17 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:17 INFO - 'USER': 'cltbld', 23:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:17 INFO - 'XPC_FLAGS': '0x0', 23:19:17 INFO - 'XPC_SERVICE_NAME': '0', 23:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-B5QVIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-qXezy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-kPG8wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-FWoIBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-wXMiRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-o8FSNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-OUyLUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-F7Rty6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:19:18 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-9E1400-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:19:19 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-EVO2pS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:19:19 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-rs_ZIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:19:19 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-gjy3k2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:19:19 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-wpLZHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:19:19 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-wc6L_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:19:19 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-s2AYBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:19:19 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-t5DpHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:19:19 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-rbfjcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:19:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:19:19 INFO - Running setup.py install for manifestparser 23:19:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:19:20 INFO - Running setup.py install for mozcrash 23:19:20 INFO - Running setup.py install for mozdebug 23:19:20 INFO - Running setup.py install for mozdevice 23:19:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:19:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:19:20 INFO - Running setup.py install for mozfile 23:19:20 INFO - Running setup.py install for mozhttpd 23:19:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:19:20 INFO - Running setup.py install for mozinfo 23:19:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:19:20 INFO - Running setup.py install for mozInstall 23:19:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Running setup.py install for mozleak 23:19:21 INFO - Running setup.py install for mozlog 23:19:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Running setup.py install for moznetwork 23:19:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Running setup.py install for mozprocess 23:19:21 INFO - Running setup.py install for mozprofile 23:19:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Running setup.py install for mozrunner 23:19:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:19:21 INFO - Running setup.py install for mozscreenshot 23:19:22 INFO - Running setup.py install for moztest 23:19:22 INFO - Running setup.py install for mozversion 23:19:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:19:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:19:22 INFO - Cleaning up... 23:19:22 INFO - Return code: 0 23:19:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:22 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 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:22 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 23:19:22 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 23:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:22 INFO - 'HOME': '/Users/cltbld', 23:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:22 INFO - 'LOGNAME': 'cltbld', 23:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:22 INFO - 'MOZ_NO_REMOTE': '1', 23:19:22 INFO - 'NO_EM_RESTART': '1', 23:19:22 INFO - 'PAGER': '/bin/cat', 23:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:22 INFO - 'PWD': '/builds/slave/test', 23:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:22 INFO - 'SHELL': '/bin/bash', 23:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:22 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:22 INFO - 'USER': 'cltbld', 23:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:22 INFO - 'XPC_FLAGS': '0x0', 23:19:22 INFO - 'XPC_SERVICE_NAME': '0', 23:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:19:22 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-SR6Nv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:19:22 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)) 23:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:19:22 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-w08Fmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:19:22 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)) 23:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:19:22 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-n0efKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-9GhwTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-VrksJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-HfjkfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-wqVAJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ceKHXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-9P0UuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-zoDT52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-qUxMye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-5T0D_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:19:23 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)) 23:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:19:23 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-8qP24o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:19:24 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)) 23:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:19:24 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ZCDDOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:19:24 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)) 23:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:19:24 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-DZ0Tfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:19:24 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)) 23:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:19:24 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-kQ8REL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:19:24 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)) 23:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:19:24 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-gBwe9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:19:24 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)) 23:19:24 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)) 23:19:24 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)) 23:19:24 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)) 23:19:24 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)) 23:19:24 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)) 23:19:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:27 INFO - Downloading blessings-1.6.tar.gz 23:19:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:19:27 INFO - Installing collected packages: blessings 23:19:27 INFO - Running setup.py install for blessings 23:19:27 INFO - Successfully installed blessings 23:19:27 INFO - Cleaning up... 23:19:27 INFO - Return code: 0 23:19:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:19:28 INFO - Reading from file tmpfile_stdout 23:19:28 INFO - Current package versions: 23:19:28 INFO - blessings == 1.6 23:19:28 INFO - blobuploader == 1.2.4 23:19:28 INFO - docopt == 0.6.1 23:19:28 INFO - functools32 == 3.2.3-2 23:19:28 INFO - jsonschema == 2.5.1 23:19:28 INFO - manifestparser == 1.1 23:19:28 INFO - mozInstall == 1.12 23:19:28 INFO - mozcrash == 1.0 23:19:28 INFO - mozdebug == 0.1 23:19:28 INFO - mozdevice == 0.49 23:19:28 INFO - mozfile == 1.2 23:19:28 INFO - mozhttpd == 0.7 23:19:28 INFO - mozinfo == 0.9 23:19:28 INFO - mozleak == 0.1 23:19:28 INFO - mozlog == 3.4 23:19:28 INFO - moznetwork == 0.27 23:19:28 INFO - mozprocess == 0.24 23:19:28 INFO - mozprofile == 0.28 23:19:28 INFO - mozrunner == 6.13 23:19:28 INFO - mozscreenshot == 0.1 23:19:28 INFO - mozsystemmonitor == 0.3 23:19:28 INFO - moztest == 0.8 23:19:28 INFO - mozversion == 1.4 23:19:28 INFO - psutil == 3.1.1 23:19:28 INFO - requests == 1.2.3 23:19:28 INFO - wsgiref == 0.1.2 23:19:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:19: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')]} 23:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19: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')]} 23:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19:28 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 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19:28 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 23:19:28 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 23:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:28 INFO - 'HOME': '/Users/cltbld', 23:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:28 INFO - 'LOGNAME': 'cltbld', 23:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:28 INFO - 'MOZ_NO_REMOTE': '1', 23:19:28 INFO - 'NO_EM_RESTART': '1', 23:19:28 INFO - 'PAGER': '/bin/cat', 23:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:28 INFO - 'PWD': '/builds/slave/test', 23:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:28 INFO - 'SHELL': '/bin/bash', 23:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:28 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:28 INFO - 'USER': 'cltbld', 23:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:28 INFO - 'XPC_FLAGS': '0x0', 23:19:28 INFO - 'XPC_SERVICE_NAME': '0', 23:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 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)) 23:19:28 INFO - Cleaning up... 23:19:28 INFO - Return code: 0 23:19:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:19: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')]} 23:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19: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')]} 23:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:19: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdbfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdc2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9d68d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, '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.ntr24iVcq5/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.gB53lJs5Ty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 23:19: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', 'jsonschema'] in /builds/slave/test/build 23:19: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 jsonschema 23:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:28 INFO - 'HOME': '/Users/cltbld', 23:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:28 INFO - 'LOGNAME': 'cltbld', 23:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:28 INFO - 'MOZ_NO_REMOTE': '1', 23:19:28 INFO - 'NO_EM_RESTART': '1', 23:19:28 INFO - 'PAGER': '/bin/cat', 23:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:28 INFO - 'PWD': '/builds/slave/test', 23:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:28 INFO - 'SHELL': '/bin/bash', 23:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:28 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:28 INFO - 'USER': 'cltbld', 23:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:28 INFO - 'XPC_FLAGS': '0x0', 23:19:28 INFO - 'XPC_SERVICE_NAME': '0', 23:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:19:28 INFO - Cleaning up... 23:19:29 INFO - Return code: 0 23:19:29 INFO - Running post-action listener: _resource_record_post_action 23:19:29 INFO - Running post-action listener: _start_resource_monitoring 23:19:29 INFO - Starting resource monitoring. 23:19:29 INFO - [mozharness: 2017-02-02 07:19:29.048847Z] Finished create-virtualenv step (success) 23:19:29 INFO - [mozharness: 2017-02-02 07:19:29.049788Z] Running install step. 23:19:29 INFO - Running pre-action listener: _resource_record_pre_action 23:19:29 INFO - Running main action method: install 23:19:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:19:29 INFO - Reading from file tmpfile_stdout 23:19:29 INFO - Detecting whether we're running mozinstall >=1.0... 23:19:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:19:29 INFO - Reading from file tmpfile_stdout 23:19:29 INFO - Output received: 23:19:29 INFO - Usage: mozinstall [options] installer 23:19:29 INFO - Options: 23:19:29 INFO - -h, --help show this help message and exit 23:19:29 INFO - -d DEST, --destination=DEST 23:19:29 INFO - Directory to install application into. [default: 23:19:29 INFO - "/builds/slave/test"] 23:19:29 INFO - --app=APP Application being installed. [default: firefox] 23:19:29 INFO - mkdir: /builds/slave/test/build/application 23:19:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:19:51 INFO - Reading from file tmpfile_stdout 23:19:51 INFO - Output received: 23:19:51 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 23:19:51 INFO - Running post-action listener: _resource_record_post_action 23:19:51 INFO - [mozharness: 2017-02-02 07:19:51.745182Z] Finished install step (success) 23:19:51 INFO - [mozharness: 2017-02-02 07:19:51.745326Z] Running run-tests step. 23:19:51 INFO - Running pre-action listener: _resource_record_pre_action 23:19:51 INFO - Running main action method: run_tests 23:19:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:19:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:19:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:19:51 INFO - 2017-02-01 23:19:51.759 screenresolution[1622:7329] starting screenresolution argv=screenresolution get 23:19:51 INFO - 2017-02-01 23:19:51.762 screenresolution[1622:7329] Display 0: 1600x1200x32@60 23:19:51 INFO - 2017-02-01 23:19:51.768 screenresolution[1623:7332] starting screenresolution argv=screenresolution list 23:19:51 INFO - Available Modes on Display 0 23:19:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:19:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:19:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:19:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:19:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:19:51 INFO - Intel Iris: 23:19:51 INFO - Chipset Model: Intel Iris 23:19:51 INFO - Type: GPU 23:19:51 INFO - Bus: Built-In 23:19:51 INFO - VRAM (Dynamic, Max): 1536 MB 23:19:51 INFO - Vendor: Intel (0x8086) 23:19:51 INFO - Device ID: 0x0a2e 23:19:51 INFO - Revision ID: 0x0009 23:19:51 INFO - Displays: 23:19:51 INFO - Display: 23:19:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:19:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:19:51 INFO - Display Serial Number: 1600x1200 60 23:19:51 INFO - Main Display: Yes 23:19:51 INFO - Mirror: Off 23:19:51 INFO - Online: Yes 23:19:51 INFO - Rotation: Supported 23:19:52 INFO - Return code: 0 23:19:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:19:52 INFO - Python 2.7.3 23:19:52 INFO - Return code: 0 23:19:52 INFO - grabbing minidump binary from tooltool 23:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:52 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 0x10cb9dd30>, '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 0x10cac4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb92be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:19:52 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 23:19:52 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 23:19:52 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 23:19:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:19:52 INFO - Return code: 0 23:19:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:19:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:19:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:19:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:19:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:19:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 23:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --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 23:19:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ntr24iVcq5/Render', 23:19:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:19:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:19:52 INFO - 'HOME': '/Users/cltbld', 23:19:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:19:52 INFO - 'LOGNAME': 'cltbld', 23:19:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:19:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:52 INFO - 'MOZ_NO_REMOTE': '1', 23:19:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:19:52 INFO - 'NO_EM_RESTART': '1', 23:19:52 INFO - 'PAGER': '/bin/cat', 23:19:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:52 INFO - 'PWD': '/builds/slave/test', 23:19:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:19:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:19:52 INFO - 'SHELL': '/bin/bash', 23:19:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gB53lJs5Ty/Listeners', 23:19:52 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 23:19:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:19:52 INFO - 'USER': 'cltbld', 23:19:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:19:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:52 INFO - 'XPC_FLAGS': '0x0', 23:19:52 INFO - 'XPC_SERVICE_NAME': '0', 23:19:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:19:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 23:19:52 INFO - mozversion application_buildid: 20170202055853 23:19:52 INFO - mozversion application_changeset: fe22af79bacf6db526737536eab551caf68ba440 23:19:52 INFO - mozversion application_display_name: Nightly 23:19:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:19:52 INFO - mozversion application_name: Firefox 23:19:52 INFO - mozversion application_remotingname: firefox 23:19:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:19:52 INFO - mozversion application_vendor: Mozilla 23:19:52 INFO - mozversion application_version: 53.0a1 23:19:52 INFO - mozversion platform_buildid: 20170202055853 23:19:52 INFO - mozversion platform_changeset: fe22af79bacf6db526737536eab551caf68ba440 23:19:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:19:52 INFO - mozversion platform_version: 53.0a1 23:19:52 INFO - using testdate: 1486019992 23:19:52 INFO - actual date: 1486019992 23:19:52 INFO - starting webserver on 'localhost:49240' 23:19:52 INFO - SUITE-START | Running 2 tests 23:19:52 INFO - TEST-START | dromaeo_css 23:19:52 INFO - operating with platform_type : mac_ 23:19:52 INFO - Initialising browser for dromaeo_css test... 23:19:52 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpdDnJln/profile http://localhost:49240/getInfo.html) 23:19:55 INFO - PROCESS | 1633 | 2017-02-01 23:19:55.246 plugin-container[1636:7501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4b, name = 'com.apple.tsm.portname' 23:19:55 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:19:55 INFO - PROCESS | 1633 | 2017-02-01 23:19:55.247 plugin-container[1636:7501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 23:19:55 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:19:55 INFO - PROCESS | 1633 | 2017-02-01 23:19:55.247 plugin-container[1636:7501] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:19:55 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 23:19:55 INFO - PROCESS | 1633 | colorDepth:24 23:19:55 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 23:19:55 INFO - PROCESS | 1633 | __metrics 23:19:55 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 23:19:55 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 23:19:56 INFO - TEST-INFO | 1633: exit 0 23:19:56 INFO - Browser initialized. 23:19:56 INFO - Running cycle 1/1 for dromaeo_css test... 23:19:56 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpdDnJln/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:19:58 INFO - PROCESS | 1637 | 2017-02-01 23:19:58.768 plugin-container[1638:7626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 23:19:58 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:19:58 INFO - PROCESS | 1637 | 2017-02-01 23:19:58.769 plugin-container[1638:7626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 23:19:58 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:19:58 INFO - PROCESS | 1637 | 2017-02-01 23:19:58.769 plugin-container[1638:7626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:20:08 INFO - PROCESS | 1637 | RSS: Main: 232943616 23:20:08 INFO - PROCESS | 1637 | 23:23:11 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 23:23:12 INFO - PROCESS | 1637 | RSS: Main: 261545984 23:23:12 INFO - PROCESS | 1637 | 23:24:56 INFO - PROCESS | 1637 | ************************* 23:24:56 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 23:24:56 INFO - PROCESS | 1637 | 23:24:56 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:24:56 INFO - PROCESS | 1637 | Full stack: 23:24:56 INFO - PROCESS | 1637 | ************************* 23:26:15 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 23:26:15 INFO - PROCESS | 1637 | RSS: Main: 262316032 23:26:15 INFO - PROCESS | 1637 | 23:29:18 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 23:29:18 INFO - PROCESS | 1637 | RSS: Main: 271126528 23:29:18 INFO - PROCESS | 1637 | 23:32:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 23:32:21 INFO - PROCESS | 1637 | RSS: Main: 267661312 23:32:21 INFO - PROCESS | 1637 | 23:35:24 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 23:35:24 INFO - PROCESS | 1637 | RSS: Main: 272613376 23:35:24 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 23:38:27 INFO - PROCESS | 1637 | RSS: Main: 274821120 23:38:27 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | __start_tp_report 23:38:27 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 23:38:27 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 23:38:27 INFO - PROCESS | 1637 | |i|pagename|runs| 23:38:27 INFO - PROCESS | 1637 | |0;dojo.html;3284.343131373726;3305.785123966942;3334.2831291272373;3356.992902129362;3361.98319008405;5505.256790333304;5505.889882202354;5526.723663816808;5562.3881373048935;5593.216949627055;4442.3114417265215;5511.173324001402;5552.333719953611;5590.245316882219;5622.128570071635;5019.447860735316;5103.438621751604;5117.565006974393;5155.7422128893595;5190;4314.654827613799;5118.923216151761;5143.948560514403;5176;5241.475852414766;5163.586913046947;5172.974135129338;5179.2749015137915;5191.584673226152;5211.218317252424;5025.623078269108;5031.572316353115;5052.823151189691;5100.081985242655;5117.565006974374;5056.570191533724;5058.873528161826;5103.336566246401;5118.616103792193;5142.305788718512;5377.220303056074;5435.157550579666;5534.723263836792;5586.13414920688;5623.634463759842;6040.607360521596;6106.969465152646;6168.537359698041;6181.752729890799;6199.721012554446;6120.204373431426;6130.581587307925;6149.200603921461;6151.200343955302;6172.259328880518;5717.799877004285;5735.827925162252;5753.884922301572;5759.884802303931;5774.422557744234;6134.294556126013;6140.8771824564155;6220.409061139185;6230.532710046674;6241.21984751906;5888.205092312569;5945.7621695132575;5948.078047902582;5988.970055149696;6092.939070609237;6104.542159338067;6119.929102025404;6124.448799608057;6146.323904370515;6187.978983467678;5967.955607768623;6042.39576042401;6091.238595175603;6094.634321940698;6158.938410615926;5074.4671809460215;6122.1542093545395;6151.200343955257;6178.165947597106;6215.06773983897;4772.260299653559;4820.566149046603;4881.219004959189;4901.730404827697;4911.607071434277;4306.65546756265;4322.243607368698;4364.323529852878;4376.956230437655;4413.558644135625;5531.585131115183;5582.413846546136;5589.832305030855;5607.355154157324;5644.209810626516;6143.280259208464;6170.50635949123;6200.193974783249;6212.751489940351;6215.409536094064;5465.781368745285;5540.584456165804;5586.944130558642;5628.324601047901;5661.179129026268;5315.096433606219;5322.148456246982;5322.92015619766;5339.839804805784;5344.331958505187;6208.72060757267;6216.191895053705;6223;6225.221847269091;6237.594556353823;4527.5019747827055;5802.448767367094;6034.275886893601;6105.641708307991;6127.281156068254;5566;6241.21984751906;6242.188515492957;6252.124702541557;6260.780872669435;6119.602225855305;6140.631562106288;6163.96918015407;6172.259328880563;6172.814815555541;5983.341832398346;5993.850153746192;6104.938950610437;6142.662153581596;6210.782622608187;5095.643304968617;5971.492423144082;6013.037913933749;6068.21113255274;6098.237720284965;5859.121131830259;5947.434993675594;5954.821355359346;6009.098635204754;6018.3379828217985;5522.3649280333275;5742.885142297047;5818.0981947798155;5830.125481177857;5933.703314834327;6048.092786082123;6049.274087109575;6068.059450785136;6068.180795592539;6077.817665470043;5877.617954832922;5917.378675239038;5918.526517878473;5920.141579470866;5938.495227905676 23:38:27 INFO - PROCESS | 1637 | |1;ext.html;468.1596534221074;510.44871538738164;517.3481413419091;519.9055987147057;535.683946471582;41507.58492415079;43506;43651.69044928649;43656.56343436565;43800.780996095054;44803;44995;45338.77330613342;45433.31850022244;45453.318200227026;8599.656013759442;8756.824863502727;9421.62313507459;9657.32398732089;9682.273829462783;11541.884581154165;11818.995385392253;11840.348780817094;12012.03903687703;12044.518219271218;11697;11767.705807354841;11768.705782355424;11783.882161178364;11785.705357366092;11699.473523691455;11711.063114950783;11798.115141364431;11800.996915262212;11813.232139910879;11509.539618415338;11589.76820463586;11608.361540115291;11624.302541847432;11639.47622356996;42297.78851105756;42366.57633423657;42596.787016065035;42842.35736463955;42892.57107428948;4079.7552146870985;4102.261592913274;4120.237756015147;4176.2691528982305;4250.3836943643;25434;31873.84063079693;31965.360692786246;32127.51808722871;32222.838885805657;3153.9684603153905;3281;3309.073459431363;3369.4945758136478;3375.0887260439545;413283.999999997;483154;489275;489479.99999999645;490689;66676;67118;67430.65138697297;67459;67555.32444675588;69547.65226173837;69802.3019769806;70254;70577.64711176412;70668.2933170672;16677.666446671;16822.327106915585;16838.49484515442;16863.325466981183;16919.577010574834;11598.76802463946;11869.881301186877;12591.307478088793;12808.615741527768;12914.741705165845;17404.898847460503;19174.233030678617;19416.12627431769;19530;19588.8041119587;6590;6678.465722742169;6728.764493242713;6741.528093033538;6773.830654233683;774.0247288416635;852.7186028610544;886.9822603547893;899.860521619137;902.6118768929292;26406.47187056248;30225.848870755945;30918;30987.84506077455;31095.067147985596;952.2524818017823;970.8786401699788;983.7393090830935;1100.301308668985;1110.4447776111945;12805.487780488676;14676.55970320892;15802.841971580137;16226.675466490604;16229.350825967065;534538.5642220455;562685;576704;579263;582133;62503.37496625067;63253.68373158105;63304;63578;63589;1430.9713805724036;1441.5170917742464;1456.6940942402002;1457.8542145785548;1461.751502244621;24532;26545.070922517618;29165.56251656269;29607.407851843273;30346.179229245434;54219.45780542144;54587.45412545824;54775.452245477034;54807.725961369935;54910;439706;505578;507482.4625876847;509075;510058;35840.82079589585;41313.79343103265;42796.35805462858;43052;43053.78473107614;39891.60108398879;47380.52619473761;49390.50609494003;50418;51398.74300628473;38949.8052509747;40170;41037;41383.050712522694;41883.37174942439;37112.73365651216;39517.60482395139;39638.40542391924;40224.798876005436;41093;37750.811245943594;38427.42358864675;39028.6097139025;39169.6083039166;40166.59833401746;23732.694701791566;33616.15959601029;34507.827460862536;35018;35129;20541;21354.78645213528;24346.391340216636;24605.753942460346;25006 23:38:27 INFO - PROCESS | 1637 | |2;jquery.html;375.7163341677035;387.26419802375483;393.10568456760865;396.70643723644514;404.9433079368887;19602.803971960293;20915.895420522913;21302.041408136633;21599.02804373805;21829.781702182972;20138.89930550345;20810.89594552029;20994.89502552489;21434.464138396546;21840.126394944222;5228.111221092405;5887.735051922674;6028.728707208186;6035.69821508925;6043.305019922721;3961.1285517186316;4409.426774519324;5922.674252916088;6037.849053773669;6046.576739628228;5998.610090344136;6087.817365479021;6141.324454310023;6147.8770424591485;6151.323354431009;6122.663253521055;6132.90800637993;7328.487005909588;7678.8080297993;7747.922520774833;7355.595442250674;7537.283958024033;7566.167721550625;7613.885791713129;7685.5004424712215;29734;29765.70234297651;29855;29987.400251994837;29993.400131997456;2848.2167403963867;2995.7004299570085;3041.3613141240367;3042.1025797389825;3096.5819614352;17173.742393863977;17244.310227590882;17340.046297453635;17628.942263464105;17927.73108403375;1676.647903940174;1971.044043638834;2011.5272910865936;2012.8087831656117;2025.2405347994502;145613.0877382457;169647;171101.2889871098;171653;172361;19315.613687726447;19900.402987910387;21326.253581124587;23544.411389715395;24364.75635243625;39651.80174099111;41814.79092604518;41941.370879436836;42275.7886210567;42757.1448571033;7672.810714339286;7874.251946065115;8209.95895020533;8984.32617553686;9082.091790820998;7260.636968151677;8721.87312466834;8758.605862736193;8894.510801905957;8952.194302512806;4064.918701625951;4856.717342848855;4869.415670119608;4896.779644915988;4949.307097006422;1680.957976050584;2029.1940173245823;2072.8652637578507;2076.200662744854;2081.1675329868176;977.9462129582942;991.6083147156833;1012.9037741414554;1024.6576984150786;1028.8631062675786;21777.673334899988;21859.781402185774;22018.779812201672;22038.88980555087;22062.11751529953;952.9380590261611;962.821877952581;1040.6825918095053;1065.6376831877199;1087.7933192693363;9844.556994935245;10754.462276886125;10825.458727063615;11332.830007549894;11346.432678366215;624310;695225;708849;715257.3556983247;744523;126068;131712.3414382941;146774;160746;175230.12384937995;1010.545254635417;1058.7617785998088;1076.8492411062457;1078.0459293525287;1082.9079528240188;19932.50168745735;20069.29757458482;20297.79702202959;20317.695234571787;20394.184232631123;62064;63518.68240658767;63789;64527.35472645213;64703;95144;95719.56420653556;96068;96588.03411966072;96753.51623241839;7739.845203095794;8103;8201.384896132695;8555.230029297392;8680.609372578123;11070.778584428106;13190.406431710404;13501.527446539323;13599;13840.861591383957;5888.852778680567;7705.422093343133;10255.333403329058;10619.15046796239;10645.095166910589;10459.68620941373;10578.100861426557;10701.464926753786;10854;10918.181136414642;10772.515236814519;10989.010989011027;11047.668569942914;11061.668149955514;11150.330980141218;8265.95867020661;8437.987441507059;8459.703910363107;8509.829803404022;8628.611712472828;9790.461524616214;10758.46207689628;10861.131109511061;10875.238733288594;11015.228933974546 23:38:27 INFO - PROCESS | 1637 | |3;mootools.html;1345.3878485289194;1422.6087825847897;1468.2071681292102;1481.1705444950826;1487.9330430130642;7518.774436766906;7816.570088645122;8060.556669383189;8482.830343393136;8490.787730306738;8103.230193131658;8345.707900223479;8370.58147092646;8447.91552084479;8481.448705834116;1941.4078705994666;2090.0176916849055;2310.202979971911;2339.4853132310864;2341.227394959668;2087.561612061469;2164.2641501889434;2213.236433430464;2344.402177444749;2364.3852598324474;2250.8086812620863;2327.8836058197107;2338.462153704649;2338.4738433852417;2347.9178228762;2093.9162433502643;2223.8109760670363;2322.5703244899746;2338.812894968407;2361.504084142332;1998.0718606544608;2285.714285714286;2296.8851557422063;2355.0462062864567;2357.0689577616918;8460.027096883876;8488.618012189467;8550.102239264912;8550.914490855073;8558.572071396406;5089.465606111342;5209.869753256161;5226.3989641191365;5242.004019236333;5243.711595862226;3506.1234691327168;3552.875649352286;3564.679178873888;3591.622879597631;3652.2695460907926;1224.8180505811933;1226.791445454275;1255.6358655989752;1271.9491220351174;1280.1934781087855;9747;10777.515011824487;10789.892101078967;10801.513931873085;10855.620053298097;4388.451443569554;4705.270683044134;4728.4798672146035;4757.09615173116;4796.424429068465;8210.548419836967;9269.814603707888;9609.90390096104;9614.855777163348;9617;5276.868078297996;5318.308619879468;5327.200919862052;5331.813386531453;5332.066888294533;4738.857834264978;4788;4796.784144713426;4881.219004959189;4887.047025829929;3199.808011519316;3933.7443066200603;3961.920761584752;3988.222296652183;3998.460207871958;984.2224642532319;1216.0757824053605;1262.3751243134523;1284.3321472834075;1289;839.6431516605418;854.8974123105268;861.9569021548898;884.4472204871955;892.1078921078921;5450.264214331094;5514.28314319144;5707.944030354398;5743.971280143573;5763.567732420085;958.3579002068631;992.4690290694588;996.5714742660725;1009.2077718990557;1038.48075962019;3340.816255105944;3386.153461634591;3790.9431358529646;3871.496705428276;3904.6876249899938;9785.559649815776;9919.752006199902;10079.143272821892;10083.294169408071;10096.495175241209;8998.685046023358;9427.717168484956;9914.950425247827;9955.054269844353;10049.29654924163;6707.564008339442;7278.417726581861;7853.253940875607;7890.8816367754525;7906.960465197637;4599.218132917345;4910.803567857246;5528.834134975958;5792.9710351447975;5853.268341457318;9116.817663646823;9141.725748227564;9154.954225229098;9295.907040929504;9323.393979391318;9280.767980800534;9444.48055356962;9448.669296574586;9575.473348965596;9609.663661771805;7233.529820561646;7359.558426494427;7381.8154546136775;7391.334779869838;7400.444966627417;7761.7671469855995;7898.5260884344825;7927.643256053376;7934.325582325567;7946.364290856602;7179.425645948207;7193.352598266181;7254.419646428168;7338.266173382619;7400.925990740024;7141.750038748619;7169.390601798906;7176.138863336224;7218.133823941161;7231.096112985877;6354.459870911024;6358.300586935526;6373.58571692857;6443.904536228946;6499.155109835691;5005.174146265971;5027.3464449621315;5047.520485553867;5115.64190506661;5142.614303927146;4937.703737775745;4961.206207007006;4985.850424487271;5059.418166910852;5198.610104242121 23:38:27 INFO - PROCESS | 1637 | |4;prototype.html;319.238615901076;346.3022010648544;355.4277613043;365.0399449447951;370.22068545711295;5274.947250527498;5293.391260005101;5442.612133905854;5514.889702205954;5540.529055030327;4739.421736588871;5192.402873669529;5446.863828404292;5462.20797984292;5485.601171701211;3962.6235507626875;4098.979505102485;4157.230912281222;4160.9375859362135;4184.4351012613215;3443.94959537341;3794.563625183098;4114.691398145136;4121.299379105544;4147.543770185277;3923.195744872294;4100.072965706128;4109.794510274489;4125.876223713293;4145.357469592218;3339.883104091345;3485.6514348565192;3685.410334346505;4033.4554835097474;4064.155091436007;3315.8507867146036;3546.4325707886874;3553.2107295772694;3570.428731402963;3573.6605022522817;5276.551493123089;5311.787528498855;5340.679559226459;5365.812196573102;5384.446265464813;4328.9783551082355;4713.363695901044;4764.7617619119255;4822.469528351879;4828.806847726087;5320.361556613192;5486;5801.274840644919;5812.8837423251725;5831.4168583141345;3886.2810380079495;3897.941530877011;3909.569947305794;3934.3705007198996;3942.1918506706343;5251.658642188246;5377.435369286247;5792.44971727681;6000.309964354068;6019.277686677583;3445.414279572479;3760.6427389397963;3941.152652179795;3974.20515896816;4009.498812648419;3574.267275208576;3998;4006.4991876015497;4016.638502534786;4041.6362527372676;3798.4872042273937;4011.618896204856;4235.428217190702;4737.857864264078;4785.955867292133;5049.747512624354;5901.023157242372;5942.286925568959;5946.464818166386;5965.343812180657;1213.6237766292477;1229.6495498782804;1305.3929922585853;1325.3836965810945;1325.8077578751027;702.68379229347;884.6815146547367;887.3211992825494;893.6246776353947;896.8296023755465;4877.561019508261;4946.233333833262;5069.923951140662;5130.92250627365;5163.277141200235;4874.0503798480895;4965.652404331662;5450.964316779799;5754.338251101177;5772.884542309131;4420.8673739787855;4643.024964757439;4892.559669629751;5100;5129.846104616868;2469.5060987802512;3054.862531186102;3056.908292751221;3073.631164260258;3077.691731201599;5076.466970968069;5458.918116228259;6055.913806624314;6056.757729690851;6081.8479538011015;5259.447757985433;5510.5397069776545;5631.141723231324;5953.940460595425;5984.940150598438;5624.606277560531;5872.853178670567;5913.467787899023;5951.315598706205;5957.791477298274;4680.174731854648;5136.845894623168;5316.627836051439;5692.260006199168;5847.210626565529;5388.865278367915;5495.82914748664;6019.969900150471;6045.395460453919;6084.756609735561;4795.664303498722;5373.731313434235;5464.863378415572;6253.37466253371;6279.183706118175;3502.422100353413;3519.859205631746;3992.6207010333974;3998.6601138902356;4001.4598029265676;5946.43508866657;5976.372480889444;5978.192943952511;6065.8180254592435;6083.756649733961;4815.3258543804595;4891.633127515522;5389.51494365509;5567.721613919369;5870;4838.137317132859;5813.110739009923;5815.621984570989;5820.708964551671;5852.736626851717;4594.816207351669;4982.950170498249;4994;5665.405132461032;5701.263393571026;5339.439358867263;5772.336181339199;5810.854728631818;5813.5583055117795;5825.533957283323;5720.3707592164;5722.799702010409;5726.398728133486;5733.655980641175;5736.196932429547 23:38:27 INFO - PROCESS | 1637 | |5;yui.html;720.9711611535539;750.9324160825524;783.6434422337837;821.5563595658344;857.5240741388529;15706.45027424039;16013.67972640548;16020.75968860468;16035.03789772613;16061.678766424664;14758.040727352714;14833.332500037497;14855.777163342558;14869.776953345709;14932;683.5932620091038;704.211283362632;856.4304737349655;860.4321148042309;874.5364956572995;853.99655404899;865.0398058155474;868.444195714743;868.6264906090411;870.1211776106115;933.1554942776764;933.2953620016912;934.0845970948475;935.060264434246;938.1884669760676;917.2982668258787;925.5464822237122;930.2232635749158;931.6925414613195;934.8878134623889;685.7291369908908;690.1200968764815;693.272064332454;694.4028135803205;695.2630211975272;14757.262136893112;15419.845801541953;15435.151066691327;15463.845361546353;15662;14538.563843084725;14746.26268686572;14865.405383784635;14880.553583392408;14932.70134597302;11928.165028448013;11960.521579136912;12016.158868879182;12063.095267854858;12067.87932120685;3711.4247291669835;3739.9902026452705;3784.5458544974504;3808.428735689641;3894.1043559981363;104326.47836760843;106651.46674266657;106687;107140;107962;11334;11474;11554.595589154507;11624;11774;20035.39893803188;21259.574808503745;24192.637110443357;24270;24328.51342973166;7689.9615501922135;7805.80485487856;7881.527108373516;7907.920920790833;7957;4228.682848786292;4257.361395790656;4325.870223893288;4373.475182978;4423.535528769497;3579.874704385334;3641;3676.246369494247;3684.1526448916898;3688.889333320005;640.7212862404868;646.689588997284;657.1457105762489;658.2791842931982;662.1491383572087;689.7827184436986;700.992990070103;710.765447402356;713.1549114299571;717.3006318839194;9597.376170548892;9745.853812192823;10375.481225938824;10498.370097794004;10543.525541350658;670.2560158224367;702.5538782872912;720.3588805962697;736.0762243384626;737.4358615659025;4625.629949604024;5056.570191533688;5058.772355244023;5061.9746901266;5067.594592432597;103133.48433257786;104949.42575861368;106590;106642.4003639946;107574;92528.14943701222;93908;96468.51765741127;96798;96852.03147968571;82330.58834705908;82603;83755.5812220935;83954.58022709847;84634.88412789606;8245.505269683837;8261.504309741435;9944.502774861372;9966.202703783534;9967.302288840001;35087;42950;46539;49811.75094124506;53426.73286633542;63001.68499157475;63633.68183159055;64535.67732161309;64713;67213;13121.268415771552;13283.86716132865;13412.731745365234;13510.459581617019;13766.24285664258;13029.348532573524;14347.856521434653;14837.40650373973;14912.850871491146;14957.177355245565;10912.454377281263;13821.565690135943;13957;14102.294885255902;14475;13596.12194161986;13633;13937.860621393656;13981.300934953415;14247.715045699235;13507.324633768469;13526.25605591702;13703;13837.100588461717;14194.645133871323;11079.446027698421;11233.943830280796;11284.21010529255;11318.351016183396;11913.106517011085;10100.899918189372;10747.838782418426;11251.156163287622;11302.547898083985;11302.886971130183 23:38:27 INFO - PROCESS | 1637 | __end_tp_report 23:38:27 INFO - PROCESS | 1637 | __start_cc_report 23:38:27 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,401 23:38:27 INFO - PROCESS | 1637 | __end_cc_report 23:38:27 INFO - PROCESS | 1637 | __startTimestamp1486021107887__endTimestamp 23:38:27 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | ------- Summary: start ------- 23:38:27 INFO - PROCESS | 1637 | Number of tests: 6 23:38:27 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5614.73 Median:5818.10 stddev:646.33 (11.1%) stddev-sans-first:621.92 23:38:27 INFO - PROCESS | 1637 | Values: 3284.3 3305.8 3334.3 3357.0 3362.0 5505.3 5505.9 5526.7 5562.4 5593.2 4442.3 5511.2 5552.3 5590.2 5622.1 5019.4 5103.4 5117.6 5155.7 5190.0 4314.7 5118.9 5143.9 5176.0 5241.5 5163.6 5173.0 5179.3 5191.6 5211.2 5025.6 5031.6 5052.8 5100.1 5117.6 5056.6 5058.9 5103.3 5118.6 5142.3 5377.2 5435.2 5534.7 5586.1 5623.6 6040.6 6107.0 6168.5 6181.8 6199.7 6120.2 6130.6 6149.2 6151.2 6172.3 5717.8 5735.8 5753.9 5759.9 5774.4 6134.3 6140.9 6220.4 6230.5 6241.2 5888.2 5945.8 5948.1 5989.0 6092.9 6104.5 6119.9 6124.4 6146.3 6188.0 5968.0 6042.4 6091.2 6094.6 6158.9 5074.5 6122.2 6151.2 6178.2 6215.1 4772.3 4820.6 4881.2 4901.7 4911.6 4306.7 4322.2 4364.3 4377.0 4413.6 5531.6 5582.4 5589.8 5607.4 5644.2 6143.3 6170.5 6200.2 6212.8 6215.4 5465.8 5540.6 5586.9 5628.3 5661.2 5315.1 5322.1 5322.9 5339.8 5344.3 6208.7 6216.2 6223.0 6225.2 6237.6 4527.5 5802.4 6034.3 6105.6 6127.3 5566.0 6241.2 6242.2 6252.1 6260.8 6119.6 6140.6 6164.0 6172.3 6172.8 5983.3 5993.9 6104.9 6142.7 6210.8 5095.6 5971.5 6013.0 6068.2 6098.2 5859.1 5947.4 5954.8 6009.1 6018.3 5522.4 5742.9 5818.1 5830.1 5933.7 6048.1 6049.3 6068.1 6068.2 6077.8 5877.6 5917.4 5918.5 5920.1 5938.5 23:38:27 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:66966.45 Median:4091.01 stddev:136524.86 (3337.2%) stddev-sans-first:136816.56 23:38:27 INFO - PROCESS | 1637 | Values: 468.2 510.4 517.3 519.9 535.7 41507.6 43506.0 43651.7 43656.6 43800.8 44803.0 44995.0 45338.8 45433.3 45453.3 8599.7 8756.8 9421.6 9657.3 9682.3 11541.9 11819.0 11840.3 12012.0 12044.5 11697.0 11767.7 11768.7 11783.9 11785.7 11699.5 11711.1 11798.1 11801.0 11813.2 11509.5 11589.8 11608.4 11624.3 11639.5 42297.8 42366.6 42596.8 42842.4 42892.6 4079.8 4102.3 4120.2 4176.3 4250.4 25434.0 31873.8 31965.4 32127.5 32222.8 3154.0 3281.0 3309.1 3369.5 3375.1 413284.0 483154.0 489275.0 489480.0 490689.0 66676.0 67118.0 67430.7 67459.0 67555.3 69547.7 69802.3 70254.0 70577.6 70668.3 16677.7 16822.3 16838.5 16863.3 16919.6 11598.8 11869.9 12591.3 12808.6 12914.7 17404.9 19174.2 19416.1 19530.0 19588.8 6590.0 6678.5 6728.8 6741.5 6773.8 774.0 852.7 887.0 899.9 902.6 26406.5 30225.8 30918.0 30987.8 31095.1 952.3 970.9 983.7 1100.3 1110.4 12805.5 14676.6 15802.8 16226.7 16229.4 534538.6 562685.0 576704.0 579263.0 582133.0 62503.4 63253.7 63304.0 63578.0 63589.0 1431.0 1441.5 1456.7 1457.9 1461.8 24532.0 26545.1 29165.6 29607.4 30346.2 54219.5 54587.5 54775.5 54807.7 54910.0 439706.0 505578.0 507482.5 509075.0 510058.0 35840.8 41313.8 42796.4 43052.0 43053.8 39891.6 47380.5 49390.5 50418.0 51398.7 38949.8 40170.0 41037.0 41383.1 41883.4 37112.7 39517.6 39638.4 40224.8 41093.0 37750.8 38427.4 39028.6 39169.6 40166.6 23732.7 33616.2 34507.8 35018.0 35129.0 20541.0 21354.8 24346.4 24605.8 25006.0 23:38:27 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:42008.77 Median:16425.35 stddev:117521.86 (715.5%) stddev-sans-first:117809.96 23:38:27 INFO - PROCESS | 1637 | Values: 375.7 387.3 393.1 396.7 404.9 19602.8 20915.9 21302.0 21599.0 21829.8 20138.9 20810.9 20994.9 21434.5 21840.1 5228.1 5887.7 6028.7 6035.7 6043.3 3961.1 4409.4 5922.7 6037.8 6046.6 5998.6 6087.8 6141.3 6147.9 6151.3 6122.7 6132.9 7328.5 7678.8 7747.9 7355.6 7537.3 7566.2 7613.9 7685.5 29734.0 29765.7 29855.0 29987.4 29993.4 2848.2 2995.7 3041.4 3042.1 3096.6 17173.7 17244.3 17340.0 17628.9 17927.7 1676.6 1971.0 2011.5 2012.8 2025.2 145613.1 169647.0 171101.3 171653.0 172361.0 19315.6 19900.4 21326.3 23544.4 24364.8 39651.8 41814.8 41941.4 42275.8 42757.1 7672.8 7874.3 8210.0 8984.3 9082.1 7260.6 8721.9 8758.6 8894.5 8952.2 4064.9 4856.7 4869.4 4896.8 4949.3 1681.0 2029.2 2072.9 2076.2 2081.2 977.9 991.6 1012.9 1024.7 1028.9 21777.7 21859.8 22018.8 22038.9 22062.1 952.9 962.8 1040.7 1065.6 1087.8 9844.6 10754.5 10825.5 11332.8 11346.4 624310.0 695225.0 708849.0 715257.4 744523.0 126068.0 131712.3 146774.0 160746.0 175230.1 1010.5 1058.8 1076.8 1078.0 1082.9 19932.5 20069.3 20297.8 20317.7 20394.2 62064.0 63518.7 63789.0 64527.4 64703.0 95144.0 95719.6 96068.0 96588.0 96753.5 7739.8 8103.0 8201.4 8555.2 8680.6 11070.8 13190.4 13501.5 13599.0 13840.9 5888.9 7705.4 10255.3 10619.2 10645.1 10459.7 10578.1 10701.5 10854.0 10918.2 10772.5 10989.0 11047.7 11061.7 11150.3 8266.0 8438.0 8459.7 8509.8 8628.6 9790.5 10758.5 10861.1 10875.2 11015.2 23:38:27 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5443.58 Median:5260.29 stddev:2950.19 (56.1%) stddev-sans-first:2942.39 23:38:27 INFO - PROCESS | 1637 | Values: 1345.4 1422.6 1468.2 1481.2 1487.9 7518.8 7816.6 8060.6 8482.8 8490.8 8103.2 8345.7 8370.6 8447.9 8481.4 1941.4 2090.0 2310.2 2339.5 2341.2 2087.6 2164.3 2213.2 2344.4 2364.4 2250.8 2327.9 2338.5 2338.5 2347.9 2093.9 2223.8 2322.6 2338.8 2361.5 1998.1 2285.7 2296.9 2355.0 2357.1 8460.0 8488.6 8550.1 8550.9 8558.6 5089.5 5209.9 5226.4 5242.0 5243.7 3506.1 3552.9 3564.7 3591.6 3652.3 1224.8 1226.8 1255.6 1271.9 1280.2 9747.0 10777.5 10789.9 10801.5 10855.6 4388.5 4705.3 4728.5 4757.1 4796.4 8210.5 9269.8 9609.9 9614.9 9617.0 5276.9 5318.3 5327.2 5331.8 5332.1 4738.9 4788.0 4796.8 4881.2 4887.0 3199.8 3933.7 3961.9 3988.2 3998.5 984.2 1216.1 1262.4 1284.3 1289.0 839.6 854.9 862.0 884.4 892.1 5450.3 5514.3 5707.9 5744.0 5763.6 958.4 992.5 996.6 1009.2 1038.5 3340.8 3386.2 3790.9 3871.5 3904.7 9785.6 9919.8 10079.1 10083.3 10096.5 8998.7 9427.7 9915.0 9955.1 10049.3 6707.6 7278.4 7853.3 7890.9 7907.0 4599.2 4910.8 5528.8 5793.0 5853.3 9116.8 9141.7 9155.0 9295.9 9323.4 9280.8 9444.5 9448.7 9575.5 9609.7 7233.5 7359.6 7381.8 7391.3 7400.4 7761.8 7898.5 7927.6 7934.3 7946.4 7179.4 7193.4 7254.4 7338.3 7400.9 7141.8 7169.4 7176.1 7218.1 7231.1 6354.5 6358.3 6373.6 6443.9 6499.2 5005.2 5027.3 5047.5 5115.6 5142.6 4937.7 4961.2 4985.9 5059.4 5198.6 23:38:27 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4589.92 Median:5130.38 stddev:1429.73 (27.9%) stddev-sans-first:1397.34 23:38:27 INFO - PROCESS | 1637 | Values: 319.2 346.3 355.4 365.0 370.2 5274.9 5293.4 5442.6 5514.9 5540.5 4739.4 5192.4 5446.9 5462.2 5485.6 3962.6 4099.0 4157.2 4160.9 4184.4 3443.9 3794.6 4114.7 4121.3 4147.5 3923.2 4100.1 4109.8 4125.9 4145.4 3339.9 3485.7 3685.4 4033.5 4064.2 3315.9 3546.4 3553.2 3570.4 3573.7 5276.6 5311.8 5340.7 5365.8 5384.4 4329.0 4713.4 4764.8 4822.5 4828.8 5320.4 5486.0 5801.3 5812.9 5831.4 3886.3 3897.9 3909.6 3934.4 3942.2 5251.7 5377.4 5792.4 6000.3 6019.3 3445.4 3760.6 3941.2 3974.2 4009.5 3574.3 3998.0 4006.5 4016.6 4041.6 3798.5 4011.6 4235.4 4737.9 4786.0 5049.7 5901.0 5942.3 5946.5 5965.3 1213.6 1229.6 1305.4 1325.4 1325.8 702.7 884.7 887.3 893.6 896.8 4877.6 4946.2 5069.9 5130.9 5163.3 4874.1 4965.7 5451.0 5754.3 5772.9 4420.9 4643.0 4892.6 5100.0 5129.8 2469.5 3054.9 3056.9 3073.6 3077.7 5076.5 5458.9 6055.9 6056.8 6081.8 5259.4 5510.5 5631.1 5953.9 5984.9 5624.6 5872.9 5913.5 5951.3 5957.8 4680.2 5136.8 5316.6 5692.3 5847.2 5388.9 5495.8 6020.0 6045.4 6084.8 4795.7 5373.7 5464.9 6253.4 6279.2 3502.4 3519.9 3992.6 3998.7 4001.5 5946.4 5976.4 5978.2 6065.8 6083.8 4815.3 4891.6 5389.5 5567.7 5870.0 4838.1 5813.1 5815.6 5820.7 5852.7 4594.8 4983.0 4994.0 5665.4 5701.3 5339.4 5772.3 5810.9 5813.6 5825.5 5720.4 5722.8 5726.4 5733.7 5736.2 23:38:27 INFO - PROCESS | 1637 | 23:38:27 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20859.28 Median:3762.27 stddev:30125.85 (800.7%) stddev-sans-first:30172.41 23:38:27 INFO - PROCESS | 1637 | Values: 721.0 750.9 783.6 821.6 857.5 15706.5 16013.7 16020.8 16035.0 16061.7 14758.0 14833.3 14855.8 14869.8 14932.0 683.6 704.2 856.4 860.4 874.5 854.0 865.0 868.4 868.6 870.1 933.2 933.3 934.1 935.1 938.2 917.3 925.5 930.2 931.7 934.9 685.7 690.1 693.3 694.4 695.3 14757.3 15419.8 15435.2 15463.8 15662.0 14538.6 14746.3 14865.4 14880.6 14932.7 11928.2 11960.5 12016.2 12063.1 12067.9 3711.4 3740.0 3784.5 3808.4 3894.1 104326.5 106651.5 106687.0 107140.0 107962.0 11334.0 11474.0 11554.6 11624.0 11774.0 20035.4 21259.6 24192.6 24270.0 24328.5 7690.0 7805.8 7881.5 7907.9 7957.0 4228.7 4257.4 4325.9 4373.5 4423.5 3579.9 3641.0 3676.2 3684.2 3688.9 640.7 646.7 657.1 658.3 662.1 689.8 701.0 710.8 713.2 717.3 9597.4 9745.9 10375.5 10498.4 10543.5 670.3 702.6 720.4 736.1 737.4 4625.6 5056.6 5058.8 5062.0 5067.6 103133.5 104949.4 106590.0 106642.4 107574.0 92528.1 93908.0 96468.5 96798.0 96852.0 82330.6 82603.0 83755.6 83954.6 84634.9 8245.5 8261.5 9944.5 9966.2 9967.3 35087.0 42950.0 46539.0 49811.8 53426.7 63001.7 63633.7 64535.7 64713.0 67213.0 13121.3 13283.9 13412.7 13510.5 13766.2 13029.3 14347.9 14837.4 14912.9 14957.2 10912.5 13821.6 13957.0 14102.3 14475.0 13596.1 13633.0 13937.9 13981.3 14247.7 13507.3 13526.3 13703.0 13837.1 14194.6 11079.4 11233.9 11284.2 11318.4 11913.1 10100.9 10747.8 11251.2 11302.5 11302.9 23:38:27 INFO - PROCESS | 1637 | -------- Summary: end -------- 23:38:27 INFO - PROCESS | 1637 | 23:38:28 INFO - TEST-INFO | 1637: exit 0 23:38:28 INFO - TEST-OK | dromaeo_css | took 1116234ms 23:38:28 INFO - TEST-START | kraken 23:38:28 INFO - operating with platform_type : mac_ 23:38:28 INFO - Initialising browser for kraken test... 23:38:28 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpGUb7BZ/profile http://localhost:49240/getInfo.html) 23:38:31 INFO - PROCESS | 1649 | 2017-02-01 23:38:31.287 plugin-container[1651:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 23:38:31 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:38:31 INFO - PROCESS | 1649 | 2017-02-01 23:38:31.288 plugin-container[1651:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 23:38:31 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:38:31 INFO - PROCESS | 1649 | 2017-02-01 23:38:31.288 plugin-container[1651:10045] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:38:31 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 23:38:31 INFO - PROCESS | 1649 | colorDepth:24 23:38:31 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 23:38:31 INFO - PROCESS | 1649 | __metrics 23:38:31 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 23:38:31 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 23:38:32 INFO - TEST-INFO | 1649: exit 0 23:38:32 INFO - Browser initialized. 23:38:32 INFO - Running cycle 1/1 for kraken test... 23:38:32 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpGUb7BZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:38:34 INFO - PROCESS | 1652 | 2017-02-01 23:38:34.694 plugin-container[1653:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 23:38:34 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:38:34 INFO - PROCESS | 1652 | 2017-02-01 23:38:34.695 plugin-container[1653:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 23:38:34 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:38:34 INFO - PROCESS | 1652 | 2017-02-01 23:38:34.695 plugin-container[1653:10161] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:38:44 INFO - PROCESS | 1652 | RSS: Main: 232259584 23:38:44 INFO - PROCESS | 1652 | 23:38:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 23:38:48 INFO - PROCESS | 1652 | RSS: Main: 246267904 23:38:48 INFO - PROCESS | 1652 | 23:38:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 23:38:51 INFO - PROCESS | 1652 | RSS: Main: 253874176 23:38:51 INFO - PROCESS | 1652 | 23:38:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 23:38:55 INFO - PROCESS | 1652 | RSS: Main: 256413696 23:38:55 INFO - PROCESS | 1652 | 23:38:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 23:38:58 INFO - PROCESS | 1652 | RSS: Main: 257609728 23:38:58 INFO - PROCESS | 1652 | 23:39:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:39:00 INFO - PROCESS | 1652 | RSS: Main: 259235840 23:39:00 INFO - PROCESS | 1652 | 23:39:03 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 23:39:04 INFO - PROCESS | 1652 | RSS: Main: 264933376 23:39:04 INFO - PROCESS | 1652 | 23:39:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 23:39:08 INFO - PROCESS | 1652 | RSS: Main: 264429568 23:39:08 INFO - PROCESS | 1652 | 23:39:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 23:39:11 INFO - PROCESS | 1652 | RSS: Main: 264343552 23:39:11 INFO - PROCESS | 1652 | 23:39:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:39:14 INFO - PROCESS | 1652 | RSS: Main: 263135232 23:39:14 INFO - PROCESS | 1652 | 23:39:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:39:16 INFO - PROCESS | 1652 | RSS: Main: 263671808 23:39:16 INFO - PROCESS | 1652 | 23:39:18 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:39:19 INFO - PROCESS | 1652 | RSS: Main: 263176192 23:39:19 INFO - PROCESS | 1652 | 23:39:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:39:23 INFO - PROCESS | 1652 | RSS: Main: 263618560 23:39:23 INFO - PROCESS | 1652 | 23:39:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:39:26 INFO - PROCESS | 1652 | RSS: Main: 272105472 23:39:26 INFO - PROCESS | 1652 | 23:39:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 23:39:29 INFO - PROCESS | 1652 | RSS: Main: 273481728 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | __start_tp_report 23:39:29 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 23:39:29 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 23:39:29 INFO - PROCESS | 1652 | |i|pagename|runs| 23:39:29 INFO - PROCESS | 1652 | |0;ai-astar;104;119;118;119;118;116;118;119;119;119 23:39:29 INFO - PROCESS | 1652 | |1;audio-beat-detection;140;113;113;148;114;114;114;104;174;150 23:39:29 INFO - PROCESS | 1652 | |2;audio-dft;201;211;209;213;194;194;194;211;265;278 23:39:29 INFO - PROCESS | 1652 | |3;audio-fft;87;59;59;84;62;61;60;60;71;60 23:39:29 INFO - PROCESS | 1652 | |4;audio-oscillator;92;72;83;74;69;71;83;58;86;110 23:39:29 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;90;89;91;88;91;88;100;76;138;88 23:39:29 INFO - PROCESS | 1652 | |6;imaging-darkroom;96;97;126;134;111;154;117;104;157;153 23:39:29 INFO - PROCESS | 1652 | |7;imaging-desaturate;91;90;76;77;129;144;151;94;144;132 23:39:29 INFO - PROCESS | 1652 | |8;json-parse-financial;63;61;63;62;62;62;62;61;63;62 23:39:29 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;71;73;71;81;76;74;104;106;81;71 23:39:29 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;87;93;90;91;87;87;81;96;111;110 23:39:29 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;128;159;162;157;165;150;157;149;184;155 23:39:29 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;173;172;175;173;158;192;171;174;176;177 23:39:29 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;69;66;67;79;90;90;90;92;86;69 23:39:29 INFO - PROCESS | 1652 | __end_tp_report 23:39:29 INFO - PROCESS | 1652 | __start_cc_report 23:39:29 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,917 23:39:29 INFO - PROCESS | 1652 | __end_cc_report 23:39:29 INFO - PROCESS | 1652 | __startTimestamp1486021169017__endTimestamp 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | ------- Summary: start ------- 23:39:29 INFO - PROCESS | 1652 | Number of tests: 14 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:116.90 Median:119.00 stddev:4.63 (3.9%) stddev-sans-first:1.00 23:39:29 INFO - PROCESS | 1652 | Values: 104.0 119.0 118.0 119.0 118.0 116.0 118.0 119.0 119.0 119.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:127.00 stddev:22.99 (18.1%) stddev-sans-first:24.00 23:39:29 INFO - PROCESS | 1652 | Values: 140.0 113.0 113.0 148.0 114.0 114.0 114.0 104.0 174.0 150.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:217.00 Median:211.00 stddev:29.85 (14.1%) stddev-sans-first:31.10 23:39:29 INFO - PROCESS | 1652 | Values: 201.0 211.0 209.0 213.0 194.0 194.0 194.0 211.0 265.0 278.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.30 Median:61.50 stddev:10.73 (17.4%) stddev-sans-first:8.37 23:39:29 INFO - PROCESS | 1652 | Values: 87.0 59.0 59.0 84.0 62.0 61.0 60.0 60.0 71.0 60.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:78.50 stddev:14.47 (18.4%) stddev-sans-first:14.66 23:39:29 INFO - PROCESS | 1652 | Values: 92.0 72.0 83.0 74.0 69.0 71.0 83.0 58.0 86.0 110.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:93.90 Median:88.50 stddev:16.54 (18.7%) stddev-sans-first:17.49 23:39:29 INFO - PROCESS | 1652 | Values: 90.0 89.0 91.0 88.0 91.0 88.0 100.0 76.0 138.0 88.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:124.90 Median:153.50 stddev:23.70 (15.4%) stddev-sans-first:22.72 23:39:29 INFO - PROCESS | 1652 | Values: 96.0 97.0 126.0 134.0 111.0 154.0 117.0 104.0 157.0 153.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:76.50 stddev:29.85 (39.0%) stddev-sans-first:30.61 23:39:29 INFO - PROCESS | 1652 | Values: 91.0 90.0 76.0 77.0 129.0 144.0 151.0 94.0 144.0 132.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 23:39:29 INFO - PROCESS | 1652 | Values: 63.0 61.0 63.0 62.0 62.0 62.0 62.0 61.0 63.0 62.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:80.80 Median:73.50 stddev:13.30 (18.1%) stddev-sans-first:13.62 23:39:29 INFO - PROCESS | 1652 | Values: 71.0 73.0 71.0 81.0 76.0 74.0 104.0 106.0 81.0 71.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:88.50 stddev:9.92 (11.2%) stddev-sans-first:10.26 23:39:29 INFO - PROCESS | 1652 | Values: 87.0 93.0 90.0 91.0 87.0 87.0 81.0 96.0 111.0 110.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:156.60 Median:158.00 stddev:14.06 (8.9%) stddev-sans-first:10.43 23:39:29 INFO - PROCESS | 1652 | Values: 128.0 159.0 162.0 157.0 165.0 150.0 157.0 149.0 184.0 155.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:174.50 stddev:8.23 (4.7%) stddev-sans-first:8.71 23:39:29 INFO - PROCESS | 1652 | Values: 173.0 172.0 175.0 173.0 158.0 192.0 171.0 174.0 176.0 177.0 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.80 Median:88.00 stddev:10.99 (12.5%) stddev-sans-first:10.94 23:39:29 INFO - PROCESS | 1652 | Values: 69.0 66.0 67.0 79.0 90.0 90.0 90.0 92.0 86.0 69.0 23:39:29 INFO - PROCESS | 1652 | -------- Summary: end -------- 23:39:29 INFO - PROCESS | 1652 | 23:39:29 INFO - TEST-INFO | 1652: exit 0 23:39:29 INFO - TEST-OK | kraken | took 61326ms 23:39:29 INFO - SUITE-END | took 1177s 23:39:30 INFO - Completed test suite (00:19:37) 23:39:30 INFO - javascript benchmark 23:39:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7487.688863351837, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.343131373726, 3305.785123966942, 3334.2831291272373, 3356.992902129362, 3361.98319008405, 5505.256790333304, 5505.889882202354, 5526.723663816808, 5562.3881373048935, 5593.216949627055, 4442.3114417265215, 5511.173324001402, 5552.333719953611, 5590.245316882219, 5622.128570071635, 5019.447860735316, 5103.438621751604, 5117.565006974393, 5155.7422128893595, 5190.0, 4314.654827613799, 5118.923216151761, 5143.948560514403, 5176.0, 5241.475852414766, 5163.586913046947, 5172.974135129338, 5179.2749015137915, 5191.584673226152, 5211.218317252424, 5025.623078269108, 5031.572316353115, 5052.823151189691, 5100.081985242655, 5117.565006974374, 5056.570191533724, 5058.873528161826, 5103.336566246401, 5118.616103792193, 5142.305788718512, 5377.220303056074, 5435.157550579666, 5534.723263836792, 5586.13414920688, 5623.634463759842, 6040.607360521596, 6106.969465152646, 6168.537359698041, 6181.752729890799, 6199.721012554446, 6120.204373431426, 6130.581587307925, 6149.200603921461, 6151.200343955302, 6172.259328880518, 5717.799877004285, 5735.827925162252, 5753.884922301572, 5759.884802303931, 5774.422557744234, 6134.294556126013, 6140.8771824564155, 6220.409061139185, 6230.532710046674, 6241.21984751906, 5888.205092312569, 5945.7621695132575, 5948.078047902582, 5988.970055149696, 6092.939070609237, 6104.542159338067, 6119.929102025404, 6124.448799608057, 6146.323904370515, 6187.978983467678, 5967.955607768623, 6042.39576042401, 6091.238595175603, 6094.634321940698, 6158.938410615926, 5074.4671809460215, 6122.1542093545395, 6151.200343955257, 6178.165947597106, 6215.06773983897, 4772.260299653559, 4820.566149046603, 4881.219004959189, 4901.730404827697, 4911.607071434277, 4306.65546756265, 4322.243607368698, 4364.323529852878, 4376.956230437655, 4413.558644135625, 5531.585131115183, 5582.413846546136, 5589.832305030855, 5607.355154157324, 5644.209810626516, 6143.280259208464, 6170.50635949123, 6200.193974783249, 6212.751489940351, 6215.409536094064, 5465.781368745285, 5540.584456165804, 5586.944130558642, 5628.324601047901, 5661.179129026268, 5315.096433606219, 5322.148456246982, 5322.92015619766, 5339.839804805784, 5344.331958505187, 6208.72060757267, 6216.191895053705, 6223.0, 6225.221847269091, 6237.594556353823, 4527.5019747827055, 5802.448767367094, 6034.275886893601, 6105.641708307991, 6127.281156068254, 5566.0, 6241.21984751906, 6242.188515492957, 6252.124702541557, 6260.780872669435, 6119.602225855305, 6140.631562106288, 6163.96918015407, 6172.259328880563, 6172.814815555541, 5983.341832398346, 5993.850153746192, 6104.938950610437, 6142.662153581596, 6210.782622608187, 5095.643304968617, 5971.492423144082, 6013.037913933749, 6068.21113255274, 6098.237720284965, 5859.121131830259, 5947.434993675594, 5954.821355359346, 6009.098635204754, 6018.3379828217985, 5522.3649280333275, 5742.885142297047, 5818.0981947798155, 5830.125481177857, 5933.703314834327, 6048.092786082123, 6049.274087109575, 6068.059450785136, 6068.180795592539, 6077.817665470043, 5877.617954832922, 5917.378675239038, 5918.526517878473, 5920.141579470866, 5938.495227905676], "value": 5574.839672893232, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.1596534221074, 510.44871538738164, 517.3481413419091, 519.9055987147057, 535.683946471582, 41507.58492415079, 43506.0, 43651.69044928649, 43656.56343436565, 43800.780996095054, 44803.0, 44995.0, 45338.77330613342, 45433.31850022244, 45453.318200227026, 8599.656013759442, 8756.824863502727, 9421.62313507459, 9657.32398732089, 9682.273829462783, 11541.884581154165, 11818.995385392253, 11840.348780817094, 12012.03903687703, 12044.518219271218, 11697.0, 11767.705807354841, 11768.705782355424, 11783.882161178364, 11785.705357366092, 11699.473523691455, 11711.063114950783, 11798.115141364431, 11800.996915262212, 11813.232139910879, 11509.539618415338, 11589.76820463586, 11608.361540115291, 11624.302541847432, 11639.47622356996, 42297.78851105756, 42366.57633423657, 42596.787016065035, 42842.35736463955, 42892.57107428948, 4079.7552146870985, 4102.261592913274, 4120.237756015147, 4176.2691528982305, 4250.3836943643, 25434.0, 31873.84063079693, 31965.360692786246, 32127.51808722871, 32222.838885805657, 3153.9684603153905, 3281.0, 3309.073459431363, 3369.4945758136478, 3375.0887260439545, 413283.999999997, 483154.0, 489275.0, 489479.99999999645, 490689.0, 66676.0, 67118.0, 67430.65138697297, 67459.0, 67555.32444675588, 69547.65226173837, 69802.3019769806, 70254.0, 70577.64711176412, 70668.2933170672, 16677.666446671, 16822.327106915585, 16838.49484515442, 16863.325466981183, 16919.577010574834, 11598.76802463946, 11869.881301186877, 12591.307478088793, 12808.615741527768, 12914.741705165845, 17404.898847460503, 19174.233030678617, 19416.12627431769, 19530.0, 19588.8041119587, 6590.0, 6678.465722742169, 6728.764493242713, 6741.528093033538, 6773.830654233683, 774.0247288416635, 852.7186028610544, 886.9822603547893, 899.860521619137, 902.6118768929292, 26406.47187056248, 30225.848870755945, 30918.0, 30987.84506077455, 31095.067147985596, 952.2524818017823, 970.8786401699788, 983.7393090830935, 1100.301308668985, 1110.4447776111945, 12805.487780488676, 14676.55970320892, 15802.841971580137, 16226.675466490604, 16229.350825967065, 534538.5642220455, 562685.0, 576704.0, 579263.0, 582133.0, 62503.37496625067, 63253.68373158105, 63304.0, 63578.0, 63589.0, 1430.9713805724036, 1441.5170917742464, 1456.6940942402002, 1457.8542145785548, 1461.751502244621, 24532.0, 26545.070922517618, 29165.56251656269, 29607.407851843273, 30346.179229245434, 54219.45780542144, 54587.45412545824, 54775.452245477034, 54807.725961369935, 54910.0, 439706.0, 505578.0, 507482.4625876847, 509075.0, 510058.0, 35840.82079589585, 41313.79343103265, 42796.35805462858, 43052.0, 43053.78473107614, 39891.60108398879, 47380.52619473761, 49390.50609494003, 50418.0, 51398.74300628473, 38949.8052509747, 40170.0, 41037.0, 41383.050712522694, 41883.37174942439, 37112.73365651216, 39517.60482395139, 39638.40542391924, 40224.798876005436, 41093.0, 37750.811245943594, 38427.42358864675, 39028.6097139025, 39169.6083039166, 40166.59833401746, 23732.694701791566, 33616.15959601029, 34507.827460862536, 35018.0, 35129.0, 20541.0, 21354.78645213528, 24346.391340216636, 24605.753942460346, 25006.0], "value": 20777.109272921676, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.7163341677035, 387.26419802375483, 393.10568456760865, 396.70643723644514, 404.9433079368887, 19602.803971960293, 20915.895420522913, 21302.041408136633, 21599.02804373805, 21829.781702182972, 20138.89930550345, 20810.89594552029, 20994.89502552489, 21434.464138396546, 21840.126394944222, 5228.111221092405, 5887.735051922674, 6028.728707208186, 6035.69821508925, 6043.305019922721, 3961.1285517186316, 4409.426774519324, 5922.674252916088, 6037.849053773669, 6046.576739628228, 5998.610090344136, 6087.817365479021, 6141.324454310023, 6147.8770424591485, 6151.323354431009, 6122.663253521055, 6132.90800637993, 7328.487005909588, 7678.8080297993, 7747.922520774833, 7355.595442250674, 7537.283958024033, 7566.167721550625, 7613.885791713129, 7685.5004424712215, 29734.0, 29765.70234297651, 29855.0, 29987.400251994837, 29993.400131997456, 2848.2167403963867, 2995.7004299570085, 3041.3613141240367, 3042.1025797389825, 3096.5819614352, 17173.742393863977, 17244.310227590882, 17340.046297453635, 17628.942263464105, 17927.73108403375, 1676.647903940174, 1971.044043638834, 2011.5272910865936, 2012.8087831656117, 2025.2405347994502, 145613.0877382457, 169647.0, 171101.2889871098, 171653.0, 172361.0, 19315.613687726447, 19900.402987910387, 21326.253581124587, 23544.411389715395, 24364.75635243625, 39651.80174099111, 41814.79092604518, 41941.370879436836, 42275.7886210567, 42757.1448571033, 7672.810714339286, 7874.251946065115, 8209.95895020533, 8984.32617553686, 9082.091790820998, 7260.636968151677, 8721.87312466834, 8758.605862736193, 8894.510801905957, 8952.194302512806, 4064.918701625951, 4856.717342848855, 4869.415670119608, 4896.779644915988, 4949.307097006422, 1680.957976050584, 2029.1940173245823, 2072.8652637578507, 2076.200662744854, 2081.1675329868176, 977.9462129582942, 991.6083147156833, 1012.9037741414554, 1024.6576984150786, 1028.8631062675786, 21777.673334899988, 21859.781402185774, 22018.779812201672, 22038.88980555087, 22062.11751529953, 952.9380590261611, 962.821877952581, 1040.6825918095053, 1065.6376831877199, 1087.7933192693363, 9844.556994935245, 10754.462276886125, 10825.458727063615, 11332.830007549894, 11346.432678366215, 624310.0, 695225.0, 708849.0, 715257.3556983247, 744523.0, 126068.0, 131712.3414382941, 146774.0, 160746.0, 175230.12384937995, 1010.545254635417, 1058.7617785998088, 1076.8492411062457, 1078.0459293525287, 1082.9079528240188, 19932.50168745735, 20069.29757458482, 20297.79702202959, 20317.695234571787, 20394.184232631123, 62064.0, 63518.68240658767, 63789.0, 64527.35472645213, 64703.0, 95144.0, 95719.56420653556, 96068.0, 96588.03411966072, 96753.51623241839, 7739.845203095794, 8103.0, 8201.384896132695, 8555.230029297392, 8680.609372578123, 11070.778584428106, 13190.406431710404, 13501.527446539323, 13599.0, 13840.861591383957, 5888.852778680567, 7705.422093343133, 10255.333403329058, 10619.15046796239, 10645.095166910589, 10459.68620941373, 10578.100861426557, 10701.464926753786, 10854.0, 10918.181136414642, 10772.515236814519, 10989.010989011027, 11047.668569942914, 11061.668149955514, 11150.330980141218, 8265.95867020661, 8437.987441507059, 8459.703910363107, 8509.829803404022, 8628.611712472828, 9790.461524616214, 10758.46207689628, 10861.131109511061, 10875.238733288594, 11015.228933974546], "value": 10838.658527594882, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.3878485289194, 1422.6087825847897, 1468.2071681292102, 1481.1705444950826, 1487.9330430130642, 7518.774436766906, 7816.570088645122, 8060.556669383189, 8482.830343393136, 8490.787730306738, 8103.230193131658, 8345.707900223479, 8370.58147092646, 8447.91552084479, 8481.448705834116, 1941.4078705994666, 2090.0176916849055, 2310.202979971911, 2339.4853132310864, 2341.227394959668, 2087.561612061469, 2164.2641501889434, 2213.236433430464, 2344.402177444749, 2364.3852598324474, 2250.8086812620863, 2327.8836058197107, 2338.462153704649, 2338.4738433852417, 2347.9178228762, 2093.9162433502643, 2223.8109760670363, 2322.5703244899746, 2338.812894968407, 2361.504084142332, 1998.0718606544608, 2285.714285714286, 2296.8851557422063, 2355.0462062864567, 2357.0689577616918, 8460.027096883876, 8488.618012189467, 8550.102239264912, 8550.914490855073, 8558.572071396406, 5089.465606111342, 5209.869753256161, 5226.3989641191365, 5242.004019236333, 5243.711595862226, 3506.1234691327168, 3552.875649352286, 3564.679178873888, 3591.622879597631, 3652.2695460907926, 1224.8180505811933, 1226.791445454275, 1255.6358655989752, 1271.9491220351174, 1280.1934781087855, 9747.0, 10777.515011824487, 10789.892101078967, 10801.513931873085, 10855.620053298097, 4388.451443569554, 4705.270683044134, 4728.4798672146035, 4757.09615173116, 4796.424429068465, 8210.548419836967, 9269.814603707888, 9609.90390096104, 9614.855777163348, 9617.0, 5276.868078297996, 5318.308619879468, 5327.200919862052, 5331.813386531453, 5332.066888294533, 4738.857834264978, 4788.0, 4796.784144713426, 4881.219004959189, 4887.047025829929, 3199.808011519316, 3933.7443066200603, 3961.920761584752, 3988.222296652183, 3998.460207871958, 984.2224642532319, 1216.0757824053605, 1262.3751243134523, 1284.3321472834075, 1289.0, 839.6431516605418, 854.8974123105268, 861.9569021548898, 884.4472204871955, 892.1078921078921, 5450.264214331094, 5514.28314319144, 5707.944030354398, 5743.971280143573, 5763.567732420085, 958.3579002068631, 992.4690290694588, 996.5714742660725, 1009.2077718990557, 1038.48075962019, 3340.816255105944, 3386.153461634591, 3790.9431358529646, 3871.496705428276, 3904.6876249899938, 9785.559649815776, 9919.752006199902, 10079.143272821892, 10083.294169408071, 10096.495175241209, 8998.685046023358, 9427.717168484956, 9914.950425247827, 9955.054269844353, 10049.29654924163, 6707.564008339442, 7278.417726581861, 7853.253940875607, 7890.8816367754525, 7906.960465197637, 4599.218132917345, 4910.803567857246, 5528.834134975958, 5792.9710351447975, 5853.268341457318, 9116.817663646823, 9141.725748227564, 9154.954225229098, 9295.907040929504, 9323.393979391318, 9280.767980800534, 9444.48055356962, 9448.669296574586, 9575.473348965596, 9609.663661771805, 7233.529820561646, 7359.558426494427, 7381.8154546136775, 7391.334779869838, 7400.444966627417, 7761.7671469855995, 7898.5260884344825, 7927.643256053376, 7934.325582325567, 7946.364290856602, 7179.425645948207, 7193.352598266181, 7254.419646428168, 7338.266173382619, 7400.925990740024, 7141.750038748619, 7169.390601798906, 7176.138863336224, 7218.133823941161, 7231.096112985877, 6354.459870911024, 6358.300586935526, 6373.58571692857, 6443.904536228946, 6499.155109835691, 5005.174146265971, 5027.3464449621315, 5047.520485553867, 5115.64190506661, 5142.614303927146, 4937.703737775745, 4961.206207007006, 4985.850424487271, 5059.418166910852, 5198.610104242121], "value": 4428.015812634366, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.238615901076, 346.3022010648544, 355.4277613043, 365.0399449447951, 370.22068545711295, 5274.947250527498, 5293.391260005101, 5442.612133905854, 5514.889702205954, 5540.529055030327, 4739.421736588871, 5192.402873669529, 5446.863828404292, 5462.20797984292, 5485.601171701211, 3962.6235507626875, 4098.979505102485, 4157.230912281222, 4160.9375859362135, 4184.4351012613215, 3443.94959537341, 3794.563625183098, 4114.691398145136, 4121.299379105544, 4147.543770185277, 3923.195744872294, 4100.072965706128, 4109.794510274489, 4125.876223713293, 4145.357469592218, 3339.883104091345, 3485.6514348565192, 3685.410334346505, 4033.4554835097474, 4064.155091436007, 3315.8507867146036, 3546.4325707886874, 3553.2107295772694, 3570.428731402963, 3573.6605022522817, 5276.551493123089, 5311.787528498855, 5340.679559226459, 5365.812196573102, 5384.446265464813, 4328.9783551082355, 4713.363695901044, 4764.7617619119255, 4822.469528351879, 4828.806847726087, 5320.361556613192, 5486.0, 5801.274840644919, 5812.8837423251725, 5831.4168583141345, 3886.2810380079495, 3897.941530877011, 3909.569947305794, 3934.3705007198996, 3942.1918506706343, 5251.658642188246, 5377.435369286247, 5792.44971727681, 6000.309964354068, 6019.277686677583, 3445.414279572479, 3760.6427389397963, 3941.152652179795, 3974.20515896816, 4009.498812648419, 3574.267275208576, 3998.0, 4006.4991876015497, 4016.638502534786, 4041.6362527372676, 3798.4872042273937, 4011.618896204856, 4235.428217190702, 4737.857864264078, 4785.955867292133, 5049.747512624354, 5901.023157242372, 5942.286925568959, 5946.464818166386, 5965.343812180657, 1213.6237766292477, 1229.6495498782804, 1305.3929922585853, 1325.3836965810945, 1325.8077578751027, 702.68379229347, 884.6815146547367, 887.3211992825494, 893.6246776353947, 896.8296023755465, 4877.561019508261, 4946.233333833262, 5069.923951140662, 5130.92250627365, 5163.277141200235, 4874.0503798480895, 4965.652404331662, 5450.964316779799, 5754.338251101177, 5772.884542309131, 4420.8673739787855, 4643.024964757439, 4892.559669629751, 5100.0, 5129.846104616868, 2469.5060987802512, 3054.862531186102, 3056.908292751221, 3073.631164260258, 3077.691731201599, 5076.466970968069, 5458.918116228259, 6055.913806624314, 6056.757729690851, 6081.8479538011015, 5259.447757985433, 5510.5397069776545, 5631.141723231324, 5953.940460595425, 5984.940150598438, 5624.606277560531, 5872.853178670567, 5913.467787899023, 5951.315598706205, 5957.791477298274, 4680.174731854648, 5136.845894623168, 5316.627836051439, 5692.260006199168, 5847.210626565529, 5388.865278367915, 5495.82914748664, 6019.969900150471, 6045.395460453919, 6084.756609735561, 4795.664303498722, 5373.731313434235, 5464.863378415572, 6253.37466253371, 6279.183706118175, 3502.422100353413, 3519.859205631746, 3992.6207010333974, 3998.6601138902356, 4001.4598029265676, 5946.43508866657, 5976.372480889444, 5978.192943952511, 6065.8180254592435, 6083.756649733961, 4815.3258543804595, 4891.633127515522, 5389.51494365509, 5567.721613919369, 5870.0, 4838.137317132859, 5813.110739009923, 5815.621984570989, 5820.708964551671, 5852.736626851717, 4594.816207351669, 4982.950170498249, 4994.0, 5665.405132461032, 5701.263393571026, 5339.439358867263, 5772.336181339199, 5810.854728631818, 5813.5583055117795, 5825.533957283323, 5720.3707592164, 5722.799702010409, 5726.398728133486, 5733.655980641175, 5736.196932429547], "value": 4141.522715488741, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.9711611535539, 750.9324160825524, 783.6434422337837, 821.5563595658344, 857.5240741388529, 15706.45027424039, 16013.67972640548, 16020.75968860468, 16035.03789772613, 16061.678766424664, 14758.040727352714, 14833.332500037497, 14855.777163342558, 14869.776953345709, 14932.0, 683.5932620091038, 704.211283362632, 856.4304737349655, 860.4321148042309, 874.5364956572995, 853.99655404899, 865.0398058155474, 868.444195714743, 868.6264906090411, 870.1211776106115, 933.1554942776764, 933.2953620016912, 934.0845970948475, 935.060264434246, 938.1884669760676, 917.2982668258787, 925.5464822237122, 930.2232635749158, 931.6925414613195, 934.8878134623889, 685.7291369908908, 690.1200968764815, 693.272064332454, 694.4028135803205, 695.2630211975272, 14757.262136893112, 15419.845801541953, 15435.151066691327, 15463.845361546353, 15662.0, 14538.563843084725, 14746.26268686572, 14865.405383784635, 14880.553583392408, 14932.70134597302, 11928.165028448013, 11960.521579136912, 12016.158868879182, 12063.095267854858, 12067.87932120685, 3711.4247291669835, 3739.9902026452705, 3784.5458544974504, 3808.428735689641, 3894.1043559981363, 104326.47836760843, 106651.46674266657, 106687.0, 107140.0, 107962.0, 11334.0, 11474.0, 11554.595589154507, 11624.0, 11774.0, 20035.39893803188, 21259.574808503745, 24192.637110443357, 24270.0, 24328.51342973166, 7689.9615501922135, 7805.80485487856, 7881.527108373516, 7907.920920790833, 7957.0, 4228.682848786292, 4257.361395790656, 4325.870223893288, 4373.475182978, 4423.535528769497, 3579.874704385334, 3641.0, 3676.246369494247, 3684.1526448916898, 3688.889333320005, 640.7212862404868, 646.689588997284, 657.1457105762489, 658.2791842931982, 662.1491383572087, 689.7827184436986, 700.992990070103, 710.765447402356, 713.1549114299571, 717.3006318839194, 9597.376170548892, 9745.853812192823, 10375.481225938824, 10498.370097794004, 10543.525541350658, 670.2560158224367, 702.5538782872912, 720.3588805962697, 736.0762243384626, 737.4358615659025, 4625.629949604024, 5056.570191533688, 5058.772355244023, 5061.9746901266, 5067.594592432597, 103133.48433257786, 104949.42575861368, 106590.0, 106642.4003639946, 107574.0, 92528.14943701222, 93908.0, 96468.51765741127, 96798.0, 96852.03147968571, 82330.58834705908, 82603.0, 83755.5812220935, 83954.58022709847, 84634.88412789606, 8245.505269683837, 8261.504309741435, 9944.502774861372, 9966.202703783534, 9967.302288840001, 35087.0, 42950.0, 46539.0, 49811.75094124506, 53426.73286633542, 63001.68499157475, 63633.68183159055, 64535.67732161309, 64713.0, 67213.0, 13121.268415771552, 13283.86716132865, 13412.731745365234, 13510.459581617019, 13766.24285664258, 13029.348532573524, 14347.856521434653, 14837.40650373973, 14912.850871491146, 14957.177355245565, 10912.454377281263, 13821.565690135943, 13957.0, 14102.294885255902, 14475.0, 13596.12194161986, 13633.0, 13937.860621393656, 13981.300934953415, 14247.715045699235, 13507.324633768469, 13526.25605591702, 13703.0, 13837.100588461717, 14194.645133871323, 11079.446027698421, 11233.943830280796, 11284.21010529255, 11318.351016183396, 11913.106517011085, 10100.899918189372, 10747.838782418426, 11251.156163287622, 11302.547898083985, 11302.886971130183], "value": 7653.744306859655, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.6999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 119.0, 118.0, 119.0, 118.0, 116.0, 118.0, 119.0, 119.0, 119.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 113.0, 113.0, 148.0, 114.0, 114.0, 114.0, 104.0, 174.0, 150.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 211.0, 209.0, 213.0, 194.0, 194.0, 194.0, 211.0, 265.0, 278.0], "value": 217.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 59.0, 59.0, 84.0, 62.0, 61.0, 60.0, 60.0, 71.0, 60.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 83.0, 74.0, 69.0, 71.0, 83.0, 58.0, 86.0, 110.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 88.0, 91.0, 88.0, 100.0, 76.0, 138.0, 88.0], "value": 93.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 126.0, 134.0, 111.0, 154.0, 117.0, 104.0, 157.0, 153.0], "value": 124.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 76.0, 77.0, 129.0, 144.0, 151.0, 94.0, 144.0, 132.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 63.0, 62.0, 62.0, 62.0, 62.0, 61.0, 63.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 71.0, 81.0, 76.0, 74.0, 104.0, 106.0, 81.0, 71.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 93.0, 90.0, 91.0, 87.0, 87.0, 81.0, 96.0, 111.0, 110.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 159.0, 162.0, 157.0, 165.0, 150.0, 157.0, 149.0, 184.0, 155.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 175.0, 173.0, 158.0, 192.0, 171.0, 174.0, 176.0, 177.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 67.0, 79.0, 90.0, 90.0, 90.0, 92.0, 86.0, 69.0], "value": 79.8, "unit": "score"}]}]} 23:39:30 INFO - Return code: 0 23:39:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:39:30 ERROR - # TBPL SUCCESS # 23:39:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:39:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:39:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:39:30 INFO - 2017-02-01 23:39:30.314 screenresolution[1657:10560] starting screenresolution argv=screenresolution get 23:39:30 INFO - 2017-02-01 23:39:30.316 screenresolution[1657:10560] Display 0: 1600x1200x32@60 23:39:30 INFO - 2017-02-01 23:39:30.322 screenresolution[1658:10563] starting screenresolution argv=screenresolution list 23:39:30 INFO - Available Modes on Display 0 23:39:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:39:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:39:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:39:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:39:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:39:30 INFO - Intel Iris: 23:39:30 INFO - Chipset Model: Intel Iris 23:39:30 INFO - Type: GPU 23:39:30 INFO - Bus: Built-In 23:39:30 INFO - VRAM (Dynamic, Max): 1536 MB 23:39:30 INFO - Vendor: Intel (0x8086) 23:39:30 INFO - Device ID: 0x0a2e 23:39:30 INFO - Revision ID: 0x0009 23:39:30 INFO - Displays: 23:39:30 INFO - Display: 23:39:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:39:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:39:30 INFO - Display Serial Number: 1600x1200 60 23:39:30 INFO - Main Display: Yes 23:39:30 INFO - Mirror: Off 23:39:30 INFO - Online: Yes 23:39:30 INFO - Rotation: Supported 23:39:30 INFO - Return code: 0 23:39:30 INFO - Running post-action listener: _resource_record_post_action 23:39:30 INFO - [mozharness: 2017-02-02 07:39:30.560152Z] Finished run-tests step (success) 23:39:30 INFO - Running post-run listener: _resource_record_post_run 23:39:30 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 40335360; Write bytes: 555194368; Read time: 400; Write time: 1693 23:39:30 INFO - TinderboxPrint: CPU usage
54.4% 23:39:30 INFO - TinderboxPrint: I/O read bytes / time
40,335,360 / 400 23:39:30 INFO - TinderboxPrint: I/O write bytes / time
555,194,368 / 1,693 23:39:30 INFO - TinderboxPrint: CPU idle
2,189.2 (45.6%) 23:39:30 INFO - TinderboxPrint: CPU system
971.8 (20.2%) 23:39:30 INFO - TinderboxPrint: CPU user
1,644.6 (34.2%) 23:39:30 INFO - TinderboxPrint: Swap in / out
290,816,000 / 0 23:39:30 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 236661248; Write bytes: 228954112; Read time: 17099; Write time: 407 23:39:30 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 39352320; Write bytes: 303343616; Read time: 380; Write time: 1233 23:39:31 INFO - Running post-run listener: _upload_blobber_files 23:39:31 INFO - Blob upload gear active. 23:39:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:39:31 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 23:39:31 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:39:31 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:39:31 INFO - (blobuploader) - INFO - Open directory for files ... 23:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:39:32 INFO - (blobuploader) - INFO - Done attempting. 23:39:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:39:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:39:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:39:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:39:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:39:33 INFO - (blobuploader) - INFO - Done attempting. 23:39:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:39:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:39:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:39:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:39:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:39:34 INFO - (blobuploader) - INFO - Done attempting. 23:39:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:39:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:39:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:39:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:39:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:39:37 INFO - (blobuploader) - INFO - Done attempting. 23:39:37 INFO - (blobuploader) - INFO - Iteration through files over. 23:39:37 INFO - Return code: 0 23:39:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:39:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:39:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7be00b9fd54fe7711ddd950c2d008f2804f5128e89b4b43a5ddbeb9d9efe86e479a3898a06c715b707b9a5b7a25c717fdf4450d19309ec4162d543ef3f69887c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59175dda665cdd9feb11f13a96b422299a696f849c045658d7c8ea090029f6eff90fedce19e3f35235922e2485ab22b0cf8beb7ad2e4e45161c5934a22d5291b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eab4caa9731a582ffa799ae2ebf97ce6566678e44233aef9d61ba6496093ff026f38507427922deb7a25dbe95940cf21924da6977baa9a40e054beec334fbf02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c6ed0aeb6e5280af476622ce4c700d038f4ec0acba841fc8a38db09e6fbf7f744daae0b9a0d07f8457de51ed87d0c22ef14aae97686e14f5f699f81ca9f4f0"} 23:39:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:39:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:39:37 INFO - Contents: 23:39:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7be00b9fd54fe7711ddd950c2d008f2804f5128e89b4b43a5ddbeb9d9efe86e479a3898a06c715b707b9a5b7a25c717fdf4450d19309ec4162d543ef3f69887c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59175dda665cdd9feb11f13a96b422299a696f849c045658d7c8ea090029f6eff90fedce19e3f35235922e2485ab22b0cf8beb7ad2e4e45161c5934a22d5291b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eab4caa9731a582ffa799ae2ebf97ce6566678e44233aef9d61ba6496093ff026f38507427922deb7a25dbe95940cf21924da6977baa9a40e054beec334fbf02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c6ed0aeb6e5280af476622ce4c700d038f4ec0acba841fc8a38db09e6fbf7f744daae0b9a0d07f8457de51ed87d0c22ef14aae97686e14f5f699f81ca9f4f0"} 23:39:37 INFO - Running post-run listener: copy_logs_to_upload_dir 23:39:37 INFO - Copying logs to upload dir... 23:39:37 INFO - mkdir: /builds/slave/test/build/upload/logs 23:39:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.573106 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-01 23:39:37.383256) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 23:39:37.387621) ========= 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.ntr24iVcq5/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.gB53lJs5Ty/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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/Firefox/sha512/7be00b9fd54fe7711ddd950c2d008f2804f5128e89b4b43a5ddbeb9d9efe86e479a3898a06c715b707b9a5b7a25c717fdf4450d19309ec4162d543ef3f69887c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59175dda665cdd9feb11f13a96b422299a696f849c045658d7c8ea090029f6eff90fedce19e3f35235922e2485ab22b0cf8beb7ad2e4e45161c5934a22d5291b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eab4caa9731a582ffa799ae2ebf97ce6566678e44233aef9d61ba6496093ff026f38507427922deb7a25dbe95940cf21924da6977baa9a40e054beec334fbf02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c6ed0aeb6e5280af476622ce4c700d038f4ec0acba841fc8a38db09e6fbf7f744daae0b9a0d07f8457de51ed87d0c22ef14aae97686e14f5f699f81ca9f4f0"} build_url:https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011064 build_url: 'https://queue.taskcluster.net/v1/task/DUSHt_qgQFGYLOem0fmTUw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7be00b9fd54fe7711ddd950c2d008f2804f5128e89b4b43a5ddbeb9d9efe86e479a3898a06c715b707b9a5b7a25c717fdf4450d19309ec4162d543ef3f69887c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59175dda665cdd9feb11f13a96b422299a696f849c045658d7c8ea090029f6eff90fedce19e3f35235922e2485ab22b0cf8beb7ad2e4e45161c5934a22d5291b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eab4caa9731a582ffa799ae2ebf97ce6566678e44233aef9d61ba6496093ff026f38507427922deb7a25dbe95940cf21924da6977baa9a40e054beec334fbf02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c6ed0aeb6e5280af476622ce4c700d038f4ec0acba841fc8a38db09e6fbf7f744daae0b9a0d07f8457de51ed87d0c22ef14aae97686e14f5f699f81ca9f4f0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 23:39:37.424840) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 23:39:37.425169) ========= 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.ntr24iVcq5/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.gB53lJs5Ty/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.005251 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 23:39:37.494717) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 23:39:37.495028) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 23:39:37.495415) ========= ========= Total master_lag: 0.23 =========