builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0079 starttime: 1489856248.94 results: success (0) buildid: 20170318082622 builduid: 026c835fd16e4393ab201dbedc85b532 revision: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:28.944411) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:28.945050) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:28.945516) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:29.196470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:29.196765) ========= 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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-03-18 09:57:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.7M=0.001s 2017-03-18 09:57:29 (16.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.162747 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:29.439965) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:29.440279) ========= 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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.028065 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:29.511116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-18 09:57:29.511508) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e1576dd8bd9d3a4ca418cf347133b8a4957ddeca --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 e1576dd8bd9d3a4ca418cf347133b8a4957ddeca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sGYPLhffGB/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.J3MJ3bdTLo/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-03-18 09:57:29,615 truncating revision to first 12 chars 2017-03-18 09:57:29,616 Setting DEBUG logging. 2017-03-18 09:57:29,616 attempt 1/10 2017-03-18 09:57:29,616 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e1576dd8bd9d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-18 09:57:30,369 unpacking tar archive at: mozilla-central-e1576dd8bd9d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.039409 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-18 09:57:30.575764) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:30.576086) ========= script_repo_revision: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:30.576467) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:30.576730) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-18 09:57:30.596347) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-18 09:57:30.596664) ========= /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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:57:30 INFO - MultiFileLogger online at 20170318 09:57:30 in /builds/slave/test 09:57:30 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 09:57:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:57:30 INFO - {'append_to_log': False, 09:57:30 INFO - 'base_work_dir': '/builds/slave/test', 09:57:30 INFO - 'blob_upload_branch': 'Firefox', 09:57:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:57:30 INFO - 'branch': 'Firefox', 09:57:30 INFO - 'buildbot_json_path': 'buildprops.json', 09:57:30 INFO - 'config_files': ('talos/mac_config.py',), 09:57:30 INFO - 'default_actions': ('clobber', 09:57:30 INFO - 'read-buildbot-config', 09:57:30 INFO - 'download-and-extract', 09:57:30 INFO - 'populate-webroot', 09:57:30 INFO - 'create-virtualenv', 09:57:30 INFO - 'install', 09:57:30 INFO - 'run-tests'), 09:57:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:57:30 INFO - 'download_minidump_stackwalk': True, 09:57:30 INFO - 'download_symbols': 'ondemand', 09:57:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:57:30 INFO - 'tooltool.py': '/tools/tooltool.py', 09:57:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:57:30 INFO - '/tools/misc-python/virtualenv.py')}, 09:57:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:57:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:57:30 INFO - 'gecko_profile': False, 09:57:30 INFO - 'gecko_profile_interval': 0, 09:57:30 INFO - 'installer_path': 'installer.exe', 09:57:30 INFO - 'log_level': 'info', 09:57:30 INFO - 'log_name': 'talos', 09:57:30 INFO - 'log_to_console': True, 09:57:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:57:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:57:30 INFO - 'opt_config_files': (), 09:57:30 INFO - 'pip_index': False, 09:57:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:57:30 INFO - 'cmd': ('bash', 09:57:30 INFO - '-c', 09:57:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:57:30 INFO - 'enabled': True, 09:57:30 INFO - 'halt_on_failure': False, 09:57:30 INFO - 'name': 'check_screen_resolution'},), 09:57:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:57:30 INFO - 'cmd': ('bash', 09:57:30 INFO - '-c', 09:57:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:57:30 INFO - 'enabled': True, 09:57:30 INFO - 'halt_on_failure': False, 09:57:30 INFO - 'name': 'check_screen_resolution'},), 09:57:30 INFO - 'run_cmd_checks_enabled': True, 09:57:30 INFO - 'suite': 'dromaeojs-e10s', 09:57:30 INFO - 'system_bits': '32', 09:57:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:57:30 INFO - 'title': 't-yosemite-r7-0079', 09:57:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:57:30 INFO - 'use_talos_json': True, 09:57:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:57:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:57:30 INFO - 'work_dir': 'build'} 09:57:30 INFO - [mozharness: 2017-03-18 16:57:30.814235Z] Running clobber step. 09:57:30 INFO - Running pre-action listener: _resource_record_pre_action 09:57:30 INFO - Running main action method: clobber 09:57:30 INFO - rmtree: /builds/slave/test/build 09:57:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:57:35 INFO - Running post-action listener: _resource_record_post_action 09:57:35 INFO - [mozharness: 2017-03-18 16:57:35.269471Z] Finished clobber step (success) 09:57:35 INFO - [mozharness: 2017-03-18 16:57:35.269718Z] Running read-buildbot-config step. 09:57:35 INFO - Running pre-action listener: _resource_record_pre_action 09:57:35 INFO - Running main action method: read_buildbot_config 09:57:35 INFO - Using buildbot properties: 09:57:35 INFO - { 09:57:35 INFO - "project": "", 09:57:35 INFO - "product": "firefox", 09:57:35 INFO - "script_repo_revision": "production", 09:57:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:57:35 INFO - "repository": "", 09:57:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:57:35 INFO - "buildid": "20170318082622", 09:57:35 INFO - "pgo_build": "False", 09:57:35 INFO - "basedir": "/builds/slave/test", 09:57:35 INFO - "buildnumber": 11, 09:57:35 INFO - "slavename": "t-yosemite-r7-0079", 09:57:35 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:57:35 INFO - "platform": "yosemite_r7", 09:57:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:57:35 INFO - "branch": "mozilla-central", 09:57:35 INFO - "repo_path": "mozilla-central", 09:57:35 INFO - "revision": "e1576dd8bd9d3a4ca418cf347133b8a4957ddeca", 09:57:35 INFO - "stage_platform": "macosx64", 09:57:35 INFO - "builduid": "026c835fd16e4393ab201dbedc85b532", 09:57:35 INFO - "slavebuilddir": "test" 09:57:35 INFO - } 09:57:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:57:35 INFO - Running post-action listener: _resource_record_post_action 09:57:35 INFO - [mozharness: 2017-03-18 16:57:35.275309Z] Finished read-buildbot-config step (success) 09:57:35 INFO - [mozharness: 2017-03-18 16:57:35.275549Z] Running download-and-extract step. 09:57:35 INFO - Running pre-action listener: _resource_record_pre_action 09:57:35 INFO - Running main action method: download_and_extract 09:57:35 INFO - mkdir: /builds/slave/test/build/tests 09:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:35 INFO - trying https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:57:35 INFO - Downloading https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:57:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:57:36 INFO - Downloaded 1325 bytes. 09:57:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:57:36 INFO - Using the following test package requirements: 09:57:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:57:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:57:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:57:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:57:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'jsshell-mac.zip'], 09:57:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:57:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:57:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:57:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:57:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:57:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:57:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:57:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:57:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:57:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:57:36 INFO - Fetch https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:57:39 INFO - Expected file size: 52475165 09:57:39 INFO - Obtained file size: 52475165 09:57:55 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:57:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:57:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:57:55 INFO - Fetch https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:57:57 INFO - Expected file size: 52475165 09:57:57 INFO - Obtained file size: 52475165 09:58:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:58:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:58:13 INFO - Fetch https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:58:14 INFO - Expected file size: 14404171 09:58:14 INFO - Obtained file size: 14404171 09:58:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:14 INFO - trying https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:58:14 INFO - Downloading https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:58:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:58:16 INFO - Downloaded 75161762 bytes. 09:58:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:58:16 INFO - mkdir: /builds/slave/test/properties 09:58:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:58:16 INFO - Writing to file /builds/slave/test/properties/build_url 09:58:16 INFO - Contents: 09:58:16 INFO - build_url:https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:58:17 INFO - Running post-action listener: _resource_record_post_action 09:58:17 INFO - Running post-action listener: set_extra_try_arguments 09:58:17 INFO - [mozharness: 2017-03-18 16:58:17.640242Z] Finished download-and-extract step (success) 09:58:17 INFO - [mozharness: 2017-03-18 16:58:17.640372Z] Running populate-webroot step. 09:58:17 INFO - Running pre-action listener: _resource_record_pre_action 09:58:17 INFO - Running main action method: populate_webroot 09:58:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:58:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:58:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:58:17 INFO - '--tppagecycles', 09:58:17 INFO - '7'], 09:58:17 INFO - 'tests': ['tsvgm']}}, 09:58:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:58:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:58:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:58:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:58:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:58:17 INFO - 'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:58:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:58:17 INFO - 'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['damp', 'tps']}, 09:58:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:58:17 INFO - 'tests': ['damp', 'tps']}, 09:58:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['dromaeo_dom']}, 09:58:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:58:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['basic_compositor_video']}, 09:58:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:58:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['a11yr', 09:58:17 INFO - 'ts_paint', 09:58:17 INFO - 'tpaint', 09:58:17 INFO - 'sessionrestore', 09:58:17 INFO - 'sessionrestore_no_auto_restore', 09:58:17 INFO - 'tabpaint']}, 09:58:17 INFO - 'other-e10s': {'tests': ['a11yr', 09:58:17 INFO - 'ts_paint', 09:58:17 INFO - 'tpaint', 09:58:17 INFO - 'sessionrestore', 09:58:17 INFO - 'sessionrestore_no_auto_restore', 09:58:17 INFO - 'tabpaint']}, 09:58:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:58:17 INFO - 'ts_paint', 09:58:17 INFO - 'tpaint', 09:58:17 INFO - 'sessionrestore', 09:58:17 INFO - 'sessionrestore_no_auto_restore', 09:58:17 INFO - 'tabpaint']}, 09:58:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:58:17 INFO - 'ts_paint', 09:58:17 INFO - 'tpaint', 09:58:17 INFO - 'sessionrestore', 09:58:17 INFO - 'sessionrestore_no_auto_restore', 09:58:17 INFO - 'tabpaint']}, 09:58:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['a11yr', 09:58:17 INFO - 'ts_paint', 09:58:17 INFO - 'tpaint', 09:58:17 INFO - 'sessionrestore', 09:58:17 INFO - 'sessionrestore_no_auto_restore', 09:58:17 INFO - 'tabpaint']}, 09:58:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['a11yr', 09:58:17 INFO - 'ts_paint', 09:58:17 INFO - 'tpaint', 09:58:17 INFO - 'sessionrestore', 09:58:17 INFO - 'sessionrestore_no_auto_restore', 09:58:17 INFO - 'tabpaint']}, 09:58:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['tsvgx', 09:58:17 INFO - 'tsvgr_opacity', 09:58:17 INFO - 'tart', 09:58:17 INFO - 'tscrollx', 09:58:17 INFO - 'cart', 09:58:17 INFO - 'tsvg_static']}, 09:58:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:58:17 INFO - 'tsvgr_opacity', 09:58:17 INFO - 'tart', 09:58:17 INFO - 'tscrollx', 09:58:17 INFO - 'cart', 09:58:17 INFO - 'tsvg_static']}, 09:58:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:58:17 INFO - 'talos_options': ['--disable-e10s'], 09:58:17 INFO - 'tests': ['tp5o']}, 09:58:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:58:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:58:17 INFO - 'talos_options': ['--disable-e10s', 09:58:17 INFO - '--xperf_path', 09:58:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:17 INFO - 'tests': ['tp5n']}, 09:58:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:58:17 INFO - 'talos_options': ['--xperf_path', 09:58:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:17 INFO - 'tests': ['tp5n']}}, 09:58:17 INFO - 'talos.zip': {'path': '', 09:58:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:58:17 INFO - Running post-action listener: _resource_record_post_action 09:58:17 INFO - [mozharness: 2017-03-18 16:58:17.658485Z] Finished populate-webroot step (success) 09:58:17 INFO - [mozharness: 2017-03-18 16:58:17.658594Z] Running create-virtualenv step. 09:58:17 INFO - Running pre-action listener: _resource_record_pre_action 09:58:17 INFO - Running main action method: create_virtualenv 09:58:17 INFO - Creating virtualenv /builds/slave/test/build/venv 09:58:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:58:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:58:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:58:17 INFO - Using real prefix '/tools/python27' 09:58:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:58:18 INFO - Installing distribute.............................................................................................................................................................................................done. 09:58:21 INFO - Installing pip.................done. 09:58:21 INFO - Return code: 0 09:58:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:58:21 INFO - Reading from file tmpfile_stdout 09:58:21 INFO - Output received: 09:58:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:58:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:21 INFO - 'HOME': '/Users/cltbld', 09:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:21 INFO - 'LOGNAME': 'cltbld', 09:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:21 INFO - 'MOZ_NO_REMOTE': '1', 09:58:21 INFO - 'NO_EM_RESTART': '1', 09:58:21 INFO - 'PAGER': '/bin/cat', 09:58:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:21 INFO - 'PWD': '/builds/slave/test', 09:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:21 INFO - 'SHELL': '/bin/bash', 09:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:21 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:21 INFO - 'USER': 'cltbld', 09:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:21 INFO - 'XPC_FLAGS': '0x0', 09:58:21 INFO - 'XPC_SERVICE_NAME': '0', 09:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:58:21 INFO - Cleaning up... 09:58:21 INFO - Return code: 0 09:58:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:21 INFO - 'HOME': '/Users/cltbld', 09:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:21 INFO - 'LOGNAME': 'cltbld', 09:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:21 INFO - 'MOZ_NO_REMOTE': '1', 09:58:21 INFO - 'NO_EM_RESTART': '1', 09:58:21 INFO - 'PAGER': '/bin/cat', 09:58:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:21 INFO - 'PWD': '/builds/slave/test', 09:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:21 INFO - 'SHELL': '/bin/bash', 09:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:21 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:21 INFO - 'USER': 'cltbld', 09:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:21 INFO - 'XPC_FLAGS': '0x0', 09:58:21 INFO - 'XPC_SERVICE_NAME': '0', 09:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:22 INFO - Downloading/unpacking psutil>=3.1.1 09:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:58:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:26 INFO - Installing collected packages: psutil 09:58:26 INFO - Running setup.py install for psutil 09:58:26 INFO - building 'psutil._psutil_osx' extension 09:58:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:58:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:58:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:58:27 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:58:27 INFO - building 'psutil._psutil_posix' extension 09:58:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:58:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:58:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:58:27 INFO - ^ 09:58:27 INFO - 1 warning generated. 09:58:27 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:58:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:27 INFO - Successfully installed psutil 09:58:27 INFO - Cleaning up... 09:58:27 INFO - Return code: 0 09:58:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:58:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:58:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:27 INFO - 'HOME': '/Users/cltbld', 09:58:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:27 INFO - 'LOGNAME': 'cltbld', 09:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:27 INFO - 'MOZ_NO_REMOTE': '1', 09:58:27 INFO - 'NO_EM_RESTART': '1', 09:58:27 INFO - 'PAGER': '/bin/cat', 09:58:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:27 INFO - 'PWD': '/builds/slave/test', 09:58:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:27 INFO - 'SHELL': '/bin/bash', 09:58:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:27 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:27 INFO - 'USER': 'cltbld', 09:58:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:27 INFO - 'XPC_FLAGS': '0x0', 09:58:27 INFO - 'XPC_SERVICE_NAME': '0', 09:58:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:58:30 INFO - Installing collected packages: mozsystemmonitor 09:58:30 INFO - Running setup.py install for mozsystemmonitor 09:58:30 INFO - Successfully installed mozsystemmonitor 09:58:30 INFO - Cleaning up... 09:58:30 INFO - Return code: 0 09:58:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:30 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 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:58:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:30 INFO - 'HOME': '/Users/cltbld', 09:58:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:30 INFO - 'LOGNAME': 'cltbld', 09:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:30 INFO - 'MOZ_NO_REMOTE': '1', 09:58:30 INFO - 'NO_EM_RESTART': '1', 09:58:30 INFO - 'PAGER': '/bin/cat', 09:58:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:30 INFO - 'PWD': '/builds/slave/test', 09:58:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:30 INFO - 'SHELL': '/bin/bash', 09:58:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:30 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:30 INFO - 'USER': 'cltbld', 09:58:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:30 INFO - 'XPC_FLAGS': '0x0', 09:58:30 INFO - 'XPC_SERVICE_NAME': '0', 09:58:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:30 INFO - Downloading/unpacking jsonschema==2.5.1 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:58:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:34 INFO - Downloading functools32-3.2.3-2.tar.gz 09:58:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:58:34 INFO - warning: no files found matching '*.txt' 09:58:34 INFO - no previously-included directories found matching 'build' 09:58:34 INFO - no previously-included directories found matching 'dist' 09:58:34 INFO - no previously-included directories found matching '.git*' 09:58:34 INFO - Installing collected packages: jsonschema, functools32 09:58:34 INFO - Running setup.py install for functools32 09:58:34 INFO - warning: no files found matching '*.txt' 09:58:34 INFO - no previously-included directories found matching 'build' 09:58:34 INFO - no previously-included directories found matching 'dist' 09:58:34 INFO - no previously-included directories found matching '.git*' 09:58:34 INFO - Successfully installed jsonschema functools32 09:58:34 INFO - Cleaning up... 09:58:34 INFO - Return code: 0 09:58:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:34 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 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:58:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:34 INFO - 'HOME': '/Users/cltbld', 09:58:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:34 INFO - 'LOGNAME': 'cltbld', 09:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:34 INFO - 'MOZ_NO_REMOTE': '1', 09:58:34 INFO - 'NO_EM_RESTART': '1', 09:58:34 INFO - 'PAGER': '/bin/cat', 09:58:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:34 INFO - 'PWD': '/builds/slave/test', 09:58:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:34 INFO - 'SHELL': '/bin/bash', 09:58:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:34 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:34 INFO - 'USER': 'cltbld', 09:58:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:34 INFO - 'XPC_FLAGS': '0x0', 09:58:34 INFO - 'XPC_SERVICE_NAME': '0', 09:58:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:58:34 INFO - Cleaning up... 09:58:34 INFO - Return code: 0 09:58:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:34 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 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:58:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:34 INFO - 'HOME': '/Users/cltbld', 09:58:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:34 INFO - 'LOGNAME': 'cltbld', 09:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:34 INFO - 'MOZ_NO_REMOTE': '1', 09:58:34 INFO - 'NO_EM_RESTART': '1', 09:58:34 INFO - 'PAGER': '/bin/cat', 09:58:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:34 INFO - 'PWD': '/builds/slave/test', 09:58:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:34 INFO - 'SHELL': '/bin/bash', 09:58:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:34 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:34 INFO - 'USER': 'cltbld', 09:58:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:34 INFO - 'XPC_FLAGS': '0x0', 09:58:34 INFO - 'XPC_SERVICE_NAME': '0', 09:58:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:35 INFO - Downloading/unpacking blobuploader==1.2.4 09:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:37 INFO - Downloading blobuploader-1.2.4.tar.gz 09:58:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:58:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:58:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:38 INFO - Downloading docopt-0.6.1.tar.gz 09:58:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:58:39 INFO - Installing collected packages: blobuploader, requests, docopt 09:58:39 INFO - Running setup.py install for blobuploader 09:58:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:58:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:58:39 INFO - Running setup.py install for requests 09:58:39 INFO - Running setup.py install for docopt 09:58:39 INFO - Successfully installed blobuploader requests docopt 09:58:39 INFO - Cleaning up... 09:58:39 INFO - Return code: 0 09:58:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:39 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 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:39 INFO - 'HOME': '/Users/cltbld', 09:58:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:39 INFO - 'LOGNAME': 'cltbld', 09:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:39 INFO - 'MOZ_NO_REMOTE': '1', 09:58:39 INFO - 'NO_EM_RESTART': '1', 09:58:39 INFO - 'PAGER': '/bin/cat', 09:58:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:39 INFO - 'PWD': '/builds/slave/test', 09:58:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:39 INFO - 'SHELL': '/bin/bash', 09:58:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:39 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:39 INFO - 'USER': 'cltbld', 09:58:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:39 INFO - 'XPC_FLAGS': '0x0', 09:58:39 INFO - 'XPC_SERVICE_NAME': '0', 09:58:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-hLFbxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-4lpTUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-1FNdPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-IlBhSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-tzHQI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Ba1P4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-1Kt1E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-aOGThC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:40 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-D9hCGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:41 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-_GDZSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:41 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-vwRT8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:41 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-qAf82V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:41 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-0GpeXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:41 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-NNdqiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:41 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-wUkO5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:41 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-1BKlQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:41 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-h4gOIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:58:41 INFO - Running setup.py install for manifestparser 09:58:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:58:42 INFO - Running setup.py install for mozcrash 09:58:42 INFO - Running setup.py install for mozdebug 09:58:42 INFO - Running setup.py install for mozdevice 09:58:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:58:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:58:42 INFO - Running setup.py install for mozfile 09:58:42 INFO - Running setup.py install for mozhttpd 09:58:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:58:42 INFO - Running setup.py install for mozinfo 09:58:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:58:42 INFO - Running setup.py install for mozInstall 09:58:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Running setup.py install for mozleak 09:58:43 INFO - Running setup.py install for mozlog 09:58:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Running setup.py install for moznetwork 09:58:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Running setup.py install for mozprocess 09:58:43 INFO - Running setup.py install for mozprofile 09:58:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:58:43 INFO - Running setup.py install for mozrunner 09:58:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:58:44 INFO - Running setup.py install for mozscreenshot 09:58:44 INFO - Running setup.py install for moztest 09:58:44 INFO - Running setup.py install for mozversion 09:58:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:58:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:58:44 INFO - Cleaning up... 09:58:44 INFO - Return code: 0 09:58:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:44 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 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:58:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:44 INFO - 'HOME': '/Users/cltbld', 09:58:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:44 INFO - 'LOGNAME': 'cltbld', 09:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:44 INFO - 'MOZ_NO_REMOTE': '1', 09:58:44 INFO - 'NO_EM_RESTART': '1', 09:58:44 INFO - 'PAGER': '/bin/cat', 09:58:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:44 INFO - 'PWD': '/builds/slave/test', 09:58:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:44 INFO - 'SHELL': '/bin/bash', 09:58:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:44 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:44 INFO - 'USER': 'cltbld', 09:58:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:44 INFO - 'XPC_FLAGS': '0x0', 09:58:44 INFO - 'XPC_SERVICE_NAME': '0', 09:58:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:44 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-ekXlg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:58:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:44 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-LmpPdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-SVdtUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-mKJkdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-HmMvy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Xbg8Nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-fgDWyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Z1Y2Gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-BjOiBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-bWkKFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-mmoxMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:45 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-RuvTsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:46 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-A5y5z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:46 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-W3sEFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:46 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-WymCg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:46 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-OuXIQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:58:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:46 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Fx3nac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:49 INFO - Downloading blessings-1.6.tar.gz 09:58:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:49 INFO - Installing collected packages: blessings 09:58:49 INFO - Running setup.py install for blessings 09:58:49 INFO - Successfully installed blessings 09:58:49 INFO - Cleaning up... 09:58:49 INFO - Return code: 0 09:58:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:58:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:49 INFO - Reading from file tmpfile_stdout 09:58:49 INFO - Current package versions: 09:58:49 INFO - blessings == 1.6 09:58:49 INFO - blobuploader == 1.2.4 09:58:49 INFO - docopt == 0.6.1 09:58:49 INFO - functools32 == 3.2.3-2 09:58:49 INFO - jsonschema == 2.5.1 09:58:49 INFO - manifestparser == 1.1 09:58:49 INFO - mozInstall == 1.12 09:58:49 INFO - mozcrash == 1.0 09:58:49 INFO - mozdebug == 0.1 09:58:49 INFO - mozdevice == 0.49 09:58:49 INFO - mozfile == 1.2 09:58:49 INFO - mozhttpd == 0.7 09:58:49 INFO - mozinfo == 0.9 09:58:49 INFO - mozleak == 0.1 09:58:49 INFO - mozlog == 3.4 09:58:49 INFO - moznetwork == 0.27 09:58:49 INFO - mozprocess == 0.25 09:58:49 INFO - mozprofile == 0.28 09:58:49 INFO - mozrunner == 6.13 09:58:49 INFO - mozscreenshot == 0.1 09:58:49 INFO - mozsystemmonitor == 0.3 09:58:49 INFO - moztest == 0.8 09:58:49 INFO - mozversion == 1.4 09:58:49 INFO - psutil == 3.1.1 09:58:49 INFO - requests == 1.2.3 09:58:49 INFO - wsgiref == 0.1.2 09:58:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:49 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 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:58:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:49 INFO - 'HOME': '/Users/cltbld', 09:58:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:49 INFO - 'LOGNAME': 'cltbld', 09:58:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:49 INFO - 'MOZ_NO_REMOTE': '1', 09:58:49 INFO - 'NO_EM_RESTART': '1', 09:58:49 INFO - 'PAGER': '/bin/cat', 09:58:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:49 INFO - 'PWD': '/builds/slave/test', 09:58:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:49 INFO - 'SHELL': '/bin/bash', 09:58:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:49 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:49 INFO - 'USER': 'cltbld', 09:58:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:49 INFO - 'XPC_FLAGS': '0x0', 09:58:49 INFO - 'XPC_SERVICE_NAME': '0', 09:58:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:58:50 INFO - Cleaning up... 09:58:50 INFO - Return code: 0 09:58:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:58:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:50 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 0x100db8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dbb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f865ac6ef30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, '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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:58:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:58:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:58:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:50 INFO - 'HOME': '/Users/cltbld', 09:58:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:50 INFO - 'LOGNAME': 'cltbld', 09:58:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:50 INFO - 'MOZ_NO_REMOTE': '1', 09:58:50 INFO - 'NO_EM_RESTART': '1', 09:58:50 INFO - 'PAGER': '/bin/cat', 09:58:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:50 INFO - 'PWD': '/builds/slave/test', 09:58:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:50 INFO - 'SHELL': '/bin/bash', 09:58:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:58:50 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:58:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:50 INFO - 'USER': 'cltbld', 09:58:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:50 INFO - 'XPC_FLAGS': '0x0', 09:58:50 INFO - 'XPC_SERVICE_NAME': '0', 09:58:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:58:50 INFO - Cleaning up... 09:58:50 INFO - Return code: 0 09:58:50 INFO - Running post-action listener: _resource_record_post_action 09:58:50 INFO - Running post-action listener: _start_resource_monitoring 09:58:50 INFO - Starting resource monitoring. 09:58:50 INFO - [mozharness: 2017-03-18 16:58:50.442207Z] Finished create-virtualenv step (success) 09:58:50 INFO - [mozharness: 2017-03-18 16:58:50.443255Z] Running install step. 09:58:50 INFO - Running pre-action listener: _resource_record_pre_action 09:58:50 INFO - Running main action method: install 09:58:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:50 INFO - Reading from file tmpfile_stdout 09:58:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:58:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:58:50 INFO - Reading from file tmpfile_stdout 09:58:50 INFO - Output received: 09:58:50 INFO - Usage: mozinstall [options] installer 09:58:50 INFO - Options: 09:58:50 INFO - -h, --help show this help message and exit 09:58:50 INFO - -d DEST, --destination=DEST 09:58:50 INFO - Directory to install application into. [default: 09:58:50 INFO - "/builds/slave/test"] 09:58:50 INFO - --app=APP Application being installed. [default: firefox] 09:58:50 INFO - mkdir: /builds/slave/test/build/application 09:58:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:58:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:59:11 INFO - Reading from file tmpfile_stdout 09:59:11 INFO - Output received: 09:59:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:59:11 INFO - Running post-action listener: _resource_record_post_action 09:59:11 INFO - [mozharness: 2017-03-18 16:59:11.015470Z] Finished install step (success) 09:59:11 INFO - [mozharness: 2017-03-18 16:59:11.015610Z] Running run-tests step. 09:59:11 INFO - Running pre-action listener: _resource_record_pre_action 09:59:11 INFO - Running main action method: run_tests 09:59:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:59:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:59:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:59:11 INFO - 2017-03-18 09:59:11.030 screenresolution[1636:9050] starting screenresolution argv=screenresolution get 09:59:11 INFO - 2017-03-18 09:59:11.032 screenresolution[1636:9050] Display 0: 1600x1200x32@60 09:59:11 INFO - 2017-03-18 09:59:11.038 screenresolution[1637:9053] starting screenresolution argv=screenresolution list 09:59:11 INFO - Available Modes on Display 0 09:59:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:59:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:59:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:59:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:59:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:59:11 INFO - Intel Iris: 09:59:11 INFO - Chipset Model: Intel Iris 09:59:11 INFO - Type: GPU 09:59:11 INFO - Bus: Built-In 09:59:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:59:11 INFO - Vendor: Intel (0x8086) 09:59:11 INFO - Device ID: 0x0a2e 09:59:11 INFO - Revision ID: 0x0009 09:59:11 INFO - Displays: 09:59:11 INFO - Display: 09:59:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:59:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:59:11 INFO - Display Serial Number: 1600x1200 60 09:59:11 INFO - Main Display: Yes 09:59:11 INFO - Mirror: Off 09:59:11 INFO - Online: Yes 09:59:11 INFO - Rotation: Supported 09:59:11 INFO - Return code: 0 09:59:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:59:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:59:11 INFO - Python 2.7.3 09:59:11 INFO - Return code: 0 09:59:11 INFO - grabbing minidump binary from tooltool 09:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:11 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 0x100b96d30>, '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 0x100abddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b8bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:59:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:59:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:59:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:59:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:59:11 INFO - Return code: 0 09:59:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:59:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:59:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:59:11 INFO - ENV: RUST_BACKTRACE is now 1 09:59:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:59:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:59:11 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:59:11 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0079 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:59:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGYPLhffGB/Render', 09:59:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:11 INFO - 'HOME': '/Users/cltbld', 09:59:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:11 INFO - 'LOGNAME': 'cltbld', 09:59:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:59:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:59:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:11 INFO - 'MOZ_NO_REMOTE': '1', 09:59:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:59:11 INFO - 'NO_EM_RESTART': '1', 09:59:11 INFO - 'PAGER': '/bin/cat', 09:59:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:11 INFO - 'PWD': '/builds/slave/test', 09:59:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:59:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:11 INFO - 'RUST_BACKTRACE': '1', 09:59:11 INFO - 'SHELL': '/bin/bash', 09:59:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J3MJ3bdTLo/Listeners', 09:59:11 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 09:59:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:11 INFO - 'USER': 'cltbld', 09:59:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:11 INFO - 'XPC_FLAGS': '0x0', 09:59:11 INFO - 'XPC_SERVICE_NAME': '0', 09:59:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:11 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:59:11 INFO - mozversion application_buildid: 20170318082622 09:59:11 INFO - mozversion application_changeset: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca 09:59:11 INFO - mozversion application_display_name: Nightly 09:59:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:59:11 INFO - mozversion application_name: Firefox 09:59:11 INFO - mozversion application_remotingname: firefox 09:59:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:59:11 INFO - mozversion application_vendor: Mozilla 09:59:11 INFO - mozversion application_version: 55.0a1 09:59:11 INFO - mozversion platform_buildid: 20170318082622 09:59:11 INFO - mozversion platform_changeset: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca 09:59:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:59:11 INFO - mozversion platform_version: 55.0a1 09:59:11 INFO - using testdate: 1489856351 09:59:11 INFO - actual date: 1489856351 09:59:11 INFO - starting webserver on 'localhost:49241' 09:59:11 INFO - SUITE-START | Running 2 tests 09:59:11 INFO - TEST-START | dromaeo_css 09:59:11 INFO - operating with platform_type : mac_ 09:59:11 INFO - Initialising browser for dromaeo_css test... 09:59:11 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmp1A9VX1/profile http://localhost:49241/getInfo.html) 09:59:14 INFO - PID 1647 | 2017-03-18 09:59:14.668 plugin-container[1649:9238] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3b, name = 'com.apple.tsm.portname' 09:59:14 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:59:14 INFO - PID 1647 | 2017-03-18 09:59:14.671 plugin-container[1649:9238] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 09:59:14 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:59:14 INFO - PID 1647 | 2017-03-18 09:59:14.671 plugin-container[1649:9238] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:59:14 INFO - PID 1647 | __metrics Screen width/height:1600/1200 09:59:14 INFO - PID 1647 | colorDepth:24 09:59:14 INFO - PID 1647 | Browser inner width/height: 1024/689 09:59:14 INFO - PID 1647 | __metrics 09:59:14 ERROR - PID 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:59:15 INFO - TEST-INFO | 1647: exit 0 09:59:15 INFO - Browser initialized. 09:59:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:59:15 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmp1A9VX1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:18 INFO - PID 1651 | 2017-03-18 09:59:18.035 plugin-container[1652:9368] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:59:18 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:59:18 INFO - PID 1651 | 2017-03-18 09:59:18.036 plugin-container[1652:9368] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 09:59:18 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:59:18 INFO - PID 1651 | 2017-03-18 09:59:18.036 plugin-container[1652:9368] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:59:27 INFO - PID 1651 | RSS: Main: 227368960 09:59:27 INFO - PID 1651 | 10:02:30 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 10:02:32 INFO - PID 1651 | RSS: Main: 254664704 10:02:32 INFO - PID 1651 | 10:04:15 INFO - PID 1651 | ************************* 10:04:15 INFO - PID 1651 | A coding exception was thrown and uncaught in a Task. 10:04:15 INFO - PID 1651 | 10:04:15 ERROR - PID 1651 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:04:15 INFO - PID 1651 | Full stack: 10:04:15 INFO - PID 1651 | ************************* 10:05:34 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:05:35 INFO - PID 1651 | RSS: Main: 261132288 10:05:35 INFO - PID 1651 | 10:08:37 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:08:38 INFO - PID 1651 | RSS: Main: 272232448 10:08:38 INFO - PID 1651 | 10:11:40 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:11:40 INFO - PID 1651 | RSS: Main: 264957952 10:11:40 INFO - PID 1651 | 10:14:43 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:14:44 INFO - PID 1651 | RSS: Main: 277798912 10:14:44 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:17:47 INFO - PID 1651 | RSS: Main: 271560704 10:17:47 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | __start_tp_report 10:17:47 INFO - PID 1651 | _x_x_mozilla_page_load 10:17:47 INFO - PID 1651 | _x_x_mozilla_page_load_details 10:17:47 INFO - PID 1651 | |i|pagename|runs| 10:17:47 INFO - PID 1651 | |0;dojo.html;3134.9999999999995;3415.992282276728;3446.5519482467275;3456.6543345665436;3470.479428085787;5835.474807267345;5917.142014407908;5988.221531200943;6020.6086604370685;6079.5136389088875;5873.030949893273;6015.127806468059;6076.696165191734;6085.2393450818645;6108.797423689729;4433.756143412127;4916.459189489171;5286;5412.702301373423;5443.428440013801;5310.15037593985;5402.756875940592;5428.45715428456;5455.427180146087;5464.289642346489;4271.380649805792;5277.182036784305;5390.54180394663;5395.082835917903;5442.346918369801;5352.437994010651;5357.598180136466;5361.142217245222;5389.865253368697;5404.567634589223;4438.556144385568;5286.9471305287225;5300.787968481256;5300.893982120336;5343.145096784535;5738.426157384228;5763.4812866842185;5887.911681324784;5993.640381577119;6001.2498437695285;5201.895962080737;6256.562040657156;6286.434220920093;6290.056491526262;6295.181626388586;6355.078513615546;6369.331220221857;6380.457661098812;6410.551261411703;6426.646534440604;5910.556708246899;6035;6091.543134264948;6130.325664176937;6144.2319710036245;6260.7495700171485;6298.527610429236;6403.807885763435;6409.198850143732;6473.708683109177;6128.472568454217;6207.224096987877;6235.220597425322;6247.031710084944;6252.499800015988;6263.749450022039;6282.528810339243;6344.904826427607;6363.554551181465;6374.840628984312;6192.938070619326;6282.937170628326;6286.339934306923;6291.528135389773;6298.779542715983;5488.847342058132;6026.758929642765;6179.351168127372;6197.411245931629;6230.345813689497;5097.949020509822;5111.948880511147;5120.539151476385;5136.5377116059735;5144.614153938394;4586.472555656075;4592.08158368321;4598.425196850394;4605.516420775837;4654.743989080633;6005.039193728982;6041.335453100155;6059.272887253558;6066;6129.295131059986;6315.526335524854;6365.363463653597;6365.395287447685;6375.553711240262;6410.102585638015;5077.746112694351;6043.546733994968;6126.479249263863;6155.47678447337;6158.876822463526;5578.41426650204;5640.097584386478;5704.629199102045;5720.3421606515785;5739.339975902824;5948.345681975065;6363.936360636427;6498.837529061811;6549.672516374067;6574;5446.400895901527;6311.976634839712;6379.457746091634;6390;6408.231012278557;5482.876010417775;6590.90113648286;6621.900671489835;6622;6642.4021838034805;6156.384361563808;6202.596831205957;6211.099390588249;6217.222847144107;6245.18812554365;5627.071533196977;5906.468417842415;6350.206224221973;6351.68241587928;6399.9680001601555;6090.573659843769;6109.677964500999;6111.908321375094;6113.962834745811;6118.785842495491;6194.132821404917;6218.502519798496;6221.751129954751;6226.159468471879;6226.533010024357;4989;6098.664573448503;6106.1146133811235;6251.124842521959;6266.530010249158;6108.645631960441;6177.320494745484;6193.4425901669065;6222.62664240147;6253.6560489173535;6224.06639004153;6232.002879539252;6241.032639940817;6260.593061450991;6266.968665156645 10:17:47 INFO - PID 1651 | |1;ext.html;700.7967689370083;709.8793205155125;798.1699033005673;925.7778133248014;928.3223247029664;29493;38097.42853857194;38098.857034289016;38128.046798830044;38146;36955.67398934102;37152.62847371525;37167.25665486696;37197.698080567185;37305.06737331568;7648.923510764905;7839.255270749298;10692.625758098467;10935.453227338641;10961.12311015117;11090.611828586001;11215.775684486305;11255.831162532568;11259.380734059623;11276.774464510705;11195.216334856563;11211.831822522669;11254.043406310473;11281.492332845;11342.376169310684;11196;11219.60731374398;11233.269837460539;11240.325580465198;11255.493502792395;10853;10912.945435272852;10933.289336193122;10943.945280273627;10952.28810127347;37955.43066853999;38070.42894356587;38119.618803811885;38133.855984320515;38212.80893595542;3881.5352251320332;3911.178652482982;3927.4894263745814;3929.1945151244213;3938.074552480165;27285.317867053283;27321.20093845002;27568;27753.444931101465;28070.71743918359;3096.8451577421265;3142.073088438916;3154.2114471382156;3163.130139211712;3238.1095198820512;296728;296794;299464;299726;300470.9999999978;50921;50929.92560595144;51013.23480147801;51024.74487627613;51187.488125119016;52450.737746311024;52711;52742.73628631832;52916.206256906175;52940.470595294326;14539.78190327146;14923.104613723212;14943.178125203218;14967.176805275596;14978.625534361729;12137.878621213675;12142.210756300812;12147.696307592381;12153.331566763736;12153.69615759613;11361.375124368074;13381.397837097355;13962.162270263614;14002.369893354824;14194;6023.789167379121;6050.30421501533;6067.271927368655;6087.269527656621;6098.878022439526;1044.874615046184;1090.738222826516;1113.5267511307661;1121.3328069798456;1129.6893354327626;20287;20795.272165474136;24554.50890982206;25076.71114338172;26128.216153515426;1092.333364693766;1144.753877916252;1178.7328621731501;1219.7072702551502;1237.7338872142532;11917.821880921547;12875.549355772502;13615.387307570985;13638.522651707142;13813.378397972116;394377;396758;400668;402785;403478;42839.78580107079;54628.45371546313;55033;55366;55719.72140139274;1292.8513220979708;1419.6876687129022;1439.784032395149;1450.985490145106;1458.9854101459061;27561.897524099615;27852;27894;28013.57979630348;28419.715802842533;37880.05299867525;37980;38033.42949855699;39406.60593394029;39630.40554391629;316459;318735.65689543565;319297;320124;320236.3988180044;31696.5245521322;32218.67781322157;32227.194320142185;32343.51484727778;32436.351272973938;35890;38060.2387952245;39672.603273968045;40371.99070023155;41218.38172427356;33409.665903340654;33790.49314260337;33948.73794561847;34136.14659633529;34923.825380872935;33423.33153336871;33564.496532551544;34257.82871085629;34761.478577820846;34988.65011349854;33730.831345843115;34236;34479.655203448645;34513.827430862686;34556.48165277573;23507;27721.11052279286;28846.855765721037;30606.387872241987;30849;22800.31599052031;22953.54092918099;23161.420964476023;23204.883975580015;23239 10:17:47 INFO - PID 1651 | |2;jquery.html;402.4205144591786;413.41914609972997;417.18648635161435;431.72640709905767;432.03871386165787;19193.424197274086;20376.286829960954;20945.685814712793;21088.57822843542;21145;15316.617084572892;15435.3825846966;16056.83943160568;21241.044153013114;21364.679529807065;6346.6509341986175;6560.245571759261;6663.63350015749;6733.56231844931;6735.1244338235965;6101.816945491642;6358.650274234915;6562.967185164068;6655.667216639173;6658.700358483856;6660.200775906874;6675.799726008203;6677.399034086931;6681.198256209237;6696.631685257309;6379.553431259814;6580.50646201532;6627.701753421107;6638.203415590112;6649.268580456146;5304.9390121975375;6453.935460645381;6466;6486.059521369375;6487.57830741005;22587.20944766925;23209.3037208884;25170.24489265325;25546.36134096644;26105.477890442275;3302.9669703302898;3322.18606441421;3356.311956049004;3366.9831650841834;3370.1574606348413;15781.009649000149;16022.919885400615;16226.432074877323;16269.267882945296;16542.172891355385;2023.9797602024087;2091.3203208956998;2099.4121645939163;2100.222917520527;2106.199644135234;119397;147241.7913731295;150566.24716876345;154330.22834885865;154756;22345.994430250354;22876.055535279356;22904.85475726207;23094;23183;35718.46422303667;35746;35799.926002219974;35804.64195358065;35912.92261232167;10160.898391016142;10415.541824144626;10562.465008397929;10578.312409693346;10765;8352.331813454908;8405.789855253668;8441.83116337682;8555.443896146855;8576.356773242032;10362.430066346273;10447.791044179074;10524.473776311306;10586.470676466146;10640.414777187329;3763.5107436033686;4241.130568233505;4324.632406245441;4382.211201983673;4424.756638384855;813.4834380168636;843.6245870587652;913.4656226107669;920.447731361178;941.8963913969458;22856.428589285402;23354.7664523356;23366.766332336458;23372.181973630843;23441.882790585936;740.8851627997669;814.1573471457014;884.3323290915446;929.3958926697701;944.0842382888587;11923.70190745221;11979.34113623759;12086.456109475095;12172.330521821385;12229.14395992272;539514;673865;677702;680600;690994;106410.46794765977;129977;132000;135448;137905.310473447;1261.905357098203;1296.241698606307;1331.6604265912324;1332.586898061604;1336.3385124363308;17493.650126997134;20294.79705202929;20338.491537711085;20720.274790382264;20759.896200518902;56058;56425;57241.427585723606;57664.4233557659;57858.710706446196;75203.24796752182;77952.61023695073;78494.18822647168;80150.5992470034;80539;10971.561137554409;11680.883191167979;11836.289822610672;12013.279203247834;12018.158728888893;11885.22746021506;12593.866552010362;13041.869581304445;13093;13571.321433928066;11219.270747401719;11496.770064598828;11498;11771.823422648496;11799.52801887915;10300.484975751333;10530.104941079788;11218.270812397168;11311.604093856675;11507.597234096766;11006;11424.543018279175;11560.6531804046;11658.242214256046;11683.299002059564;11264.324140551593;11782.644995825589;11782.941085294518;11847.052235820942;11848.52605895789;10672.519736611715;11322.773544529227;11648.242864213798;11669.474873630537;11761.411929403666 10:17:47 INFO - PID 1651 | |3;mootools.html;1491.5525342397282;1545.5517899809056;1552.0842702805342;1565.248680633296;1590.2048975512243;10366.585336586537;10573.894261057387;10792.028717415431;10847.83728244076;10943.945280273607;10604.416757078357;10747.78504429911;10777.622783202589;10781.299215550984;10819.35083894967;2325.825563082767;2359.8230132740027;2363.858168509886;2369.7985671217966;2370.4429459077105;2313.525727225923;2351.318117745852;2351.4826738117586;2359.421941624296;2363.409147713072;2332.7317358503733;2340.134150364359;2343.753905839888;2348.154664320843;2354.9764502355015;2291.5416916616578;2298.10373954158;2310.1452462588863;2312.6877871487304;2319.559283736093;2256.4133325335365;2321.048370168223;2322.1408079010707;2323.1984965187153;2329.3710698111586;10915.235933484659;10984.912866555329;10988.28576142556;11010.724731881686;11018.50416731249;5385.7307134643115;5618.185363122365;5641.576881733846;5650.124230744241;5663.292088488939;3320.750943679234;3340.4822252550894;3528.3472557576674;3553.200529880782;3578.928421431583;995.318207028188;1107.1087774341652;1124.7694222684331;1198.4666823263651;1237.8824011718962;14884.479043233436;14906.478273260493;14910.254487275593;15042.473513427087;15115.622109447242;4370.322599997005;4596.850787303174;4600.677952543357;4707.976460117746;4709.693869898446;9546.761330966781;11182.776344473228;11241.213115081866;11249.887501124884;11268.943655281671;5603.607747457639;5636.774529018875;5639.492445679826;5643.435656434323;5649.548036157097;4959.032988567267;5026.84919452417;5101.311322971425;5102.183650615883;5180.274761533388;3260.6087269527807;3726.4969229153717;3792.488014118059;3851.4607954886337;3889.5915928827067;1042.0621440703428;1047.1831971062584;1047.2773786087575;1055.9313644613076;1056.7780766039218;815.4943934760367;852.0967774159411;922.1470140120362;933.0948979489884;938.9671361502451;5946.989011867993;5965.284165900033;6429.6142231466265;6621.735130594722;6798.082258895085;834.3075247544524;875.6847534887436;967.57426732239;989.737719504332;995.5271246158017;3985.282649123186;4055.3511438169753;4107;4136.793160341971;4165.271077561415;10975.231733778559;12726.300053496961;13161.276129812952;13163;13198.406071726795;11685.883141168479;12064.396780160956;12555.93722031384;13043.543475978295;13061.542846000344;9617.326787124974;9758.951205243786;9831.360961537477;9907.306488545877;9914.008599140028;6277.435030847246;6604.636744979072;6660.200775906728;6792.592444453348;6805.557638753465;11398.373089479826;11544.365059921785;11702.414879256174;11997.520099195934;12025.518979240733;10878.510467028844;10944.835827462754;11129.721756956142;11249.606263780728;11955.342456164994;7926.286634202949;8757.343199259954;8995.640174392951;9002.279817614706;9018.86471702938;9885.901140988499;9998.20014398861;10101.14140298083;10115.14021308197;10127.594896204364;8704.825903481767;8782.560871956504;8897;9019.954900225457;9049;6902.413294869993;8128.715494957648;8193.262606365455;8246.958765206136;8335.791605209919;7640.617969101634;7654.349380302737;7708.691652333844;7737.6518056686455;7788.766337009899;5578.9163162553405;5632.88734225305;5721.971390143022;5722.62802917809;5791.449812267828;5853.736581853742;5941.643501389931;6014.939850601613;6121.708712006372;6141.710240849472 10:17:47 INFO - PID 1651 | |4;prototype.html;386.4493097336295;389.2837179589555;391.06340314945703;394.9605039496049;399.6223568727553;6004.33952265251;6062.302835173957;6083.8174854754425;6090.6345619262875;6120.877582448348;5660.858478538039;5816.243888294526;5829.504492118175;5902.439268269508;5956.612820166697;4576.771161441931;4584.564466375689;4598.770061496937;4599.425071866017;4613.777349002517;4039.515258169024;4344.218040752663;4606.332081848147;4627.305904114393;4627.444706635191;4380.233459144637;4565.65757568182;4570.680052396334;4587.885302867422;4599.816007359702;4263.829446822124;4301.65586753059;4543.091381723669;4546.659000574954;4567.246404343279;3350.614679311886;4036.2936486114813;4053.716239863211;4054.2296963576828;4080.9183816323507;5545.231101252919;5813.447722466359;5831.504322132624;5870.184380152892;5900.734466948998;5750.568707346965;5757.539396848242;5769.163471296639;5771.307443106812;5797.275340582427;5317.867053323698;5599.7480113394995;6257.843553911189;6317.273513545955;6338.968305158491;4989.90020199594;5010.2735103409805;5014.448410674823;5028.421731500887;5069.645124841262;6486.383793539606;6715.764948226789;6735.989601559756;6818.704446155215;6824.522283440161;4799.736014519165;5085.567726743194;5183.170692689227;5233;5234.769829090161;4928.383952005999;5328.626996110235;5346.946530534645;5350.732463376816;5361.544268737123;5132.640715149903;5420.13277875538;5508.053191542585;5693.800716974886;5726.713664316851;6443.7744678936015;6447.806565803034;6452.12896258999;6453.257875344396;6507.251666058369;1802.765640466731;1818.7544681467832;1823.1704574418607;1828.44232509086;1842.751228584124;1317.6837558985967;1317.743040107189;1352.3035636647135;1357.3009899901558;1366.7471517769245;5029.84910452687;5163.896722065538;5187.922181167285;6121.867454520928;6125.479334256638;5597.80407685729;6670.299073736166;6732.326767323327;6732.865342693217;6771.322867713287;4767.73777442244;5303.496167864047;5752.590615299278;5929.851753706192;6116.29662588808;3488.3197776433353;3502.842372093262;3503.667151620592;3504.649535046526;3519.6480351964597;5872.647641141545;6452.709628066748;6611.710716410254;6773.001964420508;7011;5893.02765043763;6310.148130002391;6797.864042719118;6806.387425131663;6809.897851532231;5672.432756724294;5821.748324110336;6899.310068993061;6929.930700693029;6932.688029038604;5596.972015139898;5619.8595035124445;6765.695543700447;6804.591724496546;6829.4877884157895;6328.335524770017;6546.476281897532;6633.800985970429;6678.332166783282;6718.932810672026;6710.563813352312;6828.839097353562;7004.513397743727;7023.964880175567;7074.3633073023675;4937.654364194472;5041.445441001413;5165.793368265227;5250.89498210026;5294.841154765363;6162.537809664203;6243.875122497616;6656;6708.362705542965;6747.932520674731;5310.442403547572;6425.293217746138;6434.4852411806005;6455.160829092188;6477.8704425912165;5132.769025393792;5318.494742999409;6152.938470615237;6341.936580634135;6441.613503189824;6290.2451705793765;6299.181106456132;6306.306306306395;6336.026219417735;6362.77730279438;6100.359462256577;6361.650109244036;6496.415322620986;6538.574992625464;6557.442617377576;5383.892322153613;6328.398802113792;6363.236411630634;6368.235811702625;6433.485321174201 10:17:47 INFO - PID 1651 | |5;yui.html;694.312630495809;707.2219962903081;767.3554214459856;816.1675091406765;817.9836403271935;13286.202827830337;15170.393184272643;15319.463818766346;15921.442749503769;15995.600109997242;14523.927380363084;14546.927265363685;14687.632809179775;14688.779668304984;15056.473023444181;664.4485077385782;918.4443411735904;930.9162175404213;940.1632547033146;941.5104145844157;687.2062767503542;913.1918252346687;958.4919992404037;967.9467629280387;972.3825370889465;1010.15651930638;1017.0033367300014;1017.8117048346043;1018.3635227982511;1020.5203554329453;990.0396615283199;1003.413003393016;1003.523326419952;1010.242318261304;1012.6506355307444;680.9455243580502;691.9862401581656;745.3329270303049;755.2862544895077;757.1557713149854;15830.604234894105;16170.83829161705;16197.83802161987;16394.590135246595;16647.33410663584;13185.011124165725;13801.964852636092;14151.221682807442;14499.637509062253;14599.489017884323;11430.419423851967;11650.766984660258;11706;11923.284602923766;12223.449944752507;3634.2186429917697;3705.5182826232685;3729.701623870084;3884.0872394987155;3889.4360317753767;81416;91292.08707912969;103731;103980.48009759978;105250.42124368141;11115;11387.772244555063;11494.13793965456;11771.470283837249;11891.345975971404;23408.531829363317;23729.88135059348;24015.63976540353;24021.639675404884;24062.15782447641;7584.317411432998;7681;7793.844123117506;7885.369170466349;8039.115697273295;4216.241076606179;4262.829486820555;4269.573042695768;4270.2855977354875;4481.238189507726;3223.3714425687235;3296.950545741816;3304.5373647689344;3308.5037244413047;3312.2878581104696;607.9148919151322;611.62996387186;619.8295468746131;624.7001439309157;638.5306799502362;587.806024012075;611.9046906038142;634.2547506679633;661.6989269882192;709.6168069242655;9947.751306217402;9948.253880958957;9988.450635214987;10100.51556692382;10180;646.6848681719433;680.9615336611707;703.6587255181233;707.8796604577234;721.3940290156294;4586;4917.7970094477505;4921.729304888194;4952.430470495867;4984.77568509418;95751.56372654416;97523;98357;98909.50545247228;99830;60992.695036524536;64520.677396612715;82292.58853705693;82447.58776206081;83571.16428835756;65558;67459.66270168617;67772.3222767766;68254;68617.3138268621;8886.422382545114;10419.843702344622;10631.627893023708;10730.356178629307;10744;46418;47028.99999999863;48027;48199.75900120477;48223.75888120537;60356;60468.69765651144;60966.69516652388;60974.39025609687;61875.071873922825;13103.41034653462;13135.343232838512;13428.261445620585;13533.188008719508;13685.247311397969;12747.10770246111;13645;13915.652108697364;14176.486462175064;14595.85404145945;13085.672858178621;13114;13122.671933201364;13356.532521361705;13917.095388799695;13121.278329691959;13122;13141.605751827461;13141.605751827461;13695.863041369857;12680.74638507243;12945.029122815638;12955.287459189834;12972.401480166414;13666.385012674653;9245.306602004743;9664.613415463302;10792.946035270088;10919.181061420266;11917.463714132711;7917.366610671248;7921.960390198012;9039;10535.7883843359;10914.290571112851 10:17:47 INFO - PID 1651 | __end_tp_report 10:17:47 INFO - PID 1651 | __start_cc_report 10:17:47 INFO - PID 1651 | _x_x_mozilla_cycle_collect,427 10:17:47 INFO - PID 1651 | __end_cc_report 10:17:47 INFO - PID 1651 | __startTimestamp1489857467494__endTimestamp 10:17:47 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | ------- Summary: start ------- 10:17:47 INFO - PID 1651 | Number of tests: 6 10:17:47 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:5845.63 Median:6109.68 stddev:672.35 (11.0%) stddev-sans-first:639.90 10:17:47 INFO - PID 1651 | Values: 3135.0 3416.0 3446.6 3456.7 3470.5 5835.5 5917.1 5988.2 6020.6 6079.5 5873.0 6015.1 6076.7 6085.2 6108.8 4433.8 4916.5 5286.0 5412.7 5443.4 5310.2 5402.8 5428.5 5455.4 5464.3 4271.4 5277.2 5390.5 5395.1 5442.3 5352.4 5357.6 5361.1 5389.9 5404.6 4438.6 5286.9 5300.8 5300.9 5343.1 5738.4 5763.5 5887.9 5993.6 6001.2 5201.9 6256.6 6286.4 6290.1 6295.2 6355.1 6369.3 6380.5 6410.6 6426.6 5910.6 6035.0 6091.5 6130.3 6144.2 6260.7 6298.5 6403.8 6409.2 6473.7 6128.5 6207.2 6235.2 6247.0 6252.5 6263.7 6282.5 6344.9 6363.6 6374.8 6192.9 6282.9 6286.3 6291.5 6298.8 5488.8 6026.8 6179.4 6197.4 6230.3 5097.9 5111.9 5120.5 5136.5 5144.6 4586.5 4592.1 4598.4 4605.5 4654.7 6005.0 6041.3 6059.3 6066.0 6129.3 6315.5 6365.4 6365.4 6375.6 6410.1 5077.7 6043.5 6126.5 6155.5 6158.9 5578.4 5640.1 5704.6 5720.3 5739.3 5948.3 6363.9 6498.8 6549.7 6574.0 5446.4 6312.0 6379.5 6390.0 6408.2 5482.9 6590.9 6621.9 6622.0 6642.4 6156.4 6202.6 6211.1 6217.2 6245.2 5627.1 5906.5 6350.2 6351.7 6400.0 6090.6 6109.7 6111.9 6114.0 6118.8 6194.1 6218.5 6221.8 6226.2 6226.5 4989.0 6098.7 6106.1 6251.1 6266.5 6108.6 6177.3 6193.4 6222.6 6253.7 6224.1 6232.0 6241.0 6260.6 6267.0 10:17:47 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:48707.09 Median:3148.14 stddev:89997.29 (2858.7%) stddev-sans-first:90177.57 10:17:47 INFO - PID 1651 | Values: 700.8 709.9 798.2 925.8 928.3 29493.0 38097.4 38098.9 38128.0 38146.0 36955.7 37152.6 37167.3 37197.7 37305.1 7648.9 7839.3 10692.6 10935.5 10961.1 11090.6 11215.8 11255.8 11259.4 11276.8 11195.2 11211.8 11254.0 11281.5 11342.4 11196.0 11219.6 11233.3 11240.3 11255.5 10853.0 10912.9 10933.3 10943.9 10952.3 37955.4 38070.4 38119.6 38133.9 38212.8 3881.5 3911.2 3927.5 3929.2 3938.1 27285.3 27321.2 27568.0 27753.4 28070.7 3096.8 3142.1 3154.2 3163.1 3238.1 296728.0 296794.0 299464.0 299726.0 300471.0 50921.0 50929.9 51013.2 51024.7 51187.5 52450.7 52711.0 52742.7 52916.2 52940.5 14539.8 14923.1 14943.2 14967.2 14978.6 12137.9 12142.2 12147.7 12153.3 12153.7 11361.4 13381.4 13962.2 14002.4 14194.0 6023.8 6050.3 6067.3 6087.3 6098.9 1044.9 1090.7 1113.5 1121.3 1129.7 20287.0 20795.3 24554.5 25076.7 26128.2 1092.3 1144.8 1178.7 1219.7 1237.7 11917.8 12875.5 13615.4 13638.5 13813.4 394377.0 396758.0 400668.0 402785.0 403478.0 42839.8 54628.5 55033.0 55366.0 55719.7 1292.9 1419.7 1439.8 1451.0 1459.0 27561.9 27852.0 27894.0 28013.6 28419.7 37880.1 37980.0 38033.4 39406.6 39630.4 316459.0 318735.7 319297.0 320124.0 320236.4 31696.5 32218.7 32227.2 32343.5 32436.4 35890.0 38060.2 39672.6 40372.0 41218.4 33409.7 33790.5 33948.7 34136.1 34923.8 33423.3 33564.5 34257.8 34761.5 34988.7 33730.8 34236.0 34479.7 34513.8 34556.5 23507.0 27721.1 28846.9 30606.4 30849.0 22800.3 22953.5 23161.4 23204.9 23239.0 10:17:47 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:39210.45 Median:22466.60 stddev:109209.28 (486.1%) stddev-sans-first:109476.77 10:17:47 INFO - PID 1651 | Values: 402.4 413.4 417.2 431.7 432.0 19193.4 20376.3 20945.7 21088.6 21145.0 15316.6 15435.4 16056.8 21241.0 21364.7 6346.7 6560.2 6663.6 6733.6 6735.1 6101.8 6358.7 6563.0 6655.7 6658.7 6660.2 6675.8 6677.4 6681.2 6696.6 6379.6 6580.5 6627.7 6638.2 6649.3 5304.9 6453.9 6466.0 6486.1 6487.6 22587.2 23209.3 25170.2 25546.4 26105.5 3303.0 3322.2 3356.3 3367.0 3370.2 15781.0 16022.9 16226.4 16269.3 16542.2 2024.0 2091.3 2099.4 2100.2 2106.2 119397.0 147241.8 150566.2 154330.2 154756.0 22346.0 22876.1 22904.9 23094.0 23183.0 35718.5 35746.0 35799.9 35804.6 35912.9 10160.9 10415.5 10562.5 10578.3 10765.0 8352.3 8405.8 8441.8 8555.4 8576.4 10362.4 10447.8 10524.5 10586.5 10640.4 3763.5 4241.1 4324.6 4382.2 4424.8 813.5 843.6 913.5 920.4 941.9 22856.4 23354.8 23366.8 23372.2 23441.9 740.9 814.2 884.3 929.4 944.1 11923.7 11979.3 12086.5 12172.3 12229.1 539514.0 673865.0 677702.0 680600.0 690994.0 106410.5 129977.0 132000.0 135448.0 137905.3 1261.9 1296.2 1331.7 1332.6 1336.3 17493.7 20294.8 20338.5 20720.3 20759.9 56058.0 56425.0 57241.4 57664.4 57858.7 75203.2 77952.6 78494.2 80150.6 80539.0 10971.6 11680.9 11836.3 12013.3 12018.2 11885.2 12593.9 13041.9 13093.0 13571.3 11219.3 11496.8 11498.0 11771.8 11799.5 10300.5 10530.1 11218.3 11311.6 11507.6 11006.0 11424.5 11560.7 11658.2 11683.3 11264.3 11782.6 11782.9 11847.1 11848.5 10672.5 11322.8 11648.2 11669.5 11761.4 10:17:47 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:6489.16 Median:3566.06 stddev:4003.77 (112.3%) stddev-sans-first:3997.39 10:17:47 INFO - PID 1651 | Values: 1491.6 1545.6 1552.1 1565.2 1590.2 10366.6 10573.9 10792.0 10847.8 10943.9 10604.4 10747.8 10777.6 10781.3 10819.4 2325.8 2359.8 2363.9 2369.8 2370.4 2313.5 2351.3 2351.5 2359.4 2363.4 2332.7 2340.1 2343.8 2348.2 2355.0 2291.5 2298.1 2310.1 2312.7 2319.6 2256.4 2321.0 2322.1 2323.2 2329.4 10915.2 10984.9 10988.3 11010.7 11018.5 5385.7 5618.2 5641.6 5650.1 5663.3 3320.8 3340.5 3528.3 3553.2 3578.9 995.3 1107.1 1124.8 1198.5 1237.9 14884.5 14906.5 14910.3 15042.5 15115.6 4370.3 4596.9 4600.7 4708.0 4709.7 9546.8 11182.8 11241.2 11249.9 11268.9 5603.6 5636.8 5639.5 5643.4 5649.5 4959.0 5026.8 5101.3 5102.2 5180.3 3260.6 3726.5 3792.5 3851.5 3889.6 1042.1 1047.2 1047.3 1055.9 1056.8 815.5 852.1 922.1 933.1 939.0 5947.0 5965.3 6429.6 6621.7 6798.1 834.3 875.7 967.6 989.7 995.5 3985.3 4055.4 4107.0 4136.8 4165.3 10975.2 12726.3 13161.3 13163.0 13198.4 11685.9 12064.4 12555.9 13043.5 13061.5 9617.3 9759.0 9831.4 9907.3 9914.0 6277.4 6604.6 6660.2 6792.6 6805.6 11398.4 11544.4 11702.4 11997.5 12025.5 10878.5 10944.8 11129.7 11249.6 11955.3 7926.3 8757.3 8995.6 9002.3 9018.9 9885.9 9998.2 10101.1 10115.1 10127.6 8704.8 8782.6 8897.0 9020.0 9049.0 6902.4 8128.7 8193.3 8247.0 8335.8 7640.6 7654.3 7708.7 7737.7 7788.8 5578.9 5632.9 5722.0 5722.6 5791.4 5853.7 5941.6 6014.9 6121.7 6141.7 10:17:47 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5336.20 Median:5770.24 stddev:1537.81 (26.7%) stddev-sans-first:1496.57 10:17:47 INFO - PID 1651 | Values: 386.4 389.3 391.1 395.0 399.6 6004.3 6062.3 6083.8 6090.6 6120.9 5660.9 5816.2 5829.5 5902.4 5956.6 4576.8 4584.6 4598.8 4599.4 4613.8 4039.5 4344.2 4606.3 4627.3 4627.4 4380.2 4565.7 4570.7 4587.9 4599.8 4263.8 4301.7 4543.1 4546.7 4567.2 3350.6 4036.3 4053.7 4054.2 4080.9 5545.2 5813.4 5831.5 5870.2 5900.7 5750.6 5757.5 5769.2 5771.3 5797.3 5317.9 5599.7 6257.8 6317.3 6339.0 4989.9 5010.3 5014.4 5028.4 5069.6 6486.4 6715.8 6736.0 6818.7 6824.5 4799.7 5085.6 5183.2 5233.0 5234.8 4928.4 5328.6 5346.9 5350.7 5361.5 5132.6 5420.1 5508.1 5693.8 5726.7 6443.8 6447.8 6452.1 6453.3 6507.3 1802.8 1818.8 1823.2 1828.4 1842.8 1317.7 1317.7 1352.3 1357.3 1366.7 5029.8 5163.9 5187.9 6121.9 6125.5 5597.8 6670.3 6732.3 6732.9 6771.3 4767.7 5303.5 5752.6 5929.9 6116.3 3488.3 3502.8 3503.7 3504.6 3519.6 5872.6 6452.7 6611.7 6773.0 7011.0 5893.0 6310.1 6797.9 6806.4 6809.9 5672.4 5821.7 6899.3 6929.9 6932.7 5597.0 5619.9 6765.7 6804.6 6829.5 6328.3 6546.5 6633.8 6678.3 6718.9 6710.6 6828.8 7004.5 7024.0 7074.4 4937.7 5041.4 5165.8 5250.9 5294.8 6162.5 6243.9 6656.0 6708.4 6747.9 5310.4 6425.3 6434.5 6455.2 6477.9 5132.8 5318.5 6152.9 6341.9 6441.6 6290.2 6299.2 6306.3 6336.0 6362.8 6100.4 6361.7 6496.4 6538.6 6557.4 5383.9 6328.4 6363.2 6368.2 6433.5 10:17:47 INFO - PID 1651 | 10:17:47 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:19207.54 Median:3806.89 stddev:26567.92 (697.9%) stddev-sans-first:26606.08 10:17:47 INFO - PID 1651 | Values: 694.3 707.2 767.4 816.2 818.0 13286.2 15170.4 15319.5 15921.4 15995.6 14523.9 14546.9 14687.6 14688.8 15056.5 664.4 918.4 930.9 940.2 941.5 687.2 913.2 958.5 967.9 972.4 1010.2 1017.0 1017.8 1018.4 1020.5 990.0 1003.4 1003.5 1010.2 1012.7 680.9 692.0 745.3 755.3 757.2 15830.6 16170.8 16197.8 16394.6 16647.3 13185.0 13802.0 14151.2 14499.6 14599.5 11430.4 11650.8 11706.0 11923.3 12223.4 3634.2 3705.5 3729.7 3884.1 3889.4 81416.0 91292.1 103731.0 103980.5 105250.4 11115.0 11387.8 11494.1 11771.5 11891.3 23408.5 23729.9 24015.6 24021.6 24062.2 7584.3 7681.0 7793.8 7885.4 8039.1 4216.2 4262.8 4269.6 4270.3 4481.2 3223.4 3297.0 3304.5 3308.5 3312.3 607.9 611.6 619.8 624.7 638.5 587.8 611.9 634.3 661.7 709.6 9947.8 9948.3 9988.5 10100.5 10180.0 646.7 681.0 703.7 707.9 721.4 4586.0 4917.8 4921.7 4952.4 4984.8 95751.6 97523.0 98357.0 98909.5 99830.0 60992.7 64520.7 82292.6 82447.6 83571.2 65558.0 67459.7 67772.3 68254.0 68617.3 8886.4 10419.8 10631.6 10730.4 10744.0 46418.0 47029.0 48027.0 48199.8 48223.8 60356.0 60468.7 60966.7 60974.4 61875.1 13103.4 13135.3 13428.3 13533.2 13685.2 12747.1 13645.0 13915.7 14176.5 14595.9 13085.7 13114.0 13122.7 13356.5 13917.1 13121.3 13122.0 13141.6 13141.6 13695.9 12680.7 12945.0 12955.3 12972.4 13666.4 9245.3 9664.6 10792.9 10919.2 11917.5 7917.4 7922.0 9039.0 10535.8 10914.3 10:17:47 INFO - PID 1651 | -------- Summary: end -------- 10:17:47 INFO - PID 1651 | 10:17:48 INFO - TEST-INFO | 1651: exit 0 10:17:48 INFO - TEST-OK | dromaeo_css | took 1116517ms 10:17:48 INFO - TEST-START | kraken 10:17:48 INFO - operating with platform_type : mac_ 10:17:48 INFO - Initialising browser for kraken test... 10:17:48 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmp8tBumj/profile http://localhost:49241/getInfo.html) 10:17:50 INFO - PID 1659 | 2017-03-18 10:17:50.877 plugin-container[1660:11762] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 10:17:50 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:17:50 INFO - PID 1659 | 2017-03-18 10:17:50.880 plugin-container[1660:11762] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 10:17:50 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:17:50 INFO - PID 1659 | 2017-03-18 10:17:50.880 plugin-container[1660:11762] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:17:50 INFO - PID 1659 | __metrics Screen width/height:1600/1200 10:17:50 INFO - PID 1659 | colorDepth:24 10:17:50 INFO - PID 1659 | Browser inner width/height: 1024/689 10:17:50 INFO - PID 1659 | __metrics 10:17:51 ERROR - PID 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 10:17:51 INFO - TEST-INFO | 1659: exit 0 10:17:51 INFO - Browser initialized. 10:17:51 INFO - Running cycle 1/1 for kraken test... 10:17:51 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmp8tBumj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:54 INFO - PID 1661 | 2017-03-18 10:17:54.444 plugin-container[1662:11887] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 10:17:54 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:17:54 INFO - PID 1661 | 2017-03-18 10:17:54.445 plugin-container[1662:11887] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 10:17:54 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:17:54 INFO - PID 1661 | 2017-03-18 10:17:54.445 plugin-container[1662:11887] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:18:04 INFO - PID 1661 | RSS: Main: 227479552 10:18:04 INFO - PID 1661 | 10:18:06 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 10:18:07 INFO - PID 1661 | RSS: Main: 241577984 10:18:07 INFO - PID 1661 | 10:18:10 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 10:18:11 INFO - PID 1661 | RSS: Main: 250081280 10:18:11 INFO - PID 1661 | 10:18:14 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 10:18:15 INFO - PID 1661 | RSS: Main: 252727296 10:18:15 INFO - PID 1661 | 10:18:17 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 10:18:17 INFO - PID 1661 | RSS: Main: 254996480 10:18:17 INFO - PID 1661 | 10:18:20 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:18:20 INFO - PID 1661 | RSS: Main: 255365120 10:18:20 INFO - PID 1661 | 10:18:23 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 10:18:24 INFO - PID 1661 | RSS: Main: 261574656 10:18:24 INFO - PID 1661 | 10:18:27 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 10:18:28 INFO - PID 1661 | RSS: Main: 261906432 10:18:28 INFO - PID 1661 | 10:18:31 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 10:18:31 INFO - PID 1661 | RSS: Main: 261730304 10:18:31 INFO - PID 1661 | 10:18:33 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:34 INFO - PID 1661 | RSS: Main: 261636096 10:18:34 INFO - PID 1661 | 10:18:36 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:18:36 INFO - PID 1661 | RSS: Main: 261632000 10:18:36 INFO - PID 1661 | 10:18:39 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:18:39 INFO - PID 1661 | RSS: Main: 261664768 10:18:39 INFO - PID 1661 | 10:18:42 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:18:43 INFO - PID 1661 | RSS: Main: 262275072 10:18:43 INFO - PID 1661 | 10:18:46 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:18:47 INFO - PID 1661 | RSS: Main: 262045696 10:18:47 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 10:18:49 INFO - PID 1661 | RSS: Main: 263684096 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | __start_tp_report 10:18:49 INFO - PID 1661 | _x_x_mozilla_page_load 10:18:49 INFO - PID 1661 | _x_x_mozilla_page_load_details 10:18:49 INFO - PID 1661 | |i|pagename|runs| 10:18:49 INFO - PID 1661 | |0;ai-astar;108;124;120;138;175;120;120;120;127;120 10:18:49 INFO - PID 1661 | |1;audio-beat-detection;147;117;149;155;117;117;130;146;172;116 10:18:49 INFO - PID 1661 | |2;audio-dft;194;209;218;214;213;201;202;197;197;196 10:18:49 INFO - PID 1661 | |3;audio-fft;79;64;87;112;89;50;68;86;101;62 10:18:49 INFO - PID 1661 | |4;audio-oscillator;81;70;90;104;105;84;70;108;105;116 10:18:49 INFO - PID 1661 | |5;imaging-gaussian-blur;93;89;89;97;131;138;98;136;96;85 10:18:49 INFO - PID 1661 | |6;imaging-darkroom;96;97;97;96;96;107;156;121;129;100 10:18:49 INFO - PID 1661 | |7;imaging-desaturate;95;122;92;90;92;90;98;90;90;93 10:18:49 INFO - PID 1661 | |8;json-parse-financial;66;67;65;69;70;70;63;57;91;99 10:18:49 INFO - PID 1661 | |9;json-stringify-tinderbox;58;64;60;71;59;65;57;50;77;83 10:18:49 INFO - PID 1661 | |10;stanford-crypto-aes;88;88;83;101;107;87;89;85;108;84 10:18:49 INFO - PID 1661 | |11;stanford-crypto-ccm;140;140;133;166;161;162;147;146;189;150 10:18:49 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;254;221;189;188;189;190;189;187;182;189 10:18:49 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;71;71;71;68;70;73;71;69;67;70 10:18:49 INFO - PID 1661 | __end_tp_report 10:18:49 INFO - PID 1661 | __start_cc_report 10:18:49 INFO - PID 1661 | _x_x_mozilla_cycle_collect,1002 10:18:49 INFO - PID 1661 | __end_cc_report 10:18:49 INFO - PID 1661 | __startTimestamp1489857529252__endTimestamp 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | ------- Summary: start ------- 10:18:49 INFO - PID 1661 | Number of tests: 14 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:127.20 Median:122.00 stddev:18.38 (15.1%) stddev-sans-first:18.13 10:18:49 INFO - PID 1661 | Values: 108.0 124.0 120.0 138.0 175.0 120.0 120.0 120.0 127.0 120.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:146.50 stddev:19.90 (13.6%) stddev-sans-first:20.74 10:18:49 INFO - PID 1661 | Values: 147.0 117.0 149.0 155.0 117.0 117.0 130.0 146.0 172.0 116.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:204.10 Median:205.50 stddev:8.67 (4.2%) stddev-sans-first:8.39 10:18:49 INFO - PID 1661 | Values: 194.0 209.0 218.0 214.0 213.0 201.0 202.0 197.0 197.0 196.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:79.80 Median:73.50 stddev:19.02 (25.9%) stddev-sans-first:20.17 10:18:49 INFO - PID 1661 | Values: 79.0 64.0 87.0 112.0 89.0 50.0 68.0 86.0 101.0 62.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:70.00 stddev:16.51 (23.6%) stddev-sans-first:16.90 10:18:49 INFO - PID 1661 | Values: 81.0 70.0 90.0 104.0 105.0 84.0 70.0 108.0 105.0 116.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:91.00 stddev:21.02 (23.1%) stddev-sans-first:21.82 10:18:49 INFO - PID 1661 | Values: 93.0 89.0 89.0 97.0 131.0 138.0 98.0 136.0 96.0 85.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:109.50 Median:96.00 stddev:20.08 (20.9%) stddev-sans-first:20.70 10:18:49 INFO - PID 1661 | Values: 96.0 97.0 97.0 96.0 96.0 107.0 156.0 121.0 129.0 100.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:92.00 stddev:9.77 (10.6%) stddev-sans-first:10.37 10:18:49 INFO - PID 1661 | Values: 95.0 122.0 92.0 90.0 92.0 90.0 98.0 90.0 90.0 93.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:71.70 Median:69.50 stddev:13.00 (18.7%) stddev-sans-first:13.63 10:18:49 INFO - PID 1661 | Values: 66.0 67.0 65.0 69.0 70.0 70.0 63.0 57.0 91.0 99.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.50 stddev:10.00 (15.5%) stddev-sans-first:10.34 10:18:49 INFO - PID 1661 | Values: 58.0 64.0 60.0 71.0 59.0 65.0 57.0 50.0 77.0 83.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:86.00 stddev:9.56 (11.1%) stddev-sans-first:10.03 10:18:49 INFO - PID 1661 | Values: 88.0 88.0 83.0 101.0 107.0 87.0 89.0 85.0 108.0 84.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:155.50 stddev:16.47 (10.6%) stddev-sans-first:16.74 10:18:49 INFO - PID 1661 | Values: 140.0 140.0 133.0 166.0 161.0 162.0 147.0 146.0 189.0 150.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.80 Median:189.00 stddev:22.43 (11.9%) stddev-sans-first:11.29 10:18:49 INFO - PID 1661 | Values: 254.0 221.0 189.0 188.0 189.0 190.0 189.0 187.0 182.0 189.0 10:18:49 INFO - PID 1661 | 10:18:49 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:71.00 stddev:1.73 (2.4%) stddev-sans-first:1.80 10:18:49 INFO - PID 1661 | Values: 71.0 71.0 71.0 68.0 70.0 73.0 71.0 69.0 67.0 70.0 10:18:49 INFO - PID 1661 | -------- Summary: end -------- 10:18:49 INFO - PID 1661 | 10:18:50 INFO - TEST-INFO | 1661: exit 0 10:18:50 INFO - TEST-OK | kraken | took 62027ms 10:18:50 INFO - SUITE-END | took 1178s 10:18:50 INFO - Completed test suite (00:19:38) 10:18:50 INFO - javascript benchmark 10:18:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7778.203740668089, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3134.9999999999995, 3415.992282276728, 3446.5519482467275, 3456.6543345665436, 3470.479428085787, 5835.474807267345, 5917.142014407908, 5988.221531200943, 6020.6086604370685, 6079.5136389088875, 5873.030949893273, 6015.127806468059, 6076.696165191734, 6085.2393450818645, 6108.797423689729, 4433.756143412127, 4916.459189489171, 5286.0, 5412.702301373423, 5443.428440013801, 5310.15037593985, 5402.756875940592, 5428.45715428456, 5455.427180146087, 5464.289642346489, 4271.380649805792, 5277.182036784305, 5390.54180394663, 5395.082835917903, 5442.346918369801, 5352.437994010651, 5357.598180136466, 5361.142217245222, 5389.865253368697, 5404.567634589223, 4438.556144385568, 5286.9471305287225, 5300.787968481256, 5300.893982120336, 5343.145096784535, 5738.426157384228, 5763.4812866842185, 5887.911681324784, 5993.640381577119, 6001.2498437695285, 5201.895962080737, 6256.562040657156, 6286.434220920093, 6290.056491526262, 6295.181626388586, 6355.078513615546, 6369.331220221857, 6380.457661098812, 6410.551261411703, 6426.646534440604, 5910.556708246899, 6035.0, 6091.543134264948, 6130.325664176937, 6144.2319710036245, 6260.7495700171485, 6298.527610429236, 6403.807885763435, 6409.198850143732, 6473.708683109177, 6128.472568454217, 6207.224096987877, 6235.220597425322, 6247.031710084944, 6252.499800015988, 6263.749450022039, 6282.528810339243, 6344.904826427607, 6363.554551181465, 6374.840628984312, 6192.938070619326, 6282.937170628326, 6286.339934306923, 6291.528135389773, 6298.779542715983, 5488.847342058132, 6026.758929642765, 6179.351168127372, 6197.411245931629, 6230.345813689497, 5097.949020509822, 5111.948880511147, 5120.539151476385, 5136.5377116059735, 5144.614153938394, 4586.472555656075, 4592.08158368321, 4598.425196850394, 4605.516420775837, 4654.743989080633, 6005.039193728982, 6041.335453100155, 6059.272887253558, 6066.0, 6129.295131059986, 6315.526335524854, 6365.363463653597, 6365.395287447685, 6375.553711240262, 6410.102585638015, 5077.746112694351, 6043.546733994968, 6126.479249263863, 6155.47678447337, 6158.876822463526, 5578.41426650204, 5640.097584386478, 5704.629199102045, 5720.3421606515785, 5739.339975902824, 5948.345681975065, 6363.936360636427, 6498.837529061811, 6549.672516374067, 6574.0, 5446.400895901527, 6311.976634839712, 6379.457746091634, 6390.0, 6408.231012278557, 5482.876010417775, 6590.90113648286, 6621.900671489835, 6622.0, 6642.4021838034805, 6156.384361563808, 6202.596831205957, 6211.099390588249, 6217.222847144107, 6245.18812554365, 5627.071533196977, 5906.468417842415, 6350.206224221973, 6351.68241587928, 6399.9680001601555, 6090.573659843769, 6109.677964500999, 6111.908321375094, 6113.962834745811, 6118.785842495491, 6194.132821404917, 6218.502519798496, 6221.751129954751, 6226.159468471879, 6226.533010024357, 4989.0, 6098.664573448503, 6106.1146133811235, 6251.124842521959, 6266.530010249158, 6108.645631960441, 6177.320494745484, 6193.4425901669065, 6222.62664240147, 6253.6560489173535, 6224.06639004153, 6232.002879539252, 6241.032639940817, 6260.593061450991, 6266.968665156645], "value": 5804.416972998864, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.7967689370083, 709.8793205155125, 798.1699033005673, 925.7778133248014, 928.3223247029664, 29493.0, 38097.42853857194, 38098.857034289016, 38128.046798830044, 38146.0, 36955.67398934102, 37152.62847371525, 37167.25665486696, 37197.698080567185, 37305.06737331568, 7648.923510764905, 7839.255270749298, 10692.625758098467, 10935.453227338641, 10961.12311015117, 11090.611828586001, 11215.775684486305, 11255.831162532568, 11259.380734059623, 11276.774464510705, 11195.216334856563, 11211.831822522669, 11254.043406310473, 11281.492332845, 11342.376169310684, 11196.0, 11219.60731374398, 11233.269837460539, 11240.325580465198, 11255.493502792395, 10853.0, 10912.945435272852, 10933.289336193122, 10943.945280273627, 10952.28810127347, 37955.43066853999, 38070.42894356587, 38119.618803811885, 38133.855984320515, 38212.80893595542, 3881.5352251320332, 3911.178652482982, 3927.4894263745814, 3929.1945151244213, 3938.074552480165, 27285.317867053283, 27321.20093845002, 27568.0, 27753.444931101465, 28070.71743918359, 3096.8451577421265, 3142.073088438916, 3154.2114471382156, 3163.130139211712, 3238.1095198820512, 296728.0, 296794.0, 299464.0, 299726.0, 300470.9999999978, 50921.0, 50929.92560595144, 51013.23480147801, 51024.74487627613, 51187.488125119016, 52450.737746311024, 52711.0, 52742.73628631832, 52916.206256906175, 52940.470595294326, 14539.78190327146, 14923.104613723212, 14943.178125203218, 14967.176805275596, 14978.625534361729, 12137.878621213675, 12142.210756300812, 12147.696307592381, 12153.331566763736, 12153.69615759613, 11361.375124368074, 13381.397837097355, 13962.162270263614, 14002.369893354824, 14194.0, 6023.789167379121, 6050.30421501533, 6067.271927368655, 6087.269527656621, 6098.878022439526, 1044.874615046184, 1090.738222826516, 1113.5267511307661, 1121.3328069798456, 1129.6893354327626, 20287.0, 20795.272165474136, 24554.50890982206, 25076.71114338172, 26128.216153515426, 1092.333364693766, 1144.753877916252, 1178.7328621731501, 1219.7072702551502, 1237.7338872142532, 11917.821880921547, 12875.549355772502, 13615.387307570985, 13638.522651707142, 13813.378397972116, 394377.0, 396758.0, 400668.0, 402785.0, 403478.0, 42839.78580107079, 54628.45371546313, 55033.0, 55366.0, 55719.72140139274, 1292.8513220979708, 1419.6876687129022, 1439.784032395149, 1450.985490145106, 1458.9854101459061, 27561.897524099615, 27852.0, 27894.0, 28013.57979630348, 28419.715802842533, 37880.05299867525, 37980.0, 38033.42949855699, 39406.60593394029, 39630.40554391629, 316459.0, 318735.65689543565, 319297.0, 320124.0, 320236.3988180044, 31696.5245521322, 32218.67781322157, 32227.194320142185, 32343.51484727778, 32436.351272973938, 35890.0, 38060.2387952245, 39672.603273968045, 40371.99070023155, 41218.38172427356, 33409.665903340654, 33790.49314260337, 33948.73794561847, 34136.14659633529, 34923.825380872935, 33423.33153336871, 33564.496532551544, 34257.82871085629, 34761.478577820846, 34988.65011349854, 33730.831345843115, 34236.0, 34479.655203448645, 34513.827430862686, 34556.48165277573, 23507.0, 27721.11052279286, 28846.855765721037, 30606.387872241987, 30849.0, 22800.31599052031, 22953.54092918099, 23161.420964476023, 23204.883975580015, 23239.0], "value": 18391.49169101471, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.4205144591786, 413.41914609972997, 417.18648635161435, 431.72640709905767, 432.03871386165787, 19193.424197274086, 20376.286829960954, 20945.685814712793, 21088.57822843542, 21145.0, 15316.617084572892, 15435.3825846966, 16056.83943160568, 21241.044153013114, 21364.679529807065, 6346.6509341986175, 6560.245571759261, 6663.63350015749, 6733.56231844931, 6735.1244338235965, 6101.816945491642, 6358.650274234915, 6562.967185164068, 6655.667216639173, 6658.700358483856, 6660.200775906874, 6675.799726008203, 6677.399034086931, 6681.198256209237, 6696.631685257309, 6379.553431259814, 6580.50646201532, 6627.701753421107, 6638.203415590112, 6649.268580456146, 5304.9390121975375, 6453.935460645381, 6466.0, 6486.059521369375, 6487.57830741005, 22587.20944766925, 23209.3037208884, 25170.24489265325, 25546.36134096644, 26105.477890442275, 3302.9669703302898, 3322.18606441421, 3356.311956049004, 3366.9831650841834, 3370.1574606348413, 15781.009649000149, 16022.919885400615, 16226.432074877323, 16269.267882945296, 16542.172891355385, 2023.9797602024087, 2091.3203208956998, 2099.4121645939163, 2100.222917520527, 2106.199644135234, 119397.0, 147241.7913731295, 150566.24716876345, 154330.22834885865, 154756.0, 22345.994430250354, 22876.055535279356, 22904.85475726207, 23094.0, 23183.0, 35718.46422303667, 35746.0, 35799.926002219974, 35804.64195358065, 35912.92261232167, 10160.898391016142, 10415.541824144626, 10562.465008397929, 10578.312409693346, 10765.0, 8352.331813454908, 8405.789855253668, 8441.83116337682, 8555.443896146855, 8576.356773242032, 10362.430066346273, 10447.791044179074, 10524.473776311306, 10586.470676466146, 10640.414777187329, 3763.5107436033686, 4241.130568233505, 4324.632406245441, 4382.211201983673, 4424.756638384855, 813.4834380168636, 843.6245870587652, 913.4656226107669, 920.447731361178, 941.8963913969458, 22856.428589285402, 23354.7664523356, 23366.766332336458, 23372.181973630843, 23441.882790585936, 740.8851627997669, 814.1573471457014, 884.3323290915446, 929.3958926697701, 944.0842382888587, 11923.70190745221, 11979.34113623759, 12086.456109475095, 12172.330521821385, 12229.14395992272, 539514.0, 673865.0, 677702.0, 680600.0, 690994.0, 106410.46794765977, 129977.0, 132000.0, 135448.0, 137905.310473447, 1261.905357098203, 1296.241698606307, 1331.6604265912324, 1332.586898061604, 1336.3385124363308, 17493.650126997134, 20294.79705202929, 20338.491537711085, 20720.274790382264, 20759.896200518902, 56058.0, 56425.0, 57241.427585723606, 57664.4233557659, 57858.710706446196, 75203.24796752182, 77952.61023695073, 78494.18822647168, 80150.5992470034, 80539.0, 10971.561137554409, 11680.883191167979, 11836.289822610672, 12013.279203247834, 12018.158728888893, 11885.22746021506, 12593.866552010362, 13041.869581304445, 13093.0, 13571.321433928066, 11219.270747401719, 11496.770064598828, 11498.0, 11771.823422648496, 11799.52801887915, 10300.484975751333, 10530.104941079788, 11218.270812397168, 11311.604093856675, 11507.597234096766, 11006.0, 11424.543018279175, 11560.6531804046, 11658.242214256046, 11683.299002059564, 11264.324140551593, 11782.644995825589, 11782.941085294518, 11847.052235820942, 11848.52605895789, 10672.519736611715, 11322.773544529227, 11648.242864213798, 11669.474873630537, 11761.411929403666], "value": 11482.247504899064, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.5525342397282, 1545.5517899809056, 1552.0842702805342, 1565.248680633296, 1590.2048975512243, 10366.585336586537, 10573.894261057387, 10792.028717415431, 10847.83728244076, 10943.945280273607, 10604.416757078357, 10747.78504429911, 10777.622783202589, 10781.299215550984, 10819.35083894967, 2325.825563082767, 2359.8230132740027, 2363.858168509886, 2369.7985671217966, 2370.4429459077105, 2313.525727225923, 2351.318117745852, 2351.4826738117586, 2359.421941624296, 2363.409147713072, 2332.7317358503733, 2340.134150364359, 2343.753905839888, 2348.154664320843, 2354.9764502355015, 2291.5416916616578, 2298.10373954158, 2310.1452462588863, 2312.6877871487304, 2319.559283736093, 2256.4133325335365, 2321.048370168223, 2322.1408079010707, 2323.1984965187153, 2329.3710698111586, 10915.235933484659, 10984.912866555329, 10988.28576142556, 11010.724731881686, 11018.50416731249, 5385.7307134643115, 5618.185363122365, 5641.576881733846, 5650.124230744241, 5663.292088488939, 3320.750943679234, 3340.4822252550894, 3528.3472557576674, 3553.200529880782, 3578.928421431583, 995.318207028188, 1107.1087774341652, 1124.7694222684331, 1198.4666823263651, 1237.8824011718962, 14884.479043233436, 14906.478273260493, 14910.254487275593, 15042.473513427087, 15115.622109447242, 4370.322599997005, 4596.850787303174, 4600.677952543357, 4707.976460117746, 4709.693869898446, 9546.761330966781, 11182.776344473228, 11241.213115081866, 11249.887501124884, 11268.943655281671, 5603.607747457639, 5636.774529018875, 5639.492445679826, 5643.435656434323, 5649.548036157097, 4959.032988567267, 5026.84919452417, 5101.311322971425, 5102.183650615883, 5180.274761533388, 3260.6087269527807, 3726.4969229153717, 3792.488014118059, 3851.4607954886337, 3889.5915928827067, 1042.0621440703428, 1047.1831971062584, 1047.2773786087575, 1055.9313644613076, 1056.7780766039218, 815.4943934760367, 852.0967774159411, 922.1470140120362, 933.0948979489884, 938.9671361502451, 5946.989011867993, 5965.284165900033, 6429.6142231466265, 6621.735130594722, 6798.082258895085, 834.3075247544524, 875.6847534887436, 967.57426732239, 989.737719504332, 995.5271246158017, 3985.282649123186, 4055.3511438169753, 4107.0, 4136.793160341971, 4165.271077561415, 10975.231733778559, 12726.300053496961, 13161.276129812952, 13163.0, 13198.406071726795, 11685.883141168479, 12064.396780160956, 12555.93722031384, 13043.543475978295, 13061.542846000344, 9617.326787124974, 9758.951205243786, 9831.360961537477, 9907.306488545877, 9914.008599140028, 6277.435030847246, 6604.636744979072, 6660.200775906728, 6792.592444453348, 6805.557638753465, 11398.373089479826, 11544.365059921785, 11702.414879256174, 11997.520099195934, 12025.518979240733, 10878.510467028844, 10944.835827462754, 11129.721756956142, 11249.606263780728, 11955.342456164994, 7926.286634202949, 8757.343199259954, 8995.640174392951, 9002.279817614706, 9018.86471702938, 9885.901140988499, 9998.20014398861, 10101.14140298083, 10115.14021308197, 10127.594896204364, 8704.825903481767, 8782.560871956504, 8897.0, 9019.954900225457, 9049.0, 6902.413294869993, 8128.715494957648, 8193.262606365455, 8246.958765206136, 8335.791605209919, 7640.617969101634, 7654.349380302737, 7708.691652333844, 7737.6518056686455, 7788.766337009899, 5578.9163162553405, 5632.88734225305, 5721.971390143022, 5722.62802917809, 5791.449812267828, 5853.736581853742, 5941.643501389931, 6014.939850601613, 6121.708712006372, 6141.710240849472], "value": 4972.143927690983, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.4493097336295, 389.2837179589555, 391.06340314945703, 394.9605039496049, 399.6223568727553, 6004.33952265251, 6062.302835173957, 6083.8174854754425, 6090.6345619262875, 6120.877582448348, 5660.858478538039, 5816.243888294526, 5829.504492118175, 5902.439268269508, 5956.612820166697, 4576.771161441931, 4584.564466375689, 4598.770061496937, 4599.425071866017, 4613.777349002517, 4039.515258169024, 4344.218040752663, 4606.332081848147, 4627.305904114393, 4627.444706635191, 4380.233459144637, 4565.65757568182, 4570.680052396334, 4587.885302867422, 4599.816007359702, 4263.829446822124, 4301.65586753059, 4543.091381723669, 4546.659000574954, 4567.246404343279, 3350.614679311886, 4036.2936486114813, 4053.716239863211, 4054.2296963576828, 4080.9183816323507, 5545.231101252919, 5813.447722466359, 5831.504322132624, 5870.184380152892, 5900.734466948998, 5750.568707346965, 5757.539396848242, 5769.163471296639, 5771.307443106812, 5797.275340582427, 5317.867053323698, 5599.7480113394995, 6257.843553911189, 6317.273513545955, 6338.968305158491, 4989.90020199594, 5010.2735103409805, 5014.448410674823, 5028.421731500887, 5069.645124841262, 6486.383793539606, 6715.764948226789, 6735.989601559756, 6818.704446155215, 6824.522283440161, 4799.736014519165, 5085.567726743194, 5183.170692689227, 5233.0, 5234.769829090161, 4928.383952005999, 5328.626996110235, 5346.946530534645, 5350.732463376816, 5361.544268737123, 5132.640715149903, 5420.13277875538, 5508.053191542585, 5693.800716974886, 5726.713664316851, 6443.7744678936015, 6447.806565803034, 6452.12896258999, 6453.257875344396, 6507.251666058369, 1802.765640466731, 1818.7544681467832, 1823.1704574418607, 1828.44232509086, 1842.751228584124, 1317.6837558985967, 1317.743040107189, 1352.3035636647135, 1357.3009899901558, 1366.7471517769245, 5029.84910452687, 5163.896722065538, 5187.922181167285, 6121.867454520928, 6125.479334256638, 5597.80407685729, 6670.299073736166, 6732.326767323327, 6732.865342693217, 6771.322867713287, 4767.73777442244, 5303.496167864047, 5752.590615299278, 5929.851753706192, 6116.29662588808, 3488.3197776433353, 3502.842372093262, 3503.667151620592, 3504.649535046526, 3519.6480351964597, 5872.647641141545, 6452.709628066748, 6611.710716410254, 6773.001964420508, 7011.0, 5893.02765043763, 6310.148130002391, 6797.864042719118, 6806.387425131663, 6809.897851532231, 5672.432756724294, 5821.748324110336, 6899.310068993061, 6929.930700693029, 6932.688029038604, 5596.972015139898, 5619.8595035124445, 6765.695543700447, 6804.591724496546, 6829.4877884157895, 6328.335524770017, 6546.476281897532, 6633.800985970429, 6678.332166783282, 6718.932810672026, 6710.563813352312, 6828.839097353562, 7004.513397743727, 7023.964880175567, 7074.3633073023675, 4937.654364194472, 5041.445441001413, 5165.793368265227, 5250.89498210026, 5294.841154765363, 6162.537809664203, 6243.875122497616, 6656.0, 6708.362705542965, 6747.932520674731, 5310.442403547572, 6425.293217746138, 6434.4852411806005, 6455.160829092188, 6477.8704425912165, 5132.769025393792, 5318.494742999409, 6152.938470615237, 6341.936580634135, 6441.613503189824, 6290.2451705793765, 6299.181106456132, 6306.306306306395, 6336.026219417735, 6362.77730279438, 6100.359462256577, 6361.650109244036, 6496.415322620986, 6538.574992625464, 6557.442617377576, 5383.892322153613, 6328.398802113792, 6363.236411630634, 6368.235811702625, 6433.485321174201], "value": 4878.871581125081, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.312630495809, 707.2219962903081, 767.3554214459856, 816.1675091406765, 817.9836403271935, 13286.202827830337, 15170.393184272643, 15319.463818766346, 15921.442749503769, 15995.600109997242, 14523.927380363084, 14546.927265363685, 14687.632809179775, 14688.779668304984, 15056.473023444181, 664.4485077385782, 918.4443411735904, 930.9162175404213, 940.1632547033146, 941.5104145844157, 687.2062767503542, 913.1918252346687, 958.4919992404037, 967.9467629280387, 972.3825370889465, 1010.15651930638, 1017.0033367300014, 1017.8117048346043, 1018.3635227982511, 1020.5203554329453, 990.0396615283199, 1003.413003393016, 1003.523326419952, 1010.242318261304, 1012.6506355307444, 680.9455243580502, 691.9862401581656, 745.3329270303049, 755.2862544895077, 757.1557713149854, 15830.604234894105, 16170.83829161705, 16197.83802161987, 16394.590135246595, 16647.33410663584, 13185.011124165725, 13801.964852636092, 14151.221682807442, 14499.637509062253, 14599.489017884323, 11430.419423851967, 11650.766984660258, 11706.0, 11923.284602923766, 12223.449944752507, 3634.2186429917697, 3705.5182826232685, 3729.701623870084, 3884.0872394987155, 3889.4360317753767, 81416.0, 91292.08707912969, 103731.0, 103980.48009759978, 105250.42124368141, 11115.0, 11387.772244555063, 11494.13793965456, 11771.470283837249, 11891.345975971404, 23408.531829363317, 23729.88135059348, 24015.63976540353, 24021.639675404884, 24062.15782447641, 7584.317411432998, 7681.0, 7793.844123117506, 7885.369170466349, 8039.115697273295, 4216.241076606179, 4262.829486820555, 4269.573042695768, 4270.2855977354875, 4481.238189507726, 3223.3714425687235, 3296.950545741816, 3304.5373647689344, 3308.5037244413047, 3312.2878581104696, 607.9148919151322, 611.62996387186, 619.8295468746131, 624.7001439309157, 638.5306799502362, 587.806024012075, 611.9046906038142, 634.2547506679633, 661.6989269882192, 709.6168069242655, 9947.751306217402, 9948.253880958957, 9988.450635214987, 10100.51556692382, 10180.0, 646.6848681719433, 680.9615336611707, 703.6587255181233, 707.8796604577234, 721.3940290156294, 4586.0, 4917.7970094477505, 4921.729304888194, 4952.430470495867, 4984.77568509418, 95751.56372654416, 97523.0, 98357.0, 98909.50545247228, 99830.0, 60992.695036524536, 64520.677396612715, 82292.58853705693, 82447.58776206081, 83571.16428835756, 65558.0, 67459.66270168617, 67772.3222767766, 68254.0, 68617.3138268621, 8886.422382545114, 10419.843702344622, 10631.627893023708, 10730.356178629307, 10744.0, 46418.0, 47028.99999999863, 48027.0, 48199.75900120477, 48223.75888120537, 60356.0, 60468.69765651144, 60966.69516652388, 60974.39025609687, 61875.071873922825, 13103.41034653462, 13135.343232838512, 13428.261445620585, 13533.188008719508, 13685.247311397969, 12747.10770246111, 13645.0, 13915.652108697364, 14176.486462175064, 14595.85404145945, 13085.672858178621, 13114.0, 13122.671933201364, 13356.532521361705, 13917.095388799695, 13121.278329691959, 13122.0, 13141.605751827461, 13141.605751827461, 13695.863041369857, 12680.74638507243, 12945.029122815638, 12955.287459189834, 12972.401480166414, 13666.385012674653, 9245.306602004743, 9664.613415463302, 10792.946035270088, 10919.181061420266, 11917.463714132711, 7917.366610671248, 7921.960390198012, 9039.0, 10535.7883843359, 10914.290571112851], "value": 7446.88122382928, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 124.0, 120.0, 138.0, 175.0, 120.0, 120.0, 120.0, 127.0, 120.0], "value": 127.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 149.0, 155.0, 117.0, 117.0, 130.0, 146.0, 172.0, 116.0], "value": 136.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 209.0, 218.0, 214.0, 213.0, 201.0, 202.0, 197.0, 197.0, 196.0], "value": 204.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 64.0, 87.0, 112.0, 89.0, 50.0, 68.0, 86.0, 101.0, 62.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 90.0, 104.0, 105.0, 84.0, 70.0, 108.0, 105.0, 116.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 89.0, 97.0, 131.0, 138.0, 98.0, 136.0, 96.0, 85.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 96.0, 107.0, 156.0, 121.0, 129.0, 100.0], "value": 109.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 122.0, 92.0, 90.0, 92.0, 90.0, 98.0, 90.0, 90.0, 93.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 69.0, 70.0, 70.0, 63.0, 57.0, 91.0, 99.0], "value": 71.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 60.0, 71.0, 59.0, 65.0, 57.0, 50.0, 77.0, 83.0], "value": 64.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 83.0, 101.0, 107.0, 87.0, 89.0, 85.0, 108.0, 84.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 133.0, 166.0, 161.0, 162.0, 147.0, 146.0, 189.0, 150.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [254.0, 221.0, 189.0, 188.0, 189.0, 190.0, 189.0, 187.0, 182.0, 189.0], "value": 197.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 71.0, 68.0, 70.0, 73.0, 71.0, 69.0, 67.0, 70.0], "value": 70.1, "unit": "score"}]}]} 10:18:50 INFO - Return code: 0 10:18:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:18:50 ERROR - # TBPL SUCCESS # 10:18:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:18:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:18:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:18:50 INFO - 2017-03-18 10:18:50.584 screenresolution[1666:12271] starting screenresolution argv=screenresolution get 10:18:50 INFO - 2017-03-18 10:18:50.587 screenresolution[1666:12271] Display 0: 1600x1200x32@60 10:18:50 INFO - 2017-03-18 10:18:50.594 screenresolution[1667:12274] starting screenresolution argv=screenresolution list 10:18:50 INFO - Available Modes on Display 0 10:18:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:18:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:18:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:18:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:18:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:18:50 INFO - Intel Iris: 10:18:50 INFO - Chipset Model: Intel Iris 10:18:50 INFO - Type: GPU 10:18:50 INFO - Bus: Built-In 10:18:50 INFO - VRAM (Dynamic, Max): 1536 MB 10:18:50 INFO - Vendor: Intel (0x8086) 10:18:50 INFO - Device ID: 0x0a2e 10:18:50 INFO - Revision ID: 0x0009 10:18:50 INFO - Displays: 10:18:50 INFO - Display: 10:18:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:18:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:18:50 INFO - Display Serial Number: 1600x1200 60 10:18:50 INFO - Main Display: Yes 10:18:50 INFO - Mirror: Off 10:18:50 INFO - Online: Yes 10:18:50 INFO - Rotation: Supported 10:18:50 INFO - Return code: 0 10:18:50 INFO - Running post-action listener: _resource_record_post_action 10:18:50 INFO - [mozharness: 2017-03-18 17:18:50.837676Z] Finished run-tests step (success) 10:18:50 INFO - Running post-run listener: _resource_record_post_run 10:18:50 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 34677248; Write bytes: 515548672; Read time: 322; Write time: 1697 10:18:50 INFO - TinderboxPrint: CPU usage
54.3% 10:18:50 INFO - TinderboxPrint: I/O read bytes / time
34,677,248 / 322 10:18:50 INFO - TinderboxPrint: I/O write bytes / time
515,548,672 / 1,697 10:18:50 INFO - TinderboxPrint: CPU idle
2,194.5 (45.7%) 10:18:50 INFO - TinderboxPrint: CPU system
962.0 (20.1%) 10:18:50 INFO - TinderboxPrint: CPU user
1,641.4 (34.2%) 10:18:50 INFO - TinderboxPrint: Swap in / out
241,197,056 / 0 10:18:50 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203794944; Write bytes: 203875840; Read time: 15530; Write time: 454 10:18:50 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32723456; Write bytes: 300863488; Read time: 298; Write time: 1211 10:18:51 INFO - Running post-run listener: _upload_blobber_files 10:18:51 INFO - Blob upload gear active. 10:18:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:18:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:18:51 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:18:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:18:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:52 INFO - (blobuploader) - INFO - Done attempting. 10:18:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:18:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:18:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:54 INFO - (blobuploader) - INFO - Done attempting. 10:18:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:18:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:18:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:55 INFO - (blobuploader) - INFO - Done attempting. 10:18:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:57 INFO - (blobuploader) - INFO - Done attempting. 10:18:57 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:57 INFO - Return code: 0 10:18:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/971941e5a24244a97e532c7f34cd4473fc3e22615f1ae6e946d99359238ba78206df20fbed186f3eab320ba71e5670520bab9c95937681b91f8b626876b34eba", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/181b4eee2ac341b9f51979258a174810abbf3e18994c7df00bd98915d2eaf0089e0f71bfea090b8e597f3a83ccdee2bb53aaeea79aa22ccd12cf2eee67a1e040", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca02ced14b13de3cb3474f7bf72040f32e2e245cac72d781c0aafec0b2cd1889dc42f8fa721d26849c7711c5244b8e21501cf1121411e59874a9e557683d88b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45f9505b0b3f6bcb461fa17bfec336b007f94e5042999930fa3361c547daa9da505af86fb5c5fabe48e4beee4b0d3713eab84f87238c8c91e6221b9e85f7e4de"} 10:18:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:57 INFO - Contents: 10:18:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/971941e5a24244a97e532c7f34cd4473fc3e22615f1ae6e946d99359238ba78206df20fbed186f3eab320ba71e5670520bab9c95937681b91f8b626876b34eba", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/181b4eee2ac341b9f51979258a174810abbf3e18994c7df00bd98915d2eaf0089e0f71bfea090b8e597f3a83ccdee2bb53aaeea79aa22ccd12cf2eee67a1e040", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca02ced14b13de3cb3474f7bf72040f32e2e245cac72d781c0aafec0b2cd1889dc42f8fa721d26849c7711c5244b8e21501cf1121411e59874a9e557683d88b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45f9505b0b3f6bcb461fa17bfec336b007f94e5042999930fa3361c547daa9da505af86fb5c5fabe48e4beee4b0d3713eab84f87238c8c91e6221b9e85f7e4de"} 10:18:57 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:57 INFO - Copying logs to upload dir... 10:18:57 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.081218 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-18 10:18:57.718545) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-18 10:18:57.722558) ========= 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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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/971941e5a24244a97e532c7f34cd4473fc3e22615f1ae6e946d99359238ba78206df20fbed186f3eab320ba71e5670520bab9c95937681b91f8b626876b34eba", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/181b4eee2ac341b9f51979258a174810abbf3e18994c7df00bd98915d2eaf0089e0f71bfea090b8e597f3a83ccdee2bb53aaeea79aa22ccd12cf2eee67a1e040", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca02ced14b13de3cb3474f7bf72040f32e2e245cac72d781c0aafec0b2cd1889dc42f8fa721d26849c7711c5244b8e21501cf1121411e59874a9e557683d88b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45f9505b0b3f6bcb461fa17bfec336b007f94e5042999930fa3361c547daa9da505af86fb5c5fabe48e4beee4b0d3713eab84f87238c8c91e6221b9e85f7e4de"} build_url:https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/XdSNU6FHQdeZ9-Rjq3KZKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/971941e5a24244a97e532c7f34cd4473fc3e22615f1ae6e946d99359238ba78206df20fbed186f3eab320ba71e5670520bab9c95937681b91f8b626876b34eba", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/181b4eee2ac341b9f51979258a174810abbf3e18994c7df00bd98915d2eaf0089e0f71bfea090b8e597f3a83ccdee2bb53aaeea79aa22ccd12cf2eee67a1e040", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca02ced14b13de3cb3474f7bf72040f32e2e245cac72d781c0aafec0b2cd1889dc42f8fa721d26849c7711c5244b8e21501cf1121411e59874a9e557683d88b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45f9505b0b3f6bcb461fa17bfec336b007f94e5042999930fa3361c547daa9da505af86fb5c5fabe48e4beee4b0d3713eab84f87238c8c91e6221b9e85f7e4de"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-18 10:18:57.757809) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 10:18:57.758179) ========= 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.sGYPLhffGB/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.J3MJ3bdTLo/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 10:18:57.807084) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-18 10:18:57.807402) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-18 10:18:57.807720) ========= ========= Total master_lag: 0.26 =========