builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0162 starttime: 1490612343.77 results: success (0) buildid: 20170327023216 builduid: 0a269cfe31f0476581f656061c745e3a revision: df0808040376f8e12f1d110cf8855c7666476291 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:03.771143) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:03.771566) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:03.771837) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:03.798102) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:03.798429) ========= 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.DUYQExrfG7/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.RarJRZkwwD/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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-27 03:59:03-- 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% 39.6M=0s 2017-03-27 03:59:04 (39.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.281649 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:04.104440) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:04.104807) ========= 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.DUYQExrfG7/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.RarJRZkwwD/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.030374 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:04.158001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 03:59:04.158414) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev df0808040376f8e12f1d110cf8855c7666476291 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev df0808040376f8e12f1d110cf8855c7666476291 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DUYQExrfG7/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.RarJRZkwwD/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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-27 03:59:04,281 truncating revision to first 12 chars 2017-03-27 03:59:04,281 Setting DEBUG logging. 2017-03-27 03:59:04,282 attempt 1/10 2017-03-27 03:59:04,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/df0808040376?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 03:59:05,704 unpacking tar archive at: mozilla-beta-df0808040376/testing/mozharness/ program finished with exit code 0 elapsedTime=1.695236 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 03:59:05.877028) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:05.877345) ========= script_repo_revision: df0808040376f8e12f1d110cf8855c7666476291 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:05.877738) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:05.878106) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 03:59:05.897756) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-27 03:59:05.898114) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DUYQExrfG7/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.RarJRZkwwD/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:06 INFO - MultiFileLogger online at 20170327 03:59:06 in /builds/slave/test 03:59:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 03:59:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:59:06 INFO - {'append_to_log': False, 03:59:06 INFO - 'base_work_dir': '/builds/slave/test', 03:59:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:59:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:59:06 INFO - 'branch': 'Mozilla-Beta', 03:59:06 INFO - 'buildbot_json_path': 'buildprops.json', 03:59:06 INFO - 'config_files': ('talos/mac_config.py',), 03:59:06 INFO - 'default_actions': ('clobber', 03:59:06 INFO - 'read-buildbot-config', 03:59:06 INFO - 'download-and-extract', 03:59:06 INFO - 'populate-webroot', 03:59:06 INFO - 'create-virtualenv', 03:59:06 INFO - 'install', 03:59:06 INFO - 'run-tests'), 03:59:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:59:06 INFO - 'download_minidump_stackwalk': True, 03:59:06 INFO - 'download_symbols': 'ondemand', 03:59:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:59:06 INFO - 'tooltool.py': '/tools/tooltool.py', 03:59:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:59:06 INFO - '/tools/misc-python/virtualenv.py')}, 03:59:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:59:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:59:06 INFO - 'installer_path': 'installer.exe', 03:59:06 INFO - 'log_level': 'info', 03:59:06 INFO - 'log_name': 'talos', 03:59:06 INFO - 'log_to_console': True, 03:59:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:59:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:59:06 INFO - 'opt_config_files': (), 03:59:06 INFO - 'pip_index': False, 03:59:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:59:06 INFO - 'cmd': ('bash', 03:59:06 INFO - '-c', 03:59:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:59:06 INFO - 'enabled': True, 03:59:06 INFO - 'halt_on_failure': False, 03:59:06 INFO - 'name': 'check_screen_resolution'},), 03:59:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:59:06 INFO - 'cmd': ('bash', 03:59:06 INFO - '-c', 03:59:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:59:06 INFO - 'enabled': True, 03:59:06 INFO - 'halt_on_failure': False, 03:59:06 INFO - 'name': 'check_screen_resolution'},), 03:59:06 INFO - 'run_cmd_checks_enabled': True, 03:59:06 INFO - 'sps_profile': False, 03:59:06 INFO - 'sps_profile_interval': 0, 03:59:06 INFO - 'suite': 'dromaeojs-e10s', 03:59:06 INFO - 'system_bits': '32', 03:59:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:59:06 INFO - 'title': 't-yosemite-r7-0162', 03:59:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:59:06 INFO - 'use_talos_json': True, 03:59:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:59:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:59:06 INFO - 'work_dir': 'build'} 03:59:06 INFO - [mozharness: 2017-03-27 10:59:06.133635Z] Running clobber step. 03:59:06 INFO - Running pre-action listener: _resource_record_pre_action 03:59:06 INFO - Running main action method: clobber 03:59:06 INFO - rmtree: /builds/slave/test/build 03:59:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:59:08 INFO - Running post-action listener: _resource_record_post_action 03:59:08 INFO - [mozharness: 2017-03-27 10:59:08.750710Z] Finished clobber step (success) 03:59:08 INFO - [mozharness: 2017-03-27 10:59:08.750833Z] Running read-buildbot-config step. 03:59:08 INFO - Running pre-action listener: _resource_record_pre_action 03:59:08 INFO - Running main action method: read_buildbot_config 03:59:08 INFO - Using buildbot properties: 03:59:08 INFO - { 03:59:08 INFO - "project": "", 03:59:08 INFO - "product": "firefox", 03:59:08 INFO - "script_repo_revision": "production", 03:59:08 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 03:59:08 INFO - "repository": "", 03:59:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 03:59:08 INFO - "buildid": "20170327023216", 03:59:08 INFO - "pgo_build": "False", 03:59:08 INFO - "basedir": "/builds/slave/test", 03:59:08 INFO - "buildnumber": 16, 03:59:08 INFO - "slavename": "t-yosemite-r7-0162", 03:59:08 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:59:08 INFO - "platform": "yosemite_r7", 03:59:08 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 03:59:08 INFO - "branch": "mozilla-beta", 03:59:08 INFO - "repo_path": "releases/mozilla-beta", 03:59:08 INFO - "revision": "df0808040376f8e12f1d110cf8855c7666476291", 03:59:08 INFO - "stage_platform": "macosx64", 03:59:08 INFO - "builduid": "0a269cfe31f0476581f656061c745e3a", 03:59:08 INFO - "slavebuilddir": "test" 03:59:08 INFO - } 03:59:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 03:59:08 INFO - Running post-action listener: _resource_record_post_action 03:59:08 INFO - [mozharness: 2017-03-27 10:59:08.754202Z] Finished read-buildbot-config step (success) 03:59:08 INFO - [mozharness: 2017-03-27 10:59:08.754306Z] Running download-and-extract step. 03:59:08 INFO - Running pre-action listener: _resource_record_pre_action 03:59:08 INFO - Running main action method: download_and_extract 03:59:08 INFO - mkdir: /builds/slave/test/build/tests 03:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:08 INFO - trying https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 03:59:08 INFO - Downloading https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 03:59:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 03:59:10 INFO - Downloaded 1165 bytes. 03:59:10 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 03:59:10 INFO - Using the following test package requirements: 03:59:10 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 03:59:10 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:59:10 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 03:59:10 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:59:10 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 03:59:10 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 03:59:10 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:59:10 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 03:59:10 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 03:59:10 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:59:10 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 03:59:10 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:59:10 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 03:59:10 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:59:10 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 03:59:10 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:59:10 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 03:59:10 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 03:59:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 03:59:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 03:59:10 INFO - Fetch https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 03:59:10 INFO - Expected file size: 16108482 03:59:10 INFO - Obtained file size: 16108482 03:59:17 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 03:59:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 03:59:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 03:59:17 INFO - Fetch https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 03:59:18 INFO - Expected file size: 16108482 03:59:18 INFO - Obtained file size: 16108482 03:59:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 03:59:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 03:59:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 03:59:26 INFO - Expected file size: 14403663 03:59:26 INFO - Obtained file size: 14403663 03:59:26 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')]} 03:59:26 INFO - trying https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 03:59:26 INFO - Downloading https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 03:59:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 03:59:28 INFO - Downloaded 58674749 bytes. 03:59:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 03:59:28 INFO - mkdir: /builds/slave/test/properties 03:59:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:59:28 INFO - Writing to file /builds/slave/test/properties/build_url 03:59:28 INFO - Contents: 03:59:28 INFO - build_url:https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 03:59:28 INFO - Running post-action listener: _resource_record_post_action 03:59:28 INFO - Running post-action listener: set_extra_try_arguments 03:59:28 INFO - [mozharness: 2017-03-27 10:59:28.940940Z] Finished download-and-extract step (success) 03:59:28 INFO - [mozharness: 2017-03-27 10:59:28.941112Z] Running populate-webroot step. 03:59:28 INFO - Running pre-action listener: _resource_record_pre_action 03:59:28 INFO - Running main action method: populate_webroot 03:59:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:59:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:59:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:59:28 INFO - '--tppagecycles', 03:59:28 INFO - '7'], 03:59:28 INFO - 'tests': ['tsvgm']}}, 03:59:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:59:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:59:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:59:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:59:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:59:28 INFO - 'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:59:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:59:28 INFO - 'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['damp', 'tps']}, 03:59:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:59:28 INFO - 'tests': ['damp', 'tps']}, 03:59:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['dromaeo_dom']}, 03:59:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:59:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['basic_compositor_video']}, 03:59:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:59:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['a11yr', 03:59:28 INFO - 'ts_paint', 03:59:28 INFO - 'tpaint', 03:59:28 INFO - 'sessionrestore', 03:59:28 INFO - 'sessionrestore_no_auto_restore', 03:59:28 INFO - 'tabpaint']}, 03:59:28 INFO - 'other-e10s': {'tests': ['a11yr', 03:59:28 INFO - 'ts_paint', 03:59:28 INFO - 'tpaint', 03:59:28 INFO - 'sessionrestore', 03:59:28 INFO - 'sessionrestore_no_auto_restore', 03:59:28 INFO - 'tabpaint']}, 03:59:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:59:28 INFO - 'ts_paint', 03:59:28 INFO - 'tpaint', 03:59:28 INFO - 'sessionrestore', 03:59:28 INFO - 'sessionrestore_no_auto_restore', 03:59:28 INFO - 'tabpaint']}, 03:59:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:59:28 INFO - 'ts_paint', 03:59:28 INFO - 'tpaint', 03:59:28 INFO - 'sessionrestore', 03:59:28 INFO - 'sessionrestore_no_auto_restore', 03:59:28 INFO - 'tabpaint']}, 03:59:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['a11yr', 03:59:28 INFO - 'ts_paint', 03:59:28 INFO - 'tpaint', 03:59:28 INFO - 'sessionrestore', 03:59:28 INFO - 'sessionrestore_no_auto_restore', 03:59:28 INFO - 'tabpaint']}, 03:59:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['a11yr', 03:59:28 INFO - 'ts_paint', 03:59:28 INFO - 'tpaint', 03:59:28 INFO - 'sessionrestore', 03:59:28 INFO - 'sessionrestore_no_auto_restore', 03:59:28 INFO - 'tabpaint']}, 03:59:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['tsvgx', 03:59:28 INFO - 'tsvgr_opacity', 03:59:28 INFO - 'tart', 03:59:28 INFO - 'tscrollx', 03:59:28 INFO - 'cart', 03:59:28 INFO - 'tsvg_static']}, 03:59:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:59:28 INFO - 'tsvgr_opacity', 03:59:28 INFO - 'tart', 03:59:28 INFO - 'tscrollx', 03:59:28 INFO - 'cart', 03:59:28 INFO - 'tsvg_static']}, 03:59:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:59:28 INFO - 'talos_options': ['--disable-e10s'], 03:59:28 INFO - 'tests': ['tp5o']}, 03:59:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:59:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:59:28 INFO - 'talos_options': ['--disable-e10s', 03:59:28 INFO - '--xperf_path', 03:59:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:28 INFO - 'tests': ['tp5n']}, 03:59:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:59:28 INFO - 'talos_options': ['--xperf_path', 03:59:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:28 INFO - 'tests': ['tp5n']}}, 03:59:28 INFO - 'talos.zip': {'path': '', 03:59:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:59:28 INFO - Running post-action listener: _resource_record_post_action 03:59:28 INFO - [mozharness: 2017-03-27 10:59:28.960623Z] Finished populate-webroot step (success) 03:59:28 INFO - [mozharness: 2017-03-27 10:59:28.960760Z] Running create-virtualenv step. 03:59:28 INFO - Running pre-action listener: _resource_record_pre_action 03:59:28 INFO - Running main action method: create_virtualenv 03:59:28 INFO - Creating virtualenv /builds/slave/test/build/venv 03:59:28 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 03:59:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:59:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:59:29 INFO - Using real prefix '/tools/python27' 03:59:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:59:29 INFO - Installing distribute.............................................................................................................................................................................................done. 03:59:32 INFO - Installing pip.................done. 03:59:32 INFO - Return code: 0 03:59:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:59:32 INFO - Reading from file tmpfile_stdout 03:59:32 INFO - Output received: 03:59:32 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) 03:59:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:59:32 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')]} 03:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:32 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')]} 03:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:32 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:32 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 03:59:32 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 03:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 03:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:32 INFO - 'HOME': '/Users/cltbld', 03:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:32 INFO - 'LOGNAME': 'cltbld', 03:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:32 INFO - 'MOZ_NO_REMOTE': '1', 03:59:32 INFO - 'NO_EM_RESTART': '1', 03:59:32 INFO - 'PAGER': '/bin/cat', 03:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:32 INFO - 'PWD': '/builds/slave/test', 03:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:32 INFO - 'SHELL': '/bin/bash', 03:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 03:59:32 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 03:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:32 INFO - 'USER': 'cltbld', 03:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:32 INFO - 'XPC_FLAGS': '0x0', 03:59:32 INFO - 'XPC_SERVICE_NAME': '0', 03:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:33 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 03:59:33 INFO - Cleaning up... 03:59:33 INFO - Return code: 0 03:59:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:33 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:33 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 03:59:33 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 03:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 03:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:33 INFO - 'HOME': '/Users/cltbld', 03:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:33 INFO - 'LOGNAME': 'cltbld', 03:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:33 INFO - 'MOZ_NO_REMOTE': '1', 03:59:33 INFO - 'NO_EM_RESTART': '1', 03:59:33 INFO - 'PAGER': '/bin/cat', 03:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:33 INFO - 'PWD': '/builds/slave/test', 03:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:33 INFO - 'SHELL': '/bin/bash', 03:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 03:59:33 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 03:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:33 INFO - 'USER': 'cltbld', 03:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:33 INFO - 'XPC_FLAGS': '0x0', 03:59:33 INFO - 'XPC_SERVICE_NAME': '0', 03:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:33 INFO - Downloading/unpacking psutil>=3.1.1 03:59: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 03:59: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 03:59: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 03:59: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 03:59: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 03:59: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 03:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:59:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:59:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:59:36 INFO - Installing collected packages: psutil 03:59:36 INFO - Running setup.py install for psutil 03:59:37 INFO - building 'psutil._psutil_osx' extension 03:59:37 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 03:59:37 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 03:59:37 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 03:59:37 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 03:59:37 INFO - building 'psutil._psutil_posix' extension 03:59:37 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 03:59:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:59:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:59:37 INFO - ^ 03:59:37 INFO - 1 warning generated. 03:59:37 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 03:59:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:59:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:59:38 INFO - Successfully installed psutil 03:59:38 INFO - Cleaning up... 03:59:38 INFO - Return code: 0 03:59:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:38 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:38 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 03:59:38 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 03:59:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 03:59:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:38 INFO - 'HOME': '/Users/cltbld', 03:59:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:38 INFO - 'LOGNAME': 'cltbld', 03:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:38 INFO - 'MOZ_NO_REMOTE': '1', 03:59:38 INFO - 'NO_EM_RESTART': '1', 03:59:38 INFO - 'PAGER': '/bin/cat', 03:59:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:38 INFO - 'PWD': '/builds/slave/test', 03:59:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:38 INFO - 'SHELL': '/bin/bash', 03:59:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 03:59:38 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 03:59:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:38 INFO - 'USER': 'cltbld', 03:59:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:38 INFO - 'XPC_FLAGS': '0x0', 03:59:38 INFO - 'XPC_SERVICE_NAME': '0', 03:59:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:59: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 03:59: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 03:59: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 03:59: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 03:59: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 03:59: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 03:59:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:59:41 INFO - Installing collected packages: mozsystemmonitor 03:59:41 INFO - Running setup.py install for mozsystemmonitor 03:59:41 INFO - Successfully installed mozsystemmonitor 03:59:41 INFO - Cleaning up... 03:59:41 INFO - Return code: 0 03:59:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:59:41 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')]} 03:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:41 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')]} 03:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:41 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:41 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 03:59:41 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 03:59:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 03:59:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:41 INFO - 'HOME': '/Users/cltbld', 03:59:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:41 INFO - 'LOGNAME': 'cltbld', 03:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:41 INFO - 'MOZ_NO_REMOTE': '1', 03:59:41 INFO - 'NO_EM_RESTART': '1', 03:59:41 INFO - 'PAGER': '/bin/cat', 03:59:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:41 INFO - 'PWD': '/builds/slave/test', 03:59:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:41 INFO - 'SHELL': '/bin/bash', 03:59:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 03:59:41 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 03:59:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:41 INFO - 'USER': 'cltbld', 03:59:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:41 INFO - 'XPC_FLAGS': '0x0', 03:59:41 INFO - 'XPC_SERVICE_NAME': '0', 03:59:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:41 INFO - Downloading/unpacking jsonschema==2.5.1 03:59:41 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 03:59:41 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 03:59:41 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 03:59:41 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 03:59:41 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 03:59:41 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 03:59:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:59:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:59:44 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 03:59:44 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 03:59:44 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 03:59:44 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 03:59:44 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 03:59:44 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 03:59:44 INFO - Downloading functools32-3.2.3-2.tar.gz 03:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:59:44 INFO - warning: no files found matching '*.txt' 03:59:44 INFO - no previously-included directories found matching 'build' 03:59:44 INFO - no previously-included directories found matching 'dist' 03:59:44 INFO - no previously-included directories found matching '.git*' 03:59:44 INFO - Installing collected packages: jsonschema, functools32 03:59:44 INFO - Running setup.py install for functools32 03:59:45 INFO - warning: no files found matching '*.txt' 03:59:45 INFO - no previously-included directories found matching 'build' 03:59:45 INFO - no previously-included directories found matching 'dist' 03:59:45 INFO - no previously-included directories found matching '.git*' 03:59:45 INFO - Successfully installed jsonschema functools32 03:59:45 INFO - Cleaning up... 03:59:45 INFO - Return code: 0 03:59:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:45 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:45 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 03:59:45 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 03:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 03:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:45 INFO - 'HOME': '/Users/cltbld', 03:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:45 INFO - 'LOGNAME': 'cltbld', 03:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:45 INFO - 'MOZ_NO_REMOTE': '1', 03:59:45 INFO - 'NO_EM_RESTART': '1', 03:59:45 INFO - 'PAGER': '/bin/cat', 03:59:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:45 INFO - 'PWD': '/builds/slave/test', 03:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:45 INFO - 'SHELL': '/bin/bash', 03:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 03:59:45 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 03:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:45 INFO - 'USER': 'cltbld', 03:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:45 INFO - 'XPC_FLAGS': '0x0', 03:59:45 INFO - 'XPC_SERVICE_NAME': '0', 03:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:59:45 INFO - Cleaning up... 03:59:45 INFO - Return code: 0 03:59:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:45 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:45 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 03:59:45 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 03:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 03:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:45 INFO - 'HOME': '/Users/cltbld', 03:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:45 INFO - 'LOGNAME': 'cltbld', 03:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:45 INFO - 'MOZ_NO_REMOTE': '1', 03:59:45 INFO - 'NO_EM_RESTART': '1', 03:59:45 INFO - 'PAGER': '/bin/cat', 03:59:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:45 INFO - 'PWD': '/builds/slave/test', 03:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:45 INFO - 'SHELL': '/bin/bash', 03:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 03:59:45 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 03:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:45 INFO - 'USER': 'cltbld', 03:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:45 INFO - 'XPC_FLAGS': '0x0', 03:59:45 INFO - 'XPC_SERVICE_NAME': '0', 03:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:45 INFO - Downloading/unpacking blobuploader==1.2.4 03:59:45 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 03:59:45 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 03:59:45 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 03:59:45 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 03:59:45 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 03:59:45 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 03:59:48 INFO - Downloading blobuploader-1.2.4.tar.gz 03:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:59:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:59:48 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 03:59:48 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 03:59:48 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 03:59:48 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 03:59:48 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 03:59:48 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 03:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:59:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:59:49 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 03:59:49 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 03:59:49 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 03:59:49 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 03:59:49 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 03:59:49 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 03:59:49 INFO - Downloading docopt-0.6.1.tar.gz 03:59:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:59:49 INFO - Installing collected packages: blobuploader, requests, docopt 03:59:49 INFO - Running setup.py install for blobuploader 03:59:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:59:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:59:49 INFO - Running setup.py install for requests 03:59:50 INFO - Running setup.py install for docopt 03:59:50 INFO - Successfully installed blobuploader requests docopt 03:59:50 INFO - Cleaning up... 03:59:50 INFO - Return code: 0 03:59:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59: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')]} 03:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59: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')]} 03:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:50 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:50 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 03:59:50 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 03:59:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 03:59:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:50 INFO - 'HOME': '/Users/cltbld', 03:59:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:50 INFO - 'LOGNAME': 'cltbld', 03:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:50 INFO - 'MOZ_NO_REMOTE': '1', 03:59:50 INFO - 'NO_EM_RESTART': '1', 03:59:50 INFO - 'PAGER': '/bin/cat', 03:59:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:50 INFO - 'PWD': '/builds/slave/test', 03:59:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:50 INFO - 'SHELL': '/bin/bash', 03:59:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 03:59:50 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 03:59:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:50 INFO - 'USER': 'cltbld', 03:59:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:50 INFO - 'XPC_FLAGS': '0x0', 03:59:50 INFO - 'XPC_SERVICE_NAME': '0', 03:59:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:50 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-KSJp4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:50 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-tY04zx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-LQI65D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-fZ0f4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-VnvkeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-KgOK0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-G015As-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-kT7i3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-HsmxXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-4VW4em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:51 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-DrxERK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-YL6RO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-OVqaqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Zq9odF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-6ispf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-tHfam1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-0YHFYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:59:52 INFO - Running setup.py install for manifestparser 03:59:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:59:52 INFO - Running setup.py install for mozcrash 03:59:52 INFO - Running setup.py install for mozdebug 03:59:53 INFO - Running setup.py install for mozdevice 03:59:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Running setup.py install for mozfile 03:59:53 INFO - Running setup.py install for mozhttpd 03:59:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Running setup.py install for mozinfo 03:59:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Running setup.py install for mozInstall 03:59:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Running setup.py install for mozleak 03:59:53 INFO - Running setup.py install for mozlog 03:59:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for moznetwork 03:59:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for mozprocess 03:59:54 INFO - Running setup.py install for mozprofile 03:59:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for mozrunner 03:59:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for mozscreenshot 03:59:54 INFO - Running setup.py install for moztest 03:59:55 INFO - Running setup.py install for mozversion 03:59:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:59:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:59:55 INFO - Cleaning up... 03:59:55 INFO - Return code: 0 03:59:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:55 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 03:59:55 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 03:59:55 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 03:59:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 03:59:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:55 INFO - 'HOME': '/Users/cltbld', 03:59:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:55 INFO - 'LOGNAME': 'cltbld', 03:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:55 INFO - 'MOZ_NO_REMOTE': '1', 03:59:55 INFO - 'NO_EM_RESTART': '1', 03:59:55 INFO - 'PAGER': '/bin/cat', 03:59:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:55 INFO - 'PWD': '/builds/slave/test', 03:59:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:55 INFO - 'SHELL': '/bin/bash', 03:59:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 03:59:55 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 03:59:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:55 INFO - 'USER': 'cltbld', 03:59:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:55 INFO - 'XPC_FLAGS': '0x0', 03:59:55 INFO - 'XPC_SERVICE_NAME': '0', 03:59:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:55 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-6hR3ot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:55 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)) 03:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:55 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-1tCqSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:55 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)) 03:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:55 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Byeuo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:55 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)) 03:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:55 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-XM0b0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:55 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)) 03:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:55 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-bLaRc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:56 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)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-cZQLnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:56 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)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-bp9DHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:56 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)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Wvgeao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:56 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)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-SeXP_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:56 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)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-NMEQum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:56 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)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-xWt7RS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:56 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)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-B0aU9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-kDD0eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:56 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)) 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:56 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-QVW4Zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:57 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)) 03:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-46lkAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:57 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)) 03:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-OiEhpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:57 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)) 03:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-VBZ_R8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:57 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)) 03:59:57 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)) 03:59:57 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)) 03:59:57 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)) 03:59:57 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)) 03:59:57 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)) 03:59:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:59:57 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 03:59:57 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 03:59:57 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 03:59:57 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 03:59:57 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 03:59:57 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 04:00:00 INFO - Downloading blessings-1.6.tar.gz 04:00:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:00:00 INFO - Installing collected packages: blessings 04:00:00 INFO - Running setup.py install for blessings 04:00:00 INFO - Successfully installed blessings 04:00:00 INFO - Cleaning up... 04:00:00 INFO - Return code: 0 04:00:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:00:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:00 INFO - Reading from file tmpfile_stdout 04:00:00 INFO - Current package versions: 04:00:00 INFO - blessings == 1.6 04:00:00 INFO - blobuploader == 1.2.4 04:00:00 INFO - docopt == 0.6.1 04:00:00 INFO - functools32 == 3.2.3-2 04:00:00 INFO - jsonschema == 2.5.1 04:00:00 INFO - manifestparser == 1.1 04:00:00 INFO - mozInstall == 1.12 04:00:00 INFO - mozcrash == 1.0 04:00:00 INFO - mozdebug == 0.1 04:00:00 INFO - mozdevice == 0.49 04:00:00 INFO - mozfile == 1.2 04:00:00 INFO - mozhttpd == 0.7 04:00:00 INFO - mozinfo == 0.9 04:00:00 INFO - mozleak == 0.1 04:00:00 INFO - mozlog == 3.4 04:00:00 INFO - moznetwork == 0.27 04:00:00 INFO - mozprocess == 0.24 04:00:00 INFO - mozprofile == 0.28 04:00:00 INFO - mozrunner == 6.13 04:00:00 INFO - mozscreenshot == 0.1 04:00:00 INFO - mozsystemmonitor == 0.3 04:00:00 INFO - moztest == 0.8 04:00:00 INFO - mozversion == 1.4 04:00:00 INFO - psutil == 3.1.1 04:00:00 INFO - requests == 1.2.3 04:00:00 INFO - wsgiref == 0.1.2 04:00:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:01 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')]} 04:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:01 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 04:00:01 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 04:00:01 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 04:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 04:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:00:01 INFO - 'HOME': '/Users/cltbld', 04:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:00:01 INFO - 'LOGNAME': 'cltbld', 04:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:01 INFO - 'MOZ_NO_REMOTE': '1', 04:00:01 INFO - 'NO_EM_RESTART': '1', 04:00:01 INFO - 'PAGER': '/bin/cat', 04:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:01 INFO - 'PWD': '/builds/slave/test', 04:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:00:01 INFO - 'SHELL': '/bin/bash', 04:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 04:00:01 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 04:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:00:01 INFO - 'USER': 'cltbld', 04:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:01 INFO - 'XPC_FLAGS': '0x0', 04:00:01 INFO - 'XPC_SERVICE_NAME': '0', 04:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 INFO - Cleaning up... 04:00:01 INFO - Return code: 0 04:00:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:00:01 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')]} 04:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:01 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')]} 04:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:02 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 0x107f7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f7159f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, '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.DUYQExrfG7/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.RarJRZkwwD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 04:00:02 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 04:00:02 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 04:00:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 04:00:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:00:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:00:02 INFO - 'HOME': '/Users/cltbld', 04:00:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:00:02 INFO - 'LOGNAME': 'cltbld', 04:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:02 INFO - 'MOZ_NO_REMOTE': '1', 04:00:02 INFO - 'NO_EM_RESTART': '1', 04:00:02 INFO - 'PAGER': '/bin/cat', 04:00:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:02 INFO - 'PWD': '/builds/slave/test', 04:00:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:00:02 INFO - 'SHELL': '/bin/bash', 04:00:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 04:00:02 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 04:00:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:00:02 INFO - 'USER': 'cltbld', 04:00:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:00:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:02 INFO - 'XPC_FLAGS': '0x0', 04:00:02 INFO - 'XPC_SERVICE_NAME': '0', 04:00:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:00:02 INFO - Cleaning up... 04:00:02 INFO - Return code: 0 04:00:02 INFO - Running post-action listener: _resource_record_post_action 04:00:02 INFO - Running post-action listener: _start_resource_monitoring 04:00:02 INFO - Starting resource monitoring. 04:00:02 INFO - [mozharness: 2017-03-27 11:00:02.640714Z] Finished create-virtualenv step (success) 04:00:02 INFO - [mozharness: 2017-03-27 11:00:02.641704Z] Running install step. 04:00:02 INFO - Running pre-action listener: _resource_record_pre_action 04:00:02 INFO - Running main action method: install 04:00:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:02 INFO - Reading from file tmpfile_stdout 04:00:02 INFO - Detecting whether we're running mozinstall >=1.0... 04:00:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:00:03 INFO - Reading from file tmpfile_stdout 04:00:03 INFO - Output received: 04:00:03 INFO - Usage: mozinstall [options] installer 04:00:03 INFO - Options: 04:00:03 INFO - -h, --help show this help message and exit 04:00:03 INFO - -d DEST, --destination=DEST 04:00:03 INFO - Directory to install application into. [default: 04:00:03 INFO - "/builds/slave/test"] 04:00:03 INFO - --app=APP Application being installed. [default: firefox] 04:00:03 INFO - mkdir: /builds/slave/test/build/application 04:00:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:00:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:00:17 INFO - Reading from file tmpfile_stdout 04:00:17 INFO - Output received: 04:00:17 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:00:17 INFO - Running post-action listener: _resource_record_post_action 04:00:17 INFO - [mozharness: 2017-03-27 11:00:17.986789Z] Finished install step (success) 04:00:17 INFO - [mozharness: 2017-03-27 11:00:17.986921Z] Running run-tests step. 04:00:17 INFO - Running pre-action listener: _resource_record_pre_action 04:00:17 INFO - Running main action method: run_tests 04:00:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:00:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:00:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:00:18 INFO - 2017-03-27 04:00:18.001 screenresolution[1627:7322] starting screenresolution argv=screenresolution get 04:00:18 INFO - 2017-03-27 04:00:18.004 screenresolution[1627:7322] Display 0: 1600x1200x32@60 04:00:18 INFO - 2017-03-27 04:00:18.010 screenresolution[1628:7324] starting screenresolution argv=screenresolution list 04:00:18 INFO - Available Modes on Display 0 04:00:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:00:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:00:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:00:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:00:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:00:18 INFO - Intel Iris: 04:00:18 INFO - Chipset Model: Intel Iris 04:00:18 INFO - Type: GPU 04:00:18 INFO - Bus: Built-In 04:00:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:00:18 INFO - Vendor: Intel (0x8086) 04:00:18 INFO - Device ID: 0x0a2e 04:00:18 INFO - Revision ID: 0x0009 04:00:18 INFO - Displays: 04:00:18 INFO - Display: 04:00:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:00:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:00:18 INFO - Display Serial Number: 1600x1200 60 04:00:18 INFO - Main Display: Yes 04:00:18 INFO - Mirror: Off 04:00:18 INFO - Online: Yes 04:00:18 INFO - Rotation: Supported 04:00:18 INFO - Return code: 0 04:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:00:18 INFO - Python 2.7.3 04:00:18 INFO - Return code: 0 04:00:18 INFO - grabbing minidump binary from tooltool 04:00:18 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')]} 04:00:18 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 0x107d5cd30>, '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 0x107c83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d51be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:00:18 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 04:00:18 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 04:00:18 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 04:00:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:00:18 INFO - Return code: 0 04:00:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:00:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:00:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:00:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:00:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0162', '--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 04:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0162 --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 04:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DUYQExrfG7/Render', 04:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:00:18 INFO - 'HOME': '/Users/cltbld', 04:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:00:18 INFO - 'LOGNAME': 'cltbld', 04:00:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:00:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:18 INFO - 'MOZ_NO_REMOTE': '1', 04:00:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:00:18 INFO - 'NO_EM_RESTART': '1', 04:00:18 INFO - 'PAGER': '/bin/cat', 04:00:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:18 INFO - 'PWD': '/builds/slave/test', 04:00:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:00:18 INFO - 'SHELL': '/bin/bash', 04:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RarJRZkwwD/Listeners', 04:00:18 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 04:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:00:18 INFO - 'USER': 'cltbld', 04:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:18 INFO - 'XPC_FLAGS': '0x0', 04:00:18 INFO - 'XPC_SERVICE_NAME': '0', 04:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:00:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0162', '--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 04:00:18 INFO - mozversion application_buildid: 20170327023216 04:00:18 INFO - mozversion application_changeset: df0808040376f8e12f1d110cf8855c7666476291 04:00:18 INFO - mozversion application_display_name: Firefox 04:00:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:00:18 INFO - mozversion application_name: Firefox 04:00:18 INFO - mozversion application_remotingname: firefox 04:00:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:00:18 INFO - mozversion application_vendor: Mozilla 04:00:18 INFO - mozversion application_version: 53.0 04:00:18 INFO - mozversion platform_buildid: 20170327023216 04:00:18 INFO - mozversion platform_changeset: df0808040376f8e12f1d110cf8855c7666476291 04:00:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:00:18 INFO - mozversion platform_version: 53.0 04:00:18 INFO - using testdate: 1490612418 04:00:18 INFO - actual date: 1490612418 04:00:18 INFO - starting webserver on 'localhost:49244' 04:00:18 INFO - SUITE-START | Running 2 tests 04:00:18 INFO - TEST-START | dromaeo_css 04:00:18 INFO - operating with platform_type : mac_ 04:00:18 INFO - Initialising browser for dromaeo_css test... 04:00:18 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpf8Yt4l/profile http://localhost:49244/getInfo.html) 04:00:21 INFO - PROCESS | 1638 | 2017-03-27 04:00:21.690 plugin-container[1641:7493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 04:00:21 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:00:21 INFO - PROCESS | 1638 | 2017-03-27 04:00:21.691 plugin-container[1641:7493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 04:00:21 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:00:21 INFO - PROCESS | 1638 | 2017-03-27 04:00:21.691 plugin-container[1641:7493] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:00:21 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 04:00:21 INFO - PROCESS | 1638 | colorDepth:24 04:00:21 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 04:00:21 INFO - PROCESS | 1638 | __metrics 04:00:21 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 04:00:22 INFO - TEST-INFO | 1638: exit 0 04:00:22 INFO - Browser initialized. 04:00:22 INFO - Running cycle 1/1 for dromaeo_css test... 04:00:22 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpf8Yt4l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:00:24 INFO - PROCESS | 1642 | 2017-03-27 04:00:24.821 plugin-container[1643:7630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x823f, name = 'com.apple.tsm.portname' 04:00:24 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:00:24 INFO - PROCESS | 1642 | 2017-03-27 04:00:24.822 plugin-container[1643:7630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880b, name = 'com.apple.CFPasteboardClient' 04:00:24 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:00:24 INFO - PROCESS | 1642 | 2017-03-27 04:00:24.822 plugin-container[1643:7630] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:00:34 INFO - PROCESS | 1642 | RSS: Main: 173051904 04:00:34 INFO - PROCESS | 1642 | 04:03:36 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 04:03:38 INFO - PROCESS | 1642 | RSS: Main: 193511424 04:03:38 INFO - PROCESS | 1642 | 04:06:40 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 04:06:41 INFO - PROCESS | 1642 | RSS: Main: 204926976 04:06:41 INFO - PROCESS | 1642 | 04:09:43 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 04:09:43 INFO - PROCESS | 1642 | RSS: Main: 206704640 04:09:43 INFO - PROCESS | 1642 | 04:12:45 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 04:12:46 INFO - PROCESS | 1642 | RSS: Main: 209735680 04:12:46 INFO - PROCESS | 1642 | 04:15:49 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 04:15:49 INFO - PROCESS | 1642 | RSS: Main: 210919424 04:15:49 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 04:18:52 INFO - PROCESS | 1642 | RSS: Main: 211230720 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | __start_tp_report 04:18:52 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 04:18:52 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 04:18:52 INFO - PROCESS | 1642 | |i|pagename|runs| 04:18:52 INFO - PROCESS | 1642 | |0;dojo.html;3563.3942229820937;3563.697085747713;3568.1614820517175;3582.1223800168955;3602.567691876975;6040.788572399967;6069.059295809151;6078.665673387962;6085.178500902377;6126.81619551413;6040.395960403957;6040.788572399967;6052.969735151329;6097.908531372033;6115.571909966304;5354.759035843386;5417.566594672437;5418.431064738204;5424.240606315119;5487.259220005288;5266.131088370434;5457.481539253765;5474.7810087596445;5483.862903427406;5498.505134537891;4753.215719406294;5299.099153143956;5349.7057661828585;5377.60696490062;5495.560355171597;4217.430646862665;5279.683219006872;5461.69960652164;5469.589780766459;5495.340559132889;5288.867778305535;5412.810551630674;5417.972910135424;5424.620276580641;5435.592330575183;6140;6170.197874276315;6184.721687524072;6195.132681424604;6201.720922558496;5151.381834179885;6240.095186197976;6347.333529979332;6377.252806836879;6414.550981431302;5175.404828444702;6124.899720463007;6289.1195232667005;6334.683265836689;6495.058216558573;6170.259568851767;6193.194884664965;6207.565470417027;6213.471854892339;6219.502439804804;6598.043283723833;6632.966835165793;6648.96675516629;6655.301193374723;6663.167104111986;6181.320054793969;6206.06908963659;6226.906596401058;6326.367363263729;6341.302456729756;5711.829075039607;6217.378262173746;6350.936490635035;6480.416762491304;6534.640594767238;5254.448282930313;6383.0744542042075;6400.487960963112;6415.358464153547;6444.2266927968;6154.076888466676;6229.221347331583;6350.396712312323;6425.518086143558;6427.389398007182;5162.432132465426;5191.377034755854;5208.8437346879655;5229.973850130725;5279.630425870229;4611.077784443125;4631.212693842055;4642.86071417858;4656.650751193674;4686.133065382915;6077.20996270482;6115.510759139258;6130.233720784902;6137.324894261627;6154.599951003171;6266.028765541349;6393.009083592051;6400.775972841021;6457.806265812033;6566.901496477557;6038.818835434944;6092.878142437126;6136.447719705248;6147.631142131487;6170.876582468325;5617.466340697627;5664.54683625309;5667.1782591524;5697.715114244271;5738.540916726652;6580.670966451658;6689.632070236086;6696.129503164752;6709.7316107355155;6730.091437655854;5422.945770542244;5431.6740995540395;6501.317361677145;6511.902321465276;6545.672716364067;5561.221428999862;6365.649889256136;6469.4824414045825;6668.6665666717445;6718.992151177363;4914.434839993446;6099.794059995464;6362.395572420613;6430.035494675836;6442.710078046593;4809.567138957511;5897.439743224387;6418.059751260318;6494.772682956074;6496.740130394731;5131.512506311894;6226.906596400967;6265.185525881789;6284.088807123033;6291.465225455888;6157.753689852356;6168.0439531872635;6182.752689892354;6218.657973811484;6229.595076320024;6055.6063855849225;6212.347703491068;6216.906746398718;6219.720112595034;6252.781152659635;6218.720157592828;6272.717727702173;6272.8118156455375;6284.685765711788;6285.43431091204;5410.594205434687;5979.850503737442;6158.983767678283;6203.534734894811;6239.376062393724 04:18:52 INFO - PROCESS | 1642 | |1;ext.html;468.6625629546725;497.51989330296254;514.3596222702736;525.6924699051052;537.4061661863641;37716;43017;43187;43204.35193472092;43223;41281.79359103208;43592.474263400785;45031.0993780125;45140.3228951566;45203.32195017077;9452.574634141463;9669.661561845336;9678.56446459911;11278.548858045668;11551.653450396501;9897.950510247474;11533.480993355277;11638.8254176187;11643.417829108554;11684.47419866108;11620.941895290513;11625.128115391337;11626.767464650702;11654.417279136052;11665.941670291637;11563.248388854698;11610.12924030697;11638.6508404748;11670.008049315818;11692.941535292353;11456.4844581994;11463.31220126795;11535.884641153565;11550.653480395518;11562;37880.43179352285;38180.61819381799;38398.424023639665;38484.80757596222;38568.42147367792;4006.9198616027516;4048.7165898387125;4048.9190216195807;4136.689748268862;4155.563665815076;27658.723412765816;28343.433131337257;28443.573346399822;28479.00323488668;28503.714962850314;2885.898993535216;2906.462304473679;2906.5494848298335;2908.9127326180246;3053.7862349635534;493398;496832;497467;497669.51165244303;498673;48721;58618.41381586215;58857.70571147202;59303.40696593065;59335.81328373408;53961.84152633929;54027.02851343214;59665.70167149136;62055.689721551105;62491;16082.517524474284;16733.581660458585;16774.245158967875;16782;16888.493345199662;10678.73303167427;11749.29504229749;12217.266963982189;13122.540711075066;13138.27739474319;15614.609634759221;16212.270447829877;18053.277868885096;18712.345067922557;18915.62168756617;6927.822270213975;7039.26087760788;8115.660915948921;8130.796730081795;8171.836563268701;861.7716305179133;863.2877875752529;891.7057371067532;894.4007514964989;915.4781774388667;30217.848910755307;30851.69148308533;31076.223094422367;31330.93329239723;31890.840545797586;878.2403221213732;979.3878825733916;1038.9948050259852;1118.6755840806238;1122.3826895207603;15621.297041633154;16081.356745730274;16166.515004549883;16273.104979226257;16462;655421;924328;937544;938801;942878;55907;56124.719376402856;56900.57748556319;56919.43080569224;56996.715016424656;1442.1779585636295;1449.239149446549;1474.086066638691;1477.128494188434;1487.4645127754;28259;29658.55512167276;29819.552706708982;30153.793848245823;30250.2437439057;47961;48320.5167948316;48910;48911;48933.51066489432;609634;611874;613588;613786;619210;26787.598186027615;26856.194314170607;27076;27234.319142021606;27281.59077613798;38434.80782596069;39082.2183556333;41009;45034.77482612566;45105.0978980412;25094;29424.558631620115;31971.36057278888;32249.677503224666;32487.512687310184;31650.208744781565;32570;32692.50961235536;33372.332553348315;34355.65644343525;37232.62767372291;37655;37662.435063474615;37919.43120853134;38021.429678555396;27519.587206192322;30901;31182.22044448816;31657;32619;23807.880960595085;25194;25337.366565836;25395.87302063478;25469.745302546737 04:18:52 INFO - PROCESS | 1642 | |2;jquery.html;399.0920655508717;406.98372065117394;412.7234752715681;415.49517336436236;434.42004923427226;20409.693854592173;20660.586788264245;21290.46773830655;21444.14223431065;21625.891870540643;22024.559508809813;22126.88936555319;22773.86130693467;22805.657915131243;23021.424464388398;5765.509931655814;5950.107483877409;6076.969615151918;6080.969595152018;6081.99647058237;5838.620489668179;5989.281286245635;5996.460318571328;6085.878282434349;6086.513078953695;5864.354920958691;6032.457078862902;6035.60768550045;6044.818655440344;6075.939240607581;5774.162746401769;5925.733341999598;5985.730642121093;5990;5993.370696076936;5081.7967281309075;5213.2701421800975;5219.582433405319;5322.0952438085615;5326.227696983954;26899.193024209304;27400;27415.314617134532;27505;27734.029308974088;2486.27897909606;2498.3129639349145;2886.466003789306;2910.534314509668;2951.1850510437484;13005.479780808757;16361.509154725378;16382.0170789753;16452.588685282964;16667.24997375121;1620.1818081868698;1702.5658457093487;1822.5808064145322;1877.455537894009;1899.3637131560943;118557.4072129651;119775;123111.38444307722;124015;125011;11634.53461861533;11811.586594469152;11877.168598198215;11965.700857478458;11975.820362694567;15017.098974061591;15253.694926101638;15309;15432.459863904935;15432.92283538575;7076.540024898365;9269.90730092704;9294.395864268801;9313.720588382359;9421.24630029596;7521.811954701177;9193.632254709737;9287.72136835896;9445.291603129794;9507.57215925285;7437.51656142349;7521.548707077593;7582.279683430065;7635.69457221716;7650.464467487333;2029.4520479470755;2050.261905713942;2057.845661575388;2060.3406909789023;2065.783092775267;803.7749430159565;916.2303664921498;924.4360939826701;950.5769932380163;979.7158823940978;22753.544929101656;23773.04907803703;24120.51758964811;24136.63795043076;24205.273841784776;958.8178246133352;965.503469622083;980.7302991677345;983.1397527163732;999.2855108597242;11771.941140294244;12190.024798016315;12230.877691223151;12293.323867187391;12509.999200064156;706258;714225;774368;786383.0680846673;786695;107787.46106269419;132310.33844830716;138515.07076686108;139140.3042984799;140994.88507672393;1095.8356246562919;1134.7049767060623;1167.3171194851107;1169.7426566155432;1171.4201470272099;16465.83534164691;19727.60544789125;21067.367978960654;21777.891110544348;21890.452738681022;58105.70947145237;58174.4182558169;58433.12350314834;59618;59931.70034149801;82188;83052.58473707593;83092;84271;85423.71864422163;8259.752207433787;8444.282236010007;8747.081556436482;8747.737567872973;8877.02352741185;13491.662708432368;13596;13690.65773355674;14076.436942522185;14095.365708542935;11530.481128349413;11670.183087184156;11756.58851940178;11833.053355731348;12086.39568021613;10070.798584028133;10759.731006724895;11041.944790275997;11074.390908499787;11370.31778093317;11437.256578322382;11484.196106272815;11893.286402815513;12092.758144836878;12112.454939527566;7354.8896766549615;7571.583562904093;7762.379009679099;7811.375089992674;7958.482698624571;9554.952225238829;11469.598564050219;11575.536978520764;11597.478113485082;11914.98722608588 04:18:52 INFO - PROCESS | 1642 | |3;mootools.html;1316.8156458095866;1439.7552416089263;1495.0879963222433;1504.4433559582956;1512.1985347765685;7708.190639982791;7965.04419469663;8216.096229414767;8648.394612377127;8665;6416.358364163593;8474.23731864132;8480.491170529773;8484.490930544156;8572;2012.5773587546596;2419.7701218384227;2428.611422172453;2429.2955043037496;2430.3559556717396;2395.9760402396014;2417.1177520205174;2439.9756002440013;2446.449548851503;2453.815963802713;2357.7760112789256;2399.940001499968;2414.7343792182846;2419.250032489925;2432.3675844280524;2091.4562213824365;2318.385627808632;2340.9063637454483;2394.2577800881695;2395.401149712572;2379.2981070584046;2387.916422925189;2391.198948352304;2394.856308621471;2417.782399584028;8379.62291696869;8393.370497212672;8505.489670619783;8526.914730852674;8541.060483346826;5188.247704082887;5305.071612467803;5313.601479889024;5332.146856502941;5345.545628621572;3509.543759311299;3553.147244661263;3595.8381872815785;3603.7117030637746;3632;1260.7352455984255;1268.1186575330148;1301.5379540263166;1314.5333406640696;1320.3992183556459;10889.83665245022;10898.291611045257;10930.344179349264;10948.288361256493;10952.726181845437;4342.849144976583;4648.279516674921;4707.764611769398;4739.431268247832;4823.252395878644;9042.954785226031;9546.42721436716;9626.422414655142;9658.227341812639;9666.468344240993;4531.932021019687;5400.432954539795;5415.214793854869;5418.4039755626845;5433.891322173535;4465.843695470643;4508.842190523316;4928.975355123202;4970.304157417964;4994.3507344045765;3356.7915550401835;3903.9264202344016;4044.0698639313123;4050.858219962346;4076.8369265229653;1320.5774152271374;1328.880400763936;1333.3733145421636;1334.6396472952442;1336.0514035035235;909.9636014559476;919.9264058875274;921.6037104045325;926.120185823457;940.9858852117223;4248.959005043722;5208.739563021833;5248.146602269819;5287.365516138088;5338.893222135535;802.7631848604676;1030.2273295028729;1035.896410358973;1042.8070806900748;1049.228816819637;3774.5470543534952;3901.2392583445953;3931.3906344516645;3984.541777695602;4024.6176613221696;9771.560279787427;9959.15347195482;10129.290949633601;10172.084512393772;10198.98010198989;9334.299927505464;9499.24006079512;9762.36544624597;9881.110700037032;9919.65281215154;7852.411069169835;7879.529505781308;7886.290233878978;7966.243206895451;8042.919570804217;5690.231818704588;5794.855128621818;5834.883302334015;5959.970200148971;5964.522838172849;9212.401193922373;9232.676856309998;9242.306826987868;9356.719298421058;9411.576479058322;9343.205827504738;9405.952970235105;9433.669821556212;9590.952045239728;9593.42439453635;6174.506039516918;6771.153605799275;6949.270326615835;7097.148342198969;7421.183669796426;7984.960075199587;8054.073781515201;8078.878816817635;8127.349812015143;8182.590870456573;6482.2869484357625;6659.701358235097;6917.273686263071;7042.823929401806;7218.783436496913;5981.431763982415;6941.757038503628;6961.095057642474;6970.198427180736;7027.402670773042;6402.871942561216;6446.387593178641;6479.185827968176;6518.120053792867;6542.640154791532;4321.027768752131;4366.825326987012;4922.618869206338;5096.0827051131155;5112.821051263188;4134.875953721393;5038.899222015466;5165.535101840853;5182.6890386575415;5202.817901373433 04:18:52 INFO - PROCESS | 1642 | |4;prototype.html;356.57033274903745;372.98881033568983;382.34809649547526;387.242940052198;390.3890411505991;5431.864203394917;5540.944590554093;5651.350094739101;5708.457696518829;5800.44895734905;5019.9458113796145;5369.650972686773;5452.9182062269;5481.75332110055;5589.965856316584;3414.5219669246326;3874.6900247980234;4336.97831510842;4357.542458041908;4380.693351465398;3680.6871415929545;4002.2595819773437;4117.5089071112;4199.20752699456;4425.411134486313;3627.8572249741246;3941.4876066111365;4283.365730076012;4302.247106756321;4332.046949671083;4273.444452221207;4290.935635965463;4317.568243175689;4319.308910574325;4344;3106.51848963411;3283.835808209604;3614.7108231341695;3799.772013679188;3866.439366291872;4767.380240568738;5076.263941728465;5446.2919820423485;5454.781808727646;5496.725163741797;4306.590873866978;4726.5746082852365;4853;4886.315915771795;4934.25986102084;5071.644984851062;5604.2714447122025;5839.708014599253;6002.939970600325;6008.939910600881;3550.7869527828157;3778.263238668461;3977.24432357854;4021.828212765103;4031.2743706132883;5550.9184055979;5621.514660910221;6265;6265.342139075423;6354.332795056545;3284.950725739068;3307.2228026413773;3837.712171587142;4134.276501612206;4136.565660605654;3537.911552211215;3987.521497420328;4051.4199462209763;4162.355869431558;4163.75017498945;4872.488388719134;4887.169181239198;4900.558949694545;4905.607551395951;4982.053409852189;5664.518515926193;5917.768795311373;5956.434138756811;5963.016102343152;6038.396160383926;1238.5355491690616;1306.6406738147086;1335.3990704183157;1344.247221555932;1358.6739182596107;816.4529765057425;883.8143989762222;887.0287035695902;906.4334790755778;916.834969705446;4864.513548645178;5028.698278103252;5086.898262034812;5123.359580052494;5234.451481429696;4883.8290659826735;5346.037713211582;5875.9129561030495;5886.087656413263;5938.584299099108;4242.048657617469;4694.929576056362;5197.622630003052;5273.6572122811895;5463.699496527729;2577.587585986233;2718.9184324470298;2758.848263345535;2945.867435965387;3003.3781757850516;5472.425395333433;5870.765169393262;5963.642181469039;6147.200863887755;6175.752969881154;5441.075017247077;5471.835844924659;5817.331006934172;5906.586538942232;6036.154938308552;5231.843044708665;5469.343678758574;6104.49715616595;6142.508599312045;6182.412670796177;5088.720120393414;5270.576944224961;5434.402215756343;5764.567657425794;5832.387599302012;4898.044881248123;5038.228974064176;5276.86807829792;5682.403347648603;5856.970715146397;5481.205225242397;6011.423203194093;6078.3617720138745;6106.778704208016;6144.8771024580155;3305.950410743793;3542.096765324826;3748.7001039916195;3776.0182352587976;3867.110564570221;5232.136697444879;5805.76776928935;5837.211976383134;5840.591158618856;5895.469407753323;5363.570914326767;5820.563457740602;5868.765249389977;5934.792282270272;6016.368281330398;5422.728863556886;5492.505674489315;5904.232449781501;5927.881442371042;5992.621697009625;4529.6602754794185;5678;5744.425557444222;5748.885022299447;5858.853528661817;5084.618653601069;5507.0637991542335;5785.49577109946;5892.6759028253855;5940.405959404198;4668.346431499661;5804.767809287581;5823.213866128019;5839.912401314069;5879.295004448833 04:18:52 INFO - PROCESS | 1642 | |5;yui.html;743.5278598090212;753.8492301539696;789.7078081109987;861.8017855893145;875.4615911214603;14364.712705745891;15131.470398536054;16222.513324600252;16253.674926501493;16296.348146074157;12351.444185011673;12421.56524521642;14474.131552106881;14730.26348682567;14813.333399996998;672.7073722930517;849.235687880906;873.462820365477;878;891.0910870911666;877.0484024833064;894.7002754077392;898.2140626951417;899.0919171636629;912.1516989200042;978.0806042320232;979.647326962293;980.6518685866522;982.5136557404095;984.4634674102596;956.7990721948399;962.8892677342126;969.4038166527605;969.8642190093358;972.7373609125532;714.5962531169924;722.797616667334;723.6924307169432;728.6866647341641;729.6133049483782;15072.472463463726;15153.090814551051;15758.606034849221;15779;16281.83718162815;14421.062630928956;14724.263786810723;14763.704725905422;14767.335469903772;14807.111573305636;11759.94120029403;11875.34685592292;11942.582009629708;11975.820362694567;12060;3713.9071523211865;3860.942085868714;3861.9227615447535;3886.5724770275247;3887.805609719531;91987.24038278863;92814.99999999933;110679;111127.44436277848;113256;10752.94623526893;11192.440377981231;11198.10415166801;11237.044851187577;11746.88253117475;23062.731549764925;23110.76889231086;23162.073517059467;23217.767822321897;23251.18620848296;7942.444028918036;7971.242731940456;8031.598420078973;8046.758597242092;8085.231902969209;4386.592001270885;4422.778861056934;4493.640508759291;4514.593686568224;4569.337446070301;3720.404735242348;3777.660010599004;3777.792221427793;3782.205736795249;3822.7132965027736;657.9671016449254;661.3056290894541;662.2747742131774;674.3896773420063;680.0954730208811;649.191756263455;689.5311188391942;707.0525495835604;723.3092396761081;742.6212631557944;8977.775555611162;10144;10787.946060269805;10963.61627343055;11109.888901110886;683.2620769568854;687.6733551563068;697.5108144112356;713.4720306972803;720.9783706488709;4783.851875549843;5131.384233891882;5137.280780690631;5141.305923700327;5148.845534633967;110328.34507482394;112392.43803780929;114787;115876.42061789805;115908.84091158981;91296;93481;95506.52246738721;96479;98747;64893;71192.64403677949;73367;73370.63314683392;73541;10701.51843167075;11198.657511530895;11277.154213433862;11360.602378916697;11515.25150865191;51707;51807.74096129646;52278;52655.47344526506;54568.727156363966;59829.40170598238;59983;60883;69055;73389.22970006932;13796.724065518834;13820.308984550531;13902.930485347508;14050;14453.34959926827;13022;14872;15055.924720376328;15227.314770835117;15669;13707.109037912503;14270.286485675468;14545.636359090684;14610.624288331359;14807.987405089538;13252.271125088213;13354.532591359644;13435.395407206504;13580.185188888698;13780.793288100485;11792.882071179178;13404.530841420503;14095.577132686036;14769.261536923326;14872.925635371754;9546.42721436716;9746.951265243912;10091.243156763125;10311.742206444898;10485.9475702621;7299.98403066125;8469.322454203771;10683.30558513694;11347.54609815598;11449.141314401286 04:18:52 INFO - PROCESS | 1642 | __end_tp_report 04:18:52 INFO - PROCESS | 1642 | __start_cc_report 04:18:52 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,375 04:18:52 INFO - PROCESS | 1642 | __end_cc_report 04:18:52 INFO - PROCESS | 1642 | __startTimestamp1490613532401__endTimestamp 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | ------- Summary: start ------- 04:18:52 INFO - PROCESS | 1642 | Number of tests: 6 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5900.46 Median:6157.75 stddev:669.55 (10.9%) stddev-sans-first:646.02 04:18:52 INFO - PROCESS | 1642 | Values: 3563.4 3563.7 3568.2 3582.1 3602.6 6040.8 6069.1 6078.7 6085.2 6126.8 6040.4 6040.8 6053.0 6097.9 6115.6 5354.8 5417.6 5418.4 5424.2 5487.3 5266.1 5457.5 5474.8 5483.9 5498.5 4753.2 5299.1 5349.7 5377.6 5495.6 4217.4 5279.7 5461.7 5469.6 5495.3 5288.9 5412.8 5418.0 5424.6 5435.6 6140.0 6170.2 6184.7 6195.1 6201.7 5151.4 6240.1 6347.3 6377.3 6414.6 5175.4 6124.9 6289.1 6334.7 6495.1 6170.3 6193.2 6207.6 6213.5 6219.5 6598.0 6633.0 6649.0 6655.3 6663.2 6181.3 6206.1 6226.9 6326.4 6341.3 5711.8 6217.4 6350.9 6480.4 6534.6 5254.4 6383.1 6400.5 6415.4 6444.2 6154.1 6229.2 6350.4 6425.5 6427.4 5162.4 5191.4 5208.8 5230.0 5279.6 4611.1 4631.2 4642.9 4656.7 4686.1 6077.2 6115.5 6130.2 6137.3 6154.6 6266.0 6393.0 6400.8 6457.8 6566.9 6038.8 6092.9 6136.4 6147.6 6170.9 5617.5 5664.5 5667.2 5697.7 5738.5 6580.7 6689.6 6696.1 6709.7 6730.1 5422.9 5431.7 6501.3 6511.9 6545.7 5561.2 6365.6 6469.5 6668.7 6719.0 4914.4 6099.8 6362.4 6430.0 6442.7 4809.6 5897.4 6418.1 6494.8 6496.7 5131.5 6226.9 6265.2 6284.1 6291.5 6157.8 6168.0 6182.8 6218.7 6229.6 6055.6 6212.3 6216.9 6219.7 6252.8 6218.7 6272.7 6272.8 6284.7 6285.4 5410.6 5979.9 6159.0 6203.5 6239.4 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:77419.68 Median:32655.75 stddev:184870.35 (566.1%) stddev-sans-first:185298.67 04:18:52 INFO - PROCESS | 1642 | Values: 468.7 497.5 514.4 525.7 537.4 37716.0 43017.0 43187.0 43204.4 43223.0 41281.8 43592.5 45031.1 45140.3 45203.3 9452.6 9669.7 9678.6 11278.5 11551.7 9898.0 11533.5 11638.8 11643.4 11684.5 11620.9 11625.1 11626.8 11654.4 11665.9 11563.2 11610.1 11638.7 11670.0 11692.9 11456.5 11463.3 11535.9 11550.7 11562.0 37880.4 38180.6 38398.4 38484.8 38568.4 4006.9 4048.7 4048.9 4136.7 4155.6 27658.7 28343.4 28443.6 28479.0 28503.7 2885.9 2906.5 2906.5 2908.9 3053.8 493398.0 496832.0 497467.0 497669.5 498673.0 48721.0 58618.4 58857.7 59303.4 59335.8 53961.8 54027.0 59665.7 62055.7 62491.0 16082.5 16733.6 16774.2 16782.0 16888.5 10678.7 11749.3 12217.3 13122.5 13138.3 15614.6 16212.3 18053.3 18712.3 18915.6 6927.8 7039.3 8115.7 8130.8 8171.8 861.8 863.3 891.7 894.4 915.5 30217.8 30851.7 31076.2 31330.9 31890.8 878.2 979.4 1039.0 1118.7 1122.4 15621.3 16081.4 16166.5 16273.1 16462.0 655421.0 924328.0 937544.0 938801.0 942878.0 55907.0 56124.7 56900.6 56919.4 56996.7 1442.2 1449.2 1474.1 1477.1 1487.5 28259.0 29658.6 29819.6 30153.8 30250.2 47961.0 48320.5 48910.0 48911.0 48933.5 609634.0 611874.0 613588.0 613786.0 619210.0 26787.6 26856.2 27076.0 27234.3 27281.6 38434.8 39082.2 41009.0 45034.8 45105.1 25094.0 29424.6 31971.4 32249.7 32487.5 31650.2 32570.0 32692.5 33372.3 34355.7 37232.6 37655.0 37662.4 37919.4 38021.4 27519.6 30901.0 31182.2 31657.0 32619.0 23807.9 25194.0 25337.4 25395.9 25469.7 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:40567.02 Median:14698.75 stddev:124791.88 (849.0%) stddev-sans-first:125105.50 04:18:52 INFO - PROCESS | 1642 | Values: 399.1 407.0 412.7 415.5 434.4 20409.7 20660.6 21290.5 21444.1 21625.9 22024.6 22126.9 22773.9 22805.7 23021.4 5765.5 5950.1 6077.0 6081.0 6082.0 5838.6 5989.3 5996.5 6085.9 6086.5 5864.4 6032.5 6035.6 6044.8 6075.9 5774.2 5925.7 5985.7 5990.0 5993.4 5081.8 5213.3 5219.6 5322.1 5326.2 26899.2 27400.0 27415.3 27505.0 27734.0 2486.3 2498.3 2886.5 2910.5 2951.2 13005.5 16361.5 16382.0 16452.6 16667.2 1620.2 1702.6 1822.6 1877.5 1899.4 118557.4 119775.0 123111.4 124015.0 125011.0 11634.5 11811.6 11877.2 11965.7 11975.8 15017.1 15253.7 15309.0 15432.5 15432.9 7076.5 9269.9 9294.4 9313.7 9421.2 7521.8 9193.6 9287.7 9445.3 9507.6 7437.5 7521.5 7582.3 7635.7 7650.5 2029.5 2050.3 2057.8 2060.3 2065.8 803.8 916.2 924.4 950.6 979.7 22753.5 23773.0 24120.5 24136.6 24205.3 958.8 965.5 980.7 983.1 999.3 11771.9 12190.0 12230.9 12293.3 12510.0 706258.0 714225.0 774368.0 786383.1 786695.0 107787.5 132310.3 138515.1 139140.3 140994.9 1095.8 1134.7 1167.3 1169.7 1171.4 16465.8 19727.6 21067.4 21777.9 21890.5 58105.7 58174.4 58433.1 59618.0 59931.7 82188.0 83052.6 83092.0 84271.0 85423.7 8259.8 8444.3 8747.1 8747.7 8877.0 13491.7 13596.0 13690.7 14076.4 14095.4 11530.5 11670.2 11756.6 11833.1 12086.4 10070.8 10759.7 11041.9 11074.4 11370.3 11437.3 11484.2 11893.3 12092.8 12112.5 7354.9 7571.6 7762.4 7811.4 7958.5 9555.0 11469.6 11575.5 11597.5 11915.0 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5460.57 Median:5228.44 stddev:2945.94 (56.3%) stddev-sans-first:2937.74 04:18:52 INFO - PROCESS | 1642 | Values: 1316.8 1439.8 1495.1 1504.4 1512.2 7708.2 7965.0 8216.1 8648.4 8665.0 6416.4 8474.2 8480.5 8484.5 8572.0 2012.6 2419.8 2428.6 2429.3 2430.4 2396.0 2417.1 2440.0 2446.4 2453.8 2357.8 2399.9 2414.7 2419.3 2432.4 2091.5 2318.4 2340.9 2394.3 2395.4 2379.3 2387.9 2391.2 2394.9 2417.8 8379.6 8393.4 8505.5 8526.9 8541.1 5188.2 5305.1 5313.6 5332.1 5345.5 3509.5 3553.1 3595.8 3603.7 3632.0 1260.7 1268.1 1301.5 1314.5 1320.4 10889.8 10898.3 10930.3 10948.3 10952.7 4342.8 4648.3 4707.8 4739.4 4823.3 9043.0 9546.4 9626.4 9658.2 9666.5 4531.9 5400.4 5415.2 5418.4 5433.9 4465.8 4508.8 4929.0 4970.3 4994.4 3356.8 3903.9 4044.1 4050.9 4076.8 1320.6 1328.9 1333.4 1334.6 1336.1 910.0 919.9 921.6 926.1 941.0 4249.0 5208.7 5248.1 5287.4 5338.9 802.8 1030.2 1035.9 1042.8 1049.2 3774.5 3901.2 3931.4 3984.5 4024.6 9771.6 9959.2 10129.3 10172.1 10199.0 9334.3 9499.2 9762.4 9881.1 9919.7 7852.4 7879.5 7886.3 7966.2 8042.9 5690.2 5794.9 5834.9 5960.0 5964.5 9212.4 9232.7 9242.3 9356.7 9411.6 9343.2 9406.0 9433.7 9591.0 9593.4 6174.5 6771.2 6949.3 7097.1 7421.2 7985.0 8054.1 8078.9 8127.3 8182.6 6482.3 6659.7 6917.3 7042.8 7218.8 5981.4 6941.8 6961.1 6970.2 7027.4 6402.9 6446.4 6479.2 6518.1 6542.6 4321.0 4366.8 4922.6 5096.1 5112.8 4134.9 5038.9 5165.5 5182.7 5202.8 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4671.85 Median:5252.51 stddev:1456.91 (27.7%) stddev-sans-first:1424.54 04:18:52 INFO - PROCESS | 1642 | Values: 356.6 373.0 382.3 387.2 390.4 5431.9 5540.9 5651.4 5708.5 5800.4 5019.9 5369.7 5452.9 5481.8 5590.0 3414.5 3874.7 4337.0 4357.5 4380.7 3680.7 4002.3 4117.5 4199.2 4425.4 3627.9 3941.5 4283.4 4302.2 4332.0 4273.4 4290.9 4317.6 4319.3 4344.0 3106.5 3283.8 3614.7 3799.8 3866.4 4767.4 5076.3 5446.3 5454.8 5496.7 4306.6 4726.6 4853.0 4886.3 4934.3 5071.6 5604.3 5839.7 6002.9 6008.9 3550.8 3778.3 3977.2 4021.8 4031.3 5550.9 5621.5 6265.0 6265.3 6354.3 3285.0 3307.2 3837.7 4134.3 4136.6 3537.9 3987.5 4051.4 4162.4 4163.8 4872.5 4887.2 4900.6 4905.6 4982.1 5664.5 5917.8 5956.4 5963.0 6038.4 1238.5 1306.6 1335.4 1344.2 1358.7 816.5 883.8 887.0 906.4 916.8 4864.5 5028.7 5086.9 5123.4 5234.5 4883.8 5346.0 5875.9 5886.1 5938.6 4242.0 4694.9 5197.6 5273.7 5463.7 2577.6 2718.9 2758.8 2945.9 3003.4 5472.4 5870.8 5963.6 6147.2 6175.8 5441.1 5471.8 5817.3 5906.6 6036.2 5231.8 5469.3 6104.5 6142.5 6182.4 5088.7 5270.6 5434.4 5764.6 5832.4 4898.0 5038.2 5276.9 5682.4 5857.0 5481.2 6011.4 6078.4 6106.8 6144.9 3306.0 3542.1 3748.7 3776.0 3867.1 5232.1 5805.8 5837.2 5840.6 5895.5 5363.6 5820.6 5868.8 5934.8 6016.4 5422.7 5492.5 5904.2 5927.9 5992.6 4529.7 5678.0 5744.4 5748.9 5858.9 5084.6 5507.1 5785.5 5892.7 5940.4 4668.3 5804.8 5823.2 5839.9 5879.3 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20884.53 Median:3874.25 stddev:30158.35 (778.4%) stddev-sans-first:30205.04 04:18:52 INFO - PROCESS | 1642 | Values: 743.5 753.8 789.7 861.8 875.5 14364.7 15131.5 16222.5 16253.7 16296.3 12351.4 12421.6 14474.1 14730.3 14813.3 672.7 849.2 873.5 878.0 891.1 877.0 894.7 898.2 899.1 912.2 978.1 979.6 980.7 982.5 984.5 956.8 962.9 969.4 969.9 972.7 714.6 722.8 723.7 728.7 729.6 15072.5 15153.1 15758.6 15779.0 16281.8 14421.1 14724.3 14763.7 14767.3 14807.1 11759.9 11875.3 11942.6 11975.8 12060.0 3713.9 3860.9 3861.9 3886.6 3887.8 91987.2 92815.0 110679.0 111127.4 113256.0 10752.9 11192.4 11198.1 11237.0 11746.9 23062.7 23110.8 23162.1 23217.8 23251.2 7942.4 7971.2 8031.6 8046.8 8085.2 4386.6 4422.8 4493.6 4514.6 4569.3 3720.4 3777.7 3777.8 3782.2 3822.7 658.0 661.3 662.3 674.4 680.1 649.2 689.5 707.1 723.3 742.6 8977.8 10144.0 10787.9 10963.6 11109.9 683.3 687.7 697.5 713.5 721.0 4783.9 5131.4 5137.3 5141.3 5148.8 110328.3 112392.4 114787.0 115876.4 115908.8 91296.0 93481.0 95506.5 96479.0 98747.0 64893.0 71192.6 73367.0 73370.6 73541.0 10701.5 11198.7 11277.2 11360.6 11515.3 51707.0 51807.7 52278.0 52655.5 54568.7 59829.4 59983.0 60883.0 69055.0 73389.2 13796.7 13820.3 13902.9 14050.0 14453.3 13022.0 14872.0 15055.9 15227.3 15669.0 13707.1 14270.3 14545.6 14610.6 14808.0 13252.3 13354.5 13435.4 13580.2 13780.8 11792.9 13404.5 14095.6 14769.3 14872.9 9546.4 9747.0 10091.2 10311.7 10485.9 7300.0 8469.3 10683.3 11347.5 11449.1 04:18:52 INFO - PROCESS | 1642 | -------- Summary: end -------- 04:18:52 INFO - PROCESS | 1642 | 04:18:52 INFO - TEST-INFO | 1642: exit 0 04:18:52 INFO - TEST-OK | dromaeo_css | took 1114375ms 04:18:52 INFO - TEST-START | kraken 04:18:52 INFO - operating with platform_type : mac_ 04:18:52 INFO - Initialising browser for kraken test... 04:18:52 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpAVTmp9/profile http://localhost:49244/getInfo.html) 04:18:55 INFO - PROCESS | 1653 | 2017-03-27 04:18:55.577 plugin-container[1655:9793] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 04:18:55 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:18:55 INFO - PROCESS | 1653 | 2017-03-27 04:18:55.579 plugin-container[1655:9793] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 04:18:55 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:18:55 INFO - PROCESS | 1653 | 2017-03-27 04:18:55.579 plugin-container[1655:9793] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:18:55 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 04:18:55 INFO - PROCESS | 1653 | colorDepth:24 04:18:55 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 04:18:55 INFO - PROCESS | 1653 | __metrics 04:18:55 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 04:18:56 INFO - TEST-INFO | 1653: exit 0 04:18:56 INFO - Browser initialized. 04:18:56 INFO - Running cycle 1/1 for kraken test... 04:18:56 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpAVTmp9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:58 INFO - PROCESS | 1656 | 2017-03-27 04:18:58.584 plugin-container[1657:9918] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x823f, name = 'com.apple.tsm.portname' 04:18:58 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:18:58 INFO - PROCESS | 1656 | 2017-03-27 04:18:58.584 plugin-container[1657:9918] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 04:18:58 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:18:58 INFO - PROCESS | 1656 | 2017-03-27 04:18:58.584 plugin-container[1657:9918] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:19:08 INFO - PROCESS | 1656 | RSS: Main: 173010944 04:19:08 INFO - PROCESS | 1656 | 04:19:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 04:19:12 INFO - PROCESS | 1656 | RSS: Main: 185659392 04:19:12 INFO - PROCESS | 1656 | 04:19:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 04:19:15 INFO - PROCESS | 1656 | RSS: Main: 193617920 04:19:15 INFO - PROCESS | 1656 | 04:19:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 04:19:19 INFO - PROCESS | 1656 | RSS: Main: 197464064 04:19:19 INFO - PROCESS | 1656 | 04:19:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 04:19:21 INFO - PROCESS | 1656 | RSS: Main: 198782976 04:19:21 INFO - PROCESS | 1656 | 04:19:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:19:24 INFO - PROCESS | 1656 | RSS: Main: 199094272 04:19:24 INFO - PROCESS | 1656 | 04:19:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 04:19:27 INFO - PROCESS | 1656 | RSS: Main: 203657216 04:19:27 INFO - PROCESS | 1656 | 04:19:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 04:19:31 INFO - PROCESS | 1656 | RSS: Main: 204410880 04:19:31 INFO - PROCESS | 1656 | 04:19:34 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 04:19:34 INFO - PROCESS | 1656 | RSS: Main: 203341824 04:19:34 INFO - PROCESS | 1656 | 04:19:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:19:37 INFO - PROCESS | 1656 | RSS: Main: 202395648 04:19:37 INFO - PROCESS | 1656 | 04:19:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:19:39 INFO - PROCESS | 1656 | RSS: Main: 202526720 04:19:39 INFO - PROCESS | 1656 | 04:19:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:19:42 INFO - PROCESS | 1656 | RSS: Main: 202452992 04:19:42 INFO - PROCESS | 1656 | 04:19:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:19:46 INFO - PROCESS | 1656 | RSS: Main: 202571776 04:19:46 INFO - PROCESS | 1656 | 04:19:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:19:50 INFO - PROCESS | 1656 | RSS: Main: 202895360 04:19:50 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 04:19:51 INFO - PROCESS | 1656 | RSS: Main: 204423168 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | __start_tp_report 04:19:51 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 04:19:51 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 04:19:51 INFO - PROCESS | 1656 | |i|pagename|runs| 04:19:51 INFO - PROCESS | 1656 | |0;ai-astar;106;117;117;117;118;117;122;118;117;120 04:19:51 INFO - PROCESS | 1656 | |1;audio-beat-detection;142;108;110;143;111;110;107;108;135;114 04:19:51 INFO - PROCESS | 1656 | |2;audio-dft;195;204;195;199;193;196;196;200;202;196 04:19:51 INFO - PROCESS | 1656 | |3;audio-fft;80;58;57;83;59;60;60;59;70;59 04:19:51 INFO - PROCESS | 1656 | |4;audio-oscillator;88;71;84;72;70;94;109;96;84;79 04:19:51 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;88;90;88;110;81;134;129;94;131 04:19:51 INFO - PROCESS | 1656 | |6;imaging-darkroom;96;94;94;95;97;96;107;148;113;96 04:19:51 INFO - PROCESS | 1656 | |7;imaging-desaturate;94;102;93;100;93;90;93;94;92;92 04:19:51 INFO - PROCESS | 1656 | |8;json-parse-financial;58;59;57;61;62;61;60;62;63;60 04:19:51 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;69;76;68;77;75;68;79;97;104;91 04:19:51 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;82;75;92;100;99;100;101;84;86;90 04:19:51 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;159;166;143;134;145;150;152;145;177;151 04:19:51 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;168;183;154;221;227;202;165;203;218;193 04:19:51 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;65;65;75;77;68;66;65;67;63;62 04:19:51 INFO - PROCESS | 1656 | __end_tp_report 04:19:51 INFO - PROCESS | 1656 | __start_cc_report 04:19:51 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,928 04:19:51 INFO - PROCESS | 1656 | __end_cc_report 04:19:51 INFO - PROCESS | 1656 | __startTimestamp1490613591965__endTimestamp 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | ------- Summary: start ------- 04:19:51 INFO - PROCESS | 1656 | Number of tests: 14 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:116.90 Median:117.50 stddev:4.18 (3.6%) stddev-sans-first:1.76 04:19:51 INFO - PROCESS | 1656 | Values: 106.0 117.0 117.0 117.0 118.0 117.0 122.0 118.0 117.0 120.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:112.50 stddev:14.90 (13.2%) stddev-sans-first:13.23 04:19:51 INFO - PROCESS | 1656 | Values: 142.0 108.0 110.0 143.0 111.0 110.0 107.0 108.0 135.0 114.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:197.60 Median:197.50 stddev:3.50 (1.8%) stddev-sans-first:3.59 04:19:51 INFO - PROCESS | 1656 | Values: 195.0 204.0 195.0 199.0 193.0 196.0 196.0 200.0 202.0 196.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:64.50 Median:60.00 stddev:9.68 (16.1%) stddev-sans-first:8.48 04:19:51 INFO - PROCESS | 1656 | Values: 80.0 58.0 57.0 83.0 59.0 60.0 60.0 59.0 70.0 59.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:84.70 Median:84.00 stddev:12.53 (14.9%) stddev-sans-first:13.24 04:19:51 INFO - PROCESS | 1656 | Values: 88.0 71.0 84.0 72.0 70.0 94.0 109.0 96.0 84.0 79.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:103.40 Median:88.00 stddev:20.68 (23.5%) stddev-sans-first:21.27 04:19:51 INFO - PROCESS | 1656 | Values: 89.0 88.0 90.0 88.0 110.0 81.0 134.0 129.0 94.0 131.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:95.50 stddev:16.82 (17.6%) stddev-sans-first:17.61 04:19:51 INFO - PROCESS | 1656 | Values: 96.0 94.0 94.0 95.0 97.0 96.0 107.0 148.0 113.0 96.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:93.00 stddev:3.74 (4.0%) stddev-sans-first:3.97 04:19:51 INFO - PROCESS | 1656 | Values: 94.0 102.0 93.0 100.0 93.0 90.0 93.0 94.0 92.0 92.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:61.00 stddev:1.89 (3.1%) stddev-sans-first:1.81 04:19:51 INFO - PROCESS | 1656 | Values: 58.0 59.0 57.0 61.0 62.0 61.0 60.0 62.0 63.0 60.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:80.40 Median:76.50 stddev:12.67 (16.6%) stddev-sans-first:12.75 04:19:51 INFO - PROCESS | 1656 | Values: 69.0 76.0 68.0 77.0 75.0 68.0 79.0 97.0 104.0 91.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:85.00 stddev:9.06 (10.7%) stddev-sans-first:9.02 04:19:51 INFO - PROCESS | 1656 | Values: 82.0 75.0 92.0 100.0 99.0 100.0 101.0 84.0 86.0 90.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:151.50 stddev:12.37 (8.2%) stddev-sans-first:12.88 04:19:51 INFO - PROCESS | 1656 | Values: 159.0 166.0 143.0 134.0 145.0 150.0 152.0 145.0 177.0 151.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:202.50 stddev:25.33 (12.5%) stddev-sans-first:25.14 04:19:51 INFO - PROCESS | 1656 | Values: 168.0 183.0 154.0 221.0 227.0 202.0 165.0 203.0 218.0 193.0 04:19:51 INFO - PROCESS | 1656 | 04:19:51 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:66.50 stddev:4.92 (7.4%) stddev-sans-first:5.15 04:19:51 INFO - PROCESS | 1656 | Values: 65.0 65.0 75.0 77.0 68.0 66.0 65.0 67.0 63.0 62.0 04:19:51 INFO - PROCESS | 1656 | -------- Summary: end -------- 04:19:51 INFO - PROCESS | 1656 | 04:19:52 INFO - TEST-INFO | 1656: exit 0 04:19:52 INFO - TEST-OK | kraken | took 59824ms 04:19:52 INFO - SUITE-END | took 1174s 04:19:53 INFO - Completed test suite (00:19:34) 04:19:53 INFO - javascript benchmark 04:19:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7508.307261653457, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3563.3942229820937, 3563.697085747713, 3568.1614820517175, 3582.1223800168955, 3602.567691876975, 6040.788572399967, 6069.059295809151, 6078.665673387962, 6085.178500902377, 6126.81619551413, 6040.395960403957, 6040.788572399967, 6052.969735151329, 6097.908531372033, 6115.571909966304, 5354.759035843386, 5417.566594672437, 5418.431064738204, 5424.240606315119, 5487.259220005288, 5266.131088370434, 5457.481539253765, 5474.7810087596445, 5483.862903427406, 5498.505134537891, 4753.215719406294, 5299.099153143956, 5349.7057661828585, 5377.60696490062, 5495.560355171597, 4217.430646862665, 5279.683219006872, 5461.69960652164, 5469.589780766459, 5495.340559132889, 5288.867778305535, 5412.810551630674, 5417.972910135424, 5424.620276580641, 5435.592330575183, 6140.0, 6170.197874276315, 6184.721687524072, 6195.132681424604, 6201.720922558496, 5151.381834179885, 6240.095186197976, 6347.333529979332, 6377.252806836879, 6414.550981431302, 5175.404828444702, 6124.899720463007, 6289.1195232667005, 6334.683265836689, 6495.058216558573, 6170.259568851767, 6193.194884664965, 6207.565470417027, 6213.471854892339, 6219.502439804804, 6598.043283723833, 6632.966835165793, 6648.96675516629, 6655.301193374723, 6663.167104111986, 6181.320054793969, 6206.06908963659, 6226.906596401058, 6326.367363263729, 6341.302456729756, 5711.829075039607, 6217.378262173746, 6350.936490635035, 6480.416762491304, 6534.640594767238, 5254.448282930313, 6383.0744542042075, 6400.487960963112, 6415.358464153547, 6444.2266927968, 6154.076888466676, 6229.221347331583, 6350.396712312323, 6425.518086143558, 6427.389398007182, 5162.432132465426, 5191.377034755854, 5208.8437346879655, 5229.973850130725, 5279.630425870229, 4611.077784443125, 4631.212693842055, 4642.86071417858, 4656.650751193674, 4686.133065382915, 6077.20996270482, 6115.510759139258, 6130.233720784902, 6137.324894261627, 6154.599951003171, 6266.028765541349, 6393.009083592051, 6400.775972841021, 6457.806265812033, 6566.901496477557, 6038.818835434944, 6092.878142437126, 6136.447719705248, 6147.631142131487, 6170.876582468325, 5617.466340697627, 5664.54683625309, 5667.1782591524, 5697.715114244271, 5738.540916726652, 6580.670966451658, 6689.632070236086, 6696.129503164752, 6709.7316107355155, 6730.091437655854, 5422.945770542244, 5431.6740995540395, 6501.317361677145, 6511.902321465276, 6545.672716364067, 5561.221428999862, 6365.649889256136, 6469.4824414045825, 6668.6665666717445, 6718.992151177363, 4914.434839993446, 6099.794059995464, 6362.395572420613, 6430.035494675836, 6442.710078046593, 4809.567138957511, 5897.439743224387, 6418.059751260318, 6494.772682956074, 6496.740130394731, 5131.512506311894, 6226.906596400967, 6265.185525881789, 6284.088807123033, 6291.465225455888, 6157.753689852356, 6168.0439531872635, 6182.752689892354, 6218.657973811484, 6229.595076320024, 6055.6063855849225, 6212.347703491068, 6216.906746398718, 6219.720112595034, 6252.781152659635, 6218.720157592828, 6272.717727702173, 6272.8118156455375, 6284.685765711788, 6285.43431091204, 5410.594205434687, 5979.850503737442, 6158.983767678283, 6203.534734894811, 6239.376062393724], "value": 5863.675433378097, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.6625629546725, 497.51989330296254, 514.3596222702736, 525.6924699051052, 537.4061661863641, 37716.0, 43017.0, 43187.0, 43204.35193472092, 43223.0, 41281.79359103208, 43592.474263400785, 45031.0993780125, 45140.3228951566, 45203.32195017077, 9452.574634141463, 9669.661561845336, 9678.56446459911, 11278.548858045668, 11551.653450396501, 9897.950510247474, 11533.480993355277, 11638.8254176187, 11643.417829108554, 11684.47419866108, 11620.941895290513, 11625.128115391337, 11626.767464650702, 11654.417279136052, 11665.941670291637, 11563.248388854698, 11610.12924030697, 11638.6508404748, 11670.008049315818, 11692.941535292353, 11456.4844581994, 11463.31220126795, 11535.884641153565, 11550.653480395518, 11562.0, 37880.43179352285, 38180.61819381799, 38398.424023639665, 38484.80757596222, 38568.42147367792, 4006.9198616027516, 4048.7165898387125, 4048.9190216195807, 4136.689748268862, 4155.563665815076, 27658.723412765816, 28343.433131337257, 28443.573346399822, 28479.00323488668, 28503.714962850314, 2885.898993535216, 2906.462304473679, 2906.5494848298335, 2908.9127326180246, 3053.7862349635534, 493398.0, 496832.0, 497467.0, 497669.51165244303, 498673.0, 48721.0, 58618.41381586215, 58857.70571147202, 59303.40696593065, 59335.81328373408, 53961.84152633929, 54027.02851343214, 59665.70167149136, 62055.689721551105, 62491.0, 16082.517524474284, 16733.581660458585, 16774.245158967875, 16782.0, 16888.493345199662, 10678.73303167427, 11749.29504229749, 12217.266963982189, 13122.540711075066, 13138.27739474319, 15614.609634759221, 16212.270447829877, 18053.277868885096, 18712.345067922557, 18915.62168756617, 6927.822270213975, 7039.26087760788, 8115.660915948921, 8130.796730081795, 8171.836563268701, 861.7716305179133, 863.2877875752529, 891.7057371067532, 894.4007514964989, 915.4781774388667, 30217.848910755307, 30851.69148308533, 31076.223094422367, 31330.93329239723, 31890.840545797586, 878.2403221213732, 979.3878825733916, 1038.9948050259852, 1118.6755840806238, 1122.3826895207603, 15621.297041633154, 16081.356745730274, 16166.515004549883, 16273.104979226257, 16462.0, 655421.0, 924328.0, 937544.0, 938801.0, 942878.0, 55907.0, 56124.719376402856, 56900.57748556319, 56919.43080569224, 56996.715016424656, 1442.1779585636295, 1449.239149446549, 1474.086066638691, 1477.128494188434, 1487.4645127754, 28259.0, 29658.55512167276, 29819.552706708982, 30153.793848245823, 30250.2437439057, 47961.0, 48320.5167948316, 48910.0, 48911.0, 48933.51066489432, 609634.0, 611874.0, 613588.0, 613786.0, 619210.0, 26787.598186027615, 26856.194314170607, 27076.0, 27234.319142021606, 27281.59077613798, 38434.80782596069, 39082.2183556333, 41009.0, 45034.77482612566, 45105.0978980412, 25094.0, 29424.558631620115, 31971.36057278888, 32249.677503224666, 32487.512687310184, 31650.208744781565, 32570.0, 32692.50961235536, 33372.332553348315, 34355.65644343525, 37232.62767372291, 37655.0, 37662.435063474615, 37919.43120853134, 38021.429678555396, 27519.587206192322, 30901.0, 31182.22044448816, 31657.0, 32619.0, 23807.880960595085, 25194.0, 25337.366565836, 25395.87302063478, 25469.745302546737], "value": 20189.196125544106, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.0920655508717, 406.98372065117394, 412.7234752715681, 415.49517336436236, 434.42004923427226, 20409.693854592173, 20660.586788264245, 21290.46773830655, 21444.14223431065, 21625.891870540643, 22024.559508809813, 22126.88936555319, 22773.86130693467, 22805.657915131243, 23021.424464388398, 5765.509931655814, 5950.107483877409, 6076.969615151918, 6080.969595152018, 6081.99647058237, 5838.620489668179, 5989.281286245635, 5996.460318571328, 6085.878282434349, 6086.513078953695, 5864.354920958691, 6032.457078862902, 6035.60768550045, 6044.818655440344, 6075.939240607581, 5774.162746401769, 5925.733341999598, 5985.730642121093, 5990.0, 5993.370696076936, 5081.7967281309075, 5213.2701421800975, 5219.582433405319, 5322.0952438085615, 5326.227696983954, 26899.193024209304, 27400.0, 27415.314617134532, 27505.0, 27734.029308974088, 2486.27897909606, 2498.3129639349145, 2886.466003789306, 2910.534314509668, 2951.1850510437484, 13005.479780808757, 16361.509154725378, 16382.0170789753, 16452.588685282964, 16667.24997375121, 1620.1818081868698, 1702.5658457093487, 1822.5808064145322, 1877.455537894009, 1899.3637131560943, 118557.4072129651, 119775.0, 123111.38444307722, 124015.0, 125011.0, 11634.53461861533, 11811.586594469152, 11877.168598198215, 11965.700857478458, 11975.820362694567, 15017.098974061591, 15253.694926101638, 15309.0, 15432.459863904935, 15432.92283538575, 7076.540024898365, 9269.90730092704, 9294.395864268801, 9313.720588382359, 9421.24630029596, 7521.811954701177, 9193.632254709737, 9287.72136835896, 9445.291603129794, 9507.57215925285, 7437.51656142349, 7521.548707077593, 7582.279683430065, 7635.69457221716, 7650.464467487333, 2029.4520479470755, 2050.261905713942, 2057.845661575388, 2060.3406909789023, 2065.783092775267, 803.7749430159565, 916.2303664921498, 924.4360939826701, 950.5769932380163, 979.7158823940978, 22753.544929101656, 23773.04907803703, 24120.51758964811, 24136.63795043076, 24205.273841784776, 958.8178246133352, 965.503469622083, 980.7302991677345, 983.1397527163732, 999.2855108597242, 11771.941140294244, 12190.024798016315, 12230.877691223151, 12293.323867187391, 12509.999200064156, 706258.0, 714225.0, 774368.0, 786383.0680846673, 786695.0, 107787.46106269419, 132310.33844830716, 138515.07076686108, 139140.3042984799, 140994.88507672393, 1095.8356246562919, 1134.7049767060623, 1167.3171194851107, 1169.7426566155432, 1171.4201470272099, 16465.83534164691, 19727.60544789125, 21067.367978960654, 21777.891110544348, 21890.452738681022, 58105.70947145237, 58174.4182558169, 58433.12350314834, 59618.0, 59931.70034149801, 82188.0, 83052.58473707593, 83092.0, 84271.0, 85423.71864422163, 8259.752207433787, 8444.282236010007, 8747.081556436482, 8747.737567872973, 8877.02352741185, 13491.662708432368, 13596.0, 13690.65773355674, 14076.436942522185, 14095.365708542935, 11530.481128349413, 11670.183087184156, 11756.58851940178, 11833.053355731348, 12086.39568021613, 10070.798584028133, 10759.731006724895, 11041.944790275997, 11074.390908499787, 11370.31778093317, 11437.256578322382, 11484.196106272815, 11893.286402815513, 12092.758144836878, 12112.454939527566, 7354.8896766549615, 7571.583562904093, 7762.379009679099, 7811.375089992674, 7958.482698624571, 9554.952225238829, 11469.598564050219, 11575.536978520764, 11597.478113485082, 11914.98722608588], "value": 10354.70551165331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.8156458095866, 1439.7552416089263, 1495.0879963222433, 1504.4433559582956, 1512.1985347765685, 7708.190639982791, 7965.04419469663, 8216.096229414767, 8648.394612377127, 8665.0, 6416.358364163593, 8474.23731864132, 8480.491170529773, 8484.490930544156, 8572.0, 2012.5773587546596, 2419.7701218384227, 2428.611422172453, 2429.2955043037496, 2430.3559556717396, 2395.9760402396014, 2417.1177520205174, 2439.9756002440013, 2446.449548851503, 2453.815963802713, 2357.7760112789256, 2399.940001499968, 2414.7343792182846, 2419.250032489925, 2432.3675844280524, 2091.4562213824365, 2318.385627808632, 2340.9063637454483, 2394.2577800881695, 2395.401149712572, 2379.2981070584046, 2387.916422925189, 2391.198948352304, 2394.856308621471, 2417.782399584028, 8379.62291696869, 8393.370497212672, 8505.489670619783, 8526.914730852674, 8541.060483346826, 5188.247704082887, 5305.071612467803, 5313.601479889024, 5332.146856502941, 5345.545628621572, 3509.543759311299, 3553.147244661263, 3595.8381872815785, 3603.7117030637746, 3632.0, 1260.7352455984255, 1268.1186575330148, 1301.5379540263166, 1314.5333406640696, 1320.3992183556459, 10889.83665245022, 10898.291611045257, 10930.344179349264, 10948.288361256493, 10952.726181845437, 4342.849144976583, 4648.279516674921, 4707.764611769398, 4739.431268247832, 4823.252395878644, 9042.954785226031, 9546.42721436716, 9626.422414655142, 9658.227341812639, 9666.468344240993, 4531.932021019687, 5400.432954539795, 5415.214793854869, 5418.4039755626845, 5433.891322173535, 4465.843695470643, 4508.842190523316, 4928.975355123202, 4970.304157417964, 4994.3507344045765, 3356.7915550401835, 3903.9264202344016, 4044.0698639313123, 4050.858219962346, 4076.8369265229653, 1320.5774152271374, 1328.880400763936, 1333.3733145421636, 1334.6396472952442, 1336.0514035035235, 909.9636014559476, 919.9264058875274, 921.6037104045325, 926.120185823457, 940.9858852117223, 4248.959005043722, 5208.739563021833, 5248.146602269819, 5287.365516138088, 5338.893222135535, 802.7631848604676, 1030.2273295028729, 1035.896410358973, 1042.8070806900748, 1049.228816819637, 3774.5470543534952, 3901.2392583445953, 3931.3906344516645, 3984.541777695602, 4024.6176613221696, 9771.560279787427, 9959.15347195482, 10129.290949633601, 10172.084512393772, 10198.98010198989, 9334.299927505464, 9499.24006079512, 9762.36544624597, 9881.110700037032, 9919.65281215154, 7852.411069169835, 7879.529505781308, 7886.290233878978, 7966.243206895451, 8042.919570804217, 5690.231818704588, 5794.855128621818, 5834.883302334015, 5959.970200148971, 5964.522838172849, 9212.401193922373, 9232.676856309998, 9242.306826987868, 9356.719298421058, 9411.576479058322, 9343.205827504738, 9405.952970235105, 9433.669821556212, 9590.952045239728, 9593.42439453635, 6174.506039516918, 6771.153605799275, 6949.270326615835, 7097.148342198969, 7421.183669796426, 7984.960075199587, 8054.073781515201, 8078.878816817635, 8127.349812015143, 8182.590870456573, 6482.2869484357625, 6659.701358235097, 6917.273686263071, 7042.823929401806, 7218.783436496913, 5981.431763982415, 6941.757038503628, 6961.095057642474, 6970.198427180736, 7027.402670773042, 6402.871942561216, 6446.387593178641, 6479.185827968176, 6518.120053792867, 6542.640154791532, 4321.027768752131, 4366.825326987012, 4922.618869206338, 5096.0827051131155, 5112.821051263188, 4134.875953721393, 5038.899222015466, 5165.535101840853, 5182.6890386575415, 5202.817901373433], "value": 4476.917167982915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.57033274903745, 372.98881033568983, 382.34809649547526, 387.242940052198, 390.3890411505991, 5431.864203394917, 5540.944590554093, 5651.350094739101, 5708.457696518829, 5800.44895734905, 5019.9458113796145, 5369.650972686773, 5452.9182062269, 5481.75332110055, 5589.965856316584, 3414.5219669246326, 3874.6900247980234, 4336.97831510842, 4357.542458041908, 4380.693351465398, 3680.6871415929545, 4002.2595819773437, 4117.5089071112, 4199.20752699456, 4425.411134486313, 3627.8572249741246, 3941.4876066111365, 4283.365730076012, 4302.247106756321, 4332.046949671083, 4273.444452221207, 4290.935635965463, 4317.568243175689, 4319.308910574325, 4344.0, 3106.51848963411, 3283.835808209604, 3614.7108231341695, 3799.772013679188, 3866.439366291872, 4767.380240568738, 5076.263941728465, 5446.2919820423485, 5454.781808727646, 5496.725163741797, 4306.590873866978, 4726.5746082852365, 4853.0, 4886.315915771795, 4934.25986102084, 5071.644984851062, 5604.2714447122025, 5839.708014599253, 6002.939970600325, 6008.939910600881, 3550.7869527828157, 3778.263238668461, 3977.24432357854, 4021.828212765103, 4031.2743706132883, 5550.9184055979, 5621.514660910221, 6265.0, 6265.342139075423, 6354.332795056545, 3284.950725739068, 3307.2228026413773, 3837.712171587142, 4134.276501612206, 4136.565660605654, 3537.911552211215, 3987.521497420328, 4051.4199462209763, 4162.355869431558, 4163.75017498945, 4872.488388719134, 4887.169181239198, 4900.558949694545, 4905.607551395951, 4982.053409852189, 5664.518515926193, 5917.768795311373, 5956.434138756811, 5963.016102343152, 6038.396160383926, 1238.5355491690616, 1306.6406738147086, 1335.3990704183157, 1344.247221555932, 1358.6739182596107, 816.4529765057425, 883.8143989762222, 887.0287035695902, 906.4334790755778, 916.834969705446, 4864.513548645178, 5028.698278103252, 5086.898262034812, 5123.359580052494, 5234.451481429696, 4883.8290659826735, 5346.037713211582, 5875.9129561030495, 5886.087656413263, 5938.584299099108, 4242.048657617469, 4694.929576056362, 5197.622630003052, 5273.6572122811895, 5463.699496527729, 2577.587585986233, 2718.9184324470298, 2758.848263345535, 2945.867435965387, 3003.3781757850516, 5472.425395333433, 5870.765169393262, 5963.642181469039, 6147.200863887755, 6175.752969881154, 5441.075017247077, 5471.835844924659, 5817.331006934172, 5906.586538942232, 6036.154938308552, 5231.843044708665, 5469.343678758574, 6104.49715616595, 6142.508599312045, 6182.412670796177, 5088.720120393414, 5270.576944224961, 5434.402215756343, 5764.567657425794, 5832.387599302012, 4898.044881248123, 5038.228974064176, 5276.86807829792, 5682.403347648603, 5856.970715146397, 5481.205225242397, 6011.423203194093, 6078.3617720138745, 6106.778704208016, 6144.8771024580155, 3305.950410743793, 3542.096765324826, 3748.7001039916195, 3776.0182352587976, 3867.110564570221, 5232.136697444879, 5805.76776928935, 5837.211976383134, 5840.591158618856, 5895.469407753323, 5363.570914326767, 5820.563457740602, 5868.765249389977, 5934.792282270272, 6016.368281330398, 5422.728863556886, 5492.505674489315, 5904.232449781501, 5927.881442371042, 5992.621697009625, 4529.6602754794185, 5678.0, 5744.425557444222, 5748.885022299447, 5858.853528661817, 5084.618653601069, 5507.0637991542335, 5785.49577109946, 5892.6759028253855, 5940.405959404198, 4668.346431499661, 5804.767809287581, 5823.213866128019, 5839.912401314069, 5879.295004448833], "value": 4220.902808094444, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.5278598090212, 753.8492301539696, 789.7078081109987, 861.8017855893145, 875.4615911214603, 14364.712705745891, 15131.470398536054, 16222.513324600252, 16253.674926501493, 16296.348146074157, 12351.444185011673, 12421.56524521642, 14474.131552106881, 14730.26348682567, 14813.333399996998, 672.7073722930517, 849.235687880906, 873.462820365477, 878.0, 891.0910870911666, 877.0484024833064, 894.7002754077392, 898.2140626951417, 899.0919171636629, 912.1516989200042, 978.0806042320232, 979.647326962293, 980.6518685866522, 982.5136557404095, 984.4634674102596, 956.7990721948399, 962.8892677342126, 969.4038166527605, 969.8642190093358, 972.7373609125532, 714.5962531169924, 722.797616667334, 723.6924307169432, 728.6866647341641, 729.6133049483782, 15072.472463463726, 15153.090814551051, 15758.606034849221, 15779.0, 16281.83718162815, 14421.062630928956, 14724.263786810723, 14763.704725905422, 14767.335469903772, 14807.111573305636, 11759.94120029403, 11875.34685592292, 11942.582009629708, 11975.820362694567, 12060.0, 3713.9071523211865, 3860.942085868714, 3861.9227615447535, 3886.5724770275247, 3887.805609719531, 91987.24038278863, 92814.99999999933, 110679.0, 111127.44436277848, 113256.0, 10752.94623526893, 11192.440377981231, 11198.10415166801, 11237.044851187577, 11746.88253117475, 23062.731549764925, 23110.76889231086, 23162.073517059467, 23217.767822321897, 23251.18620848296, 7942.444028918036, 7971.242731940456, 8031.598420078973, 8046.758597242092, 8085.231902969209, 4386.592001270885, 4422.778861056934, 4493.640508759291, 4514.593686568224, 4569.337446070301, 3720.404735242348, 3777.660010599004, 3777.792221427793, 3782.205736795249, 3822.7132965027736, 657.9671016449254, 661.3056290894541, 662.2747742131774, 674.3896773420063, 680.0954730208811, 649.191756263455, 689.5311188391942, 707.0525495835604, 723.3092396761081, 742.6212631557944, 8977.775555611162, 10144.0, 10787.946060269805, 10963.61627343055, 11109.888901110886, 683.2620769568854, 687.6733551563068, 697.5108144112356, 713.4720306972803, 720.9783706488709, 4783.851875549843, 5131.384233891882, 5137.280780690631, 5141.305923700327, 5148.845534633967, 110328.34507482394, 112392.43803780929, 114787.0, 115876.42061789805, 115908.84091158981, 91296.0, 93481.0, 95506.52246738721, 96479.0, 98747.0, 64893.0, 71192.64403677949, 73367.0, 73370.63314683392, 73541.0, 10701.51843167075, 11198.657511530895, 11277.154213433862, 11360.602378916697, 11515.25150865191, 51707.0, 51807.74096129646, 52278.0, 52655.47344526506, 54568.727156363966, 59829.40170598238, 59983.0, 60883.0, 69055.0, 73389.22970006932, 13796.724065518834, 13820.308984550531, 13902.930485347508, 14050.0, 14453.34959926827, 13022.0, 14872.0, 15055.924720376328, 15227.314770835117, 15669.0, 13707.109037912503, 14270.286485675468, 14545.636359090684, 14610.624288331359, 14807.987405089538, 13252.271125088213, 13354.532591359644, 13435.395407206504, 13580.185188888698, 13780.793288100485, 11792.882071179178, 13404.530841420503, 14095.577132686036, 14769.261536923326, 14872.925635371754, 9546.42721436716, 9746.951265243912, 10091.243156763125, 10311.742206444898, 10485.9475702621, 7299.98403066125, 8469.322454203771, 10683.30558513694, 11347.54609815598, 11449.141314401286], "value": 7733.829606273149, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 117.0, 117.0, 118.0, 117.0, 122.0, 118.0, 117.0, 120.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 110.0, 143.0, 111.0, 110.0, 107.0, 108.0, 135.0, 114.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 195.0, 199.0, 193.0, 196.0, 196.0, 200.0, 202.0, 196.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 57.0, 83.0, 59.0, 60.0, 60.0, 59.0, 70.0, 59.0], "value": 64.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 84.0, 72.0, 70.0, 94.0, 109.0, 96.0, 84.0, 79.0], "value": 84.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 88.0, 110.0, 81.0, 134.0, 129.0, 94.0, 131.0], "value": 103.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 94.0, 95.0, 97.0, 96.0, 107.0, 148.0, 113.0, 96.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 102.0, 93.0, 100.0, 93.0, 90.0, 93.0, 94.0, 92.0, 92.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 57.0, 61.0, 62.0, 61.0, 60.0, 62.0, 63.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 68.0, 77.0, 75.0, 68.0, 79.0, 97.0, 104.0, 91.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 75.0, 92.0, 100.0, 99.0, 100.0, 101.0, 84.0, 86.0, 90.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 166.0, 143.0, 134.0, 145.0, 150.0, 152.0, 145.0, 177.0, 151.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 183.0, 154.0, 221.0, 227.0, 202.0, 165.0, 203.0, 218.0, 193.0], "value": 193.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 75.0, 77.0, 68.0, 66.0, 65.0, 67.0, 63.0, 62.0], "value": 67.3, "unit": "score"}]}]} 04:19:53 INFO - Return code: 0 04:19:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:19:53 ERROR - # TBPL SUCCESS # 04:19:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:19:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:19:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:19:53 INFO - 2017-03-27 04:19:53.276 screenresolution[1661:10305] starting screenresolution argv=screenresolution get 04:19:53 INFO - 2017-03-27 04:19:53.279 screenresolution[1661:10305] Display 0: 1600x1200x32@60 04:19:53 INFO - 2017-03-27 04:19:53.285 screenresolution[1662:10308] starting screenresolution argv=screenresolution list 04:19:53 INFO - Available Modes on Display 0 04:19:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:19:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:19:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:19:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:19:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:19:53 INFO - Intel Iris: 04:19:53 INFO - Chipset Model: Intel Iris 04:19:53 INFO - Type: GPU 04:19:53 INFO - Bus: Built-In 04:19:53 INFO - VRAM (Dynamic, Max): 1536 MB 04:19:53 INFO - Vendor: Intel (0x8086) 04:19:53 INFO - Device ID: 0x0a2e 04:19:53 INFO - Revision ID: 0x0009 04:19:53 INFO - Displays: 04:19:53 INFO - Display: 04:19:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:19:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:19:53 INFO - Display Serial Number: 1600x1200 60 04:19:53 INFO - Main Display: Yes 04:19:53 INFO - Mirror: Off 04:19:53 INFO - Online: Yes 04:19:53 INFO - Rotation: Supported 04:19:53 INFO - Return code: 0 04:19:53 INFO - Running post-action listener: _resource_record_post_action 04:19:53 INFO - [mozharness: 2017-03-27 11:19:53.522316Z] Finished run-tests step (success) 04:19:53 INFO - Running post-run listener: _resource_record_post_run 04:19:53 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 40200192; Write bytes: 423038976; Read time: 403; Write time: 1426 04:19:53 INFO - TinderboxPrint: CPU usage
54.5% 04:19:53 INFO - TinderboxPrint: I/O read bytes / time
40,200,192 / 403 04:19:53 INFO - TinderboxPrint: I/O write bytes / time
423,038,976 / 1,426 04:19:53 INFO - TinderboxPrint: CPU idle
2,166.6 (45.5%) 04:19:53 INFO - TinderboxPrint: CPU system
961.7 (20.2%) 04:19:53 INFO - TinderboxPrint: CPU user
1,633.3 (34.3%) 04:19:53 INFO - TinderboxPrint: Swap in / out
162,074,624 / 0 04:19:53 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119384576; Write bytes: 112726016; Read time: 10791; Write time: 212 04:19:53 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38082560; Write bytes: 299286528; Read time: 378; Write time: 1158 04:19:53 INFO - Running post-run listener: _upload_blobber_files 04:19:53 INFO - Blob upload gear active. 04:19:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:19:53 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 04:19:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:19:54 INFO - (blobuploader) - INFO - Open directory for files ... 04:19:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:19:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:19:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:55 INFO - (blobuploader) - INFO - Done attempting. 04:19:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:19:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:19:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:56 INFO - (blobuploader) - INFO - Done attempting. 04:19:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:19:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:19:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:57 INFO - (blobuploader) - INFO - Done attempting. 04:19:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:19:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:20:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:00 INFO - (blobuploader) - INFO - Done attempting. 04:20:00 INFO - (blobuploader) - INFO - Iteration through files over. 04:20:00 INFO - Return code: 0 04:20:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:20:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:20:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ba502015e6241f2a450288fbebf011ac88e13308d9eb400f0e24a696f8d9abf5b8fa3331f87078714d32d46b6add3ee6e7e842520602a51acd6e1ecb72c86f9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/42608daad7fb7b3584a8cbc40865839672d29e34b070af1b422f389fda3bb7513a6fc15237e194711fc9bd9684c3feb309a356a5e7610e59dc4b9066b0b936be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/64a01f87a10414fa9cb7ff24447ed79015fe7baf4051d592f98793bb13063ed74b721343c4d71f23ddba93a232c7cefa706bc7a9e4208b95bf7347858db745ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01d24f8fa4e7a961889d3cecc71b6d6873200a83994a47d01f8890e11ce05266a96bd96d6517f19f54d9cdbae556f1e46d02dd332063d3b5754a6caea5d5f21b"} 04:20:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:20:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:20:00 INFO - Contents: 04:20:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ba502015e6241f2a450288fbebf011ac88e13308d9eb400f0e24a696f8d9abf5b8fa3331f87078714d32d46b6add3ee6e7e842520602a51acd6e1ecb72c86f9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/42608daad7fb7b3584a8cbc40865839672d29e34b070af1b422f389fda3bb7513a6fc15237e194711fc9bd9684c3feb309a356a5e7610e59dc4b9066b0b936be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/64a01f87a10414fa9cb7ff24447ed79015fe7baf4051d592f98793bb13063ed74b721343c4d71f23ddba93a232c7cefa706bc7a9e4208b95bf7347858db745ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01d24f8fa4e7a961889d3cecc71b6d6873200a83994a47d01f8890e11ce05266a96bd96d6517f19f54d9cdbae556f1e46d02dd332063d3b5754a6caea5d5f21b"} 04:20:00 INFO - Running post-run listener: copy_logs_to_upload_dir 04:20:00 INFO - Copying logs to upload dir... 04:20:00 INFO - mkdir: /builds/slave/test/build/upload/logs 04:20:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.214129 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-27 04:20:00.149898) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 04:20:00.152969) ========= 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.DUYQExrfG7/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.RarJRZkwwD/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ba502015e6241f2a450288fbebf011ac88e13308d9eb400f0e24a696f8d9abf5b8fa3331f87078714d32d46b6add3ee6e7e842520602a51acd6e1ecb72c86f9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/42608daad7fb7b3584a8cbc40865839672d29e34b070af1b422f389fda3bb7513a6fc15237e194711fc9bd9684c3feb309a356a5e7610e59dc4b9066b0b936be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/64a01f87a10414fa9cb7ff24447ed79015fe7baf4051d592f98793bb13063ed74b721343c4d71f23ddba93a232c7cefa706bc7a9e4208b95bf7347858db745ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01d24f8fa4e7a961889d3cecc71b6d6873200a83994a47d01f8890e11ce05266a96bd96d6517f19f54d9cdbae556f1e46d02dd332063d3b5754a6caea5d5f21b"} build_url:https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011039 build_url: 'https://queue.taskcluster.net/v1/task/ADwkcauvSxCBum1UFYVHqA/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ba502015e6241f2a450288fbebf011ac88e13308d9eb400f0e24a696f8d9abf5b8fa3331f87078714d32d46b6add3ee6e7e842520602a51acd6e1ecb72c86f9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/42608daad7fb7b3584a8cbc40865839672d29e34b070af1b422f389fda3bb7513a6fc15237e194711fc9bd9684c3feb309a356a5e7610e59dc4b9066b0b936be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/64a01f87a10414fa9cb7ff24447ed79015fe7baf4051d592f98793bb13063ed74b721343c4d71f23ddba93a232c7cefa706bc7a9e4208b95bf7347858db745ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01d24f8fa4e7a961889d3cecc71b6d6873200a83994a47d01f8890e11ce05266a96bd96d6517f19f54d9cdbae556f1e46d02dd332063d3b5754a6caea5d5f21b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 04:20:00.188256) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 04:20:00.188662) ========= 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.DUYQExrfG7/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.RarJRZkwwD/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.005027 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 04:20:00.237383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 04:20:00.237703) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 04:20:00.238136) ========= ========= Total master_lag: 0.18 =========