builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0379 starttime: 1490042787.26 results: success (0) buildid: 20170320124223 builduid: cfbae3e14cfd43f5912b55b3753279c8 revision: 0496e366a8f4d1393a8caba1db5f6d1f49da468d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:27.256877) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:27.257285) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:27.257570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:27.284075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:27.284379) ========= 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.FqFuY9s1N4/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.YTGsqysE28/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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-20 13:46:27-- 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% 18.9M=0.001s 2017-03-20 13:46:27 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.232342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:27.540582) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:27.540908) ========= 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.FqFuY9s1N4/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.YTGsqysE28/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.026655 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:27.589417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 13:46:27.589755) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0496e366a8f4d1393a8caba1db5f6d1f49da468d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0496e366a8f4d1393a8caba1db5f6d1f49da468d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FqFuY9s1N4/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.YTGsqysE28/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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-20 13:46:27,697 truncating revision to first 12 chars 2017-03-20 13:46:27,698 Setting DEBUG logging. 2017-03-20 13:46:27,698 attempt 1/10 2017-03-20 13:46:27,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0496e366a8f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 13:46:29,083 unpacking tar archive at: mozilla-aurora-0496e366a8f4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.658443 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 13:46:29.273581) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:29.274120) ========= script_repo_revision: 0496e366a8f4d1393a8caba1db5f6d1f49da468d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:29.274750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:29.275197) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 13:46:29.295800) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-03-20 13:46:29.296132) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FqFuY9s1N4/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.YTGsqysE28/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:46:29 INFO - MultiFileLogger online at 20170320 13:46:29 in /builds/slave/test 13:46:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:46:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:46:29 INFO - {'append_to_log': False, 13:46:29 INFO - 'base_work_dir': '/builds/slave/test', 13:46:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:46:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:46:29 INFO - 'branch': 'Mozilla-Aurora', 13:46:29 INFO - 'buildbot_json_path': 'buildprops.json', 13:46:29 INFO - 'config_files': ('talos/mac_config.py',), 13:46:29 INFO - 'default_actions': ('clobber', 13:46:29 INFO - 'read-buildbot-config', 13:46:29 INFO - 'download-and-extract', 13:46:29 INFO - 'populate-webroot', 13:46:29 INFO - 'create-virtualenv', 13:46:29 INFO - 'install', 13:46:29 INFO - 'run-tests'), 13:46:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:46:29 INFO - 'download_minidump_stackwalk': True, 13:46:29 INFO - 'download_symbols': 'ondemand', 13:46:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:46:29 INFO - 'tooltool.py': '/tools/tooltool.py', 13:46:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:46:29 INFO - '/tools/misc-python/virtualenv.py')}, 13:46:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:46:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:46:29 INFO - 'gecko_profile': False, 13:46:29 INFO - 'gecko_profile_interval': 0, 13:46:29 INFO - 'installer_path': 'installer.exe', 13:46:29 INFO - 'log_level': 'info', 13:46:29 INFO - 'log_name': 'talos', 13:46:29 INFO - 'log_to_console': True, 13:46:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:46:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:46:29 INFO - 'opt_config_files': (), 13:46:29 INFO - 'pip_index': False, 13:46:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:46:29 INFO - 'cmd': ('bash', 13:46:29 INFO - '-c', 13:46:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:46:29 INFO - 'enabled': True, 13:46:29 INFO - 'halt_on_failure': False, 13:46:29 INFO - 'name': 'check_screen_resolution'},), 13:46:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:46:29 INFO - 'cmd': ('bash', 13:46:29 INFO - '-c', 13:46:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:46:29 INFO - 'enabled': True, 13:46:29 INFO - 'halt_on_failure': False, 13:46:29 INFO - 'name': 'check_screen_resolution'},), 13:46:29 INFO - 'run_cmd_checks_enabled': True, 13:46:29 INFO - 'suite': 'dromaeojs-e10s', 13:46:29 INFO - 'system_bits': '32', 13:46:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:46:29 INFO - 'title': 't-yosemite-r7-0379', 13:46:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:46:29 INFO - 'use_talos_json': True, 13:46:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:46:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:46:29 INFO - 'work_dir': 'build'} 13:46:29 INFO - [mozharness: 2017-03-20 20:46:29.510354Z] Running clobber step. 13:46:29 INFO - Running pre-action listener: _resource_record_pre_action 13:46:29 INFO - Running main action method: clobber 13:46:29 INFO - rmtree: /builds/slave/test/build 13:46:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:46:31 INFO - Running post-action listener: _resource_record_post_action 13:46:31 INFO - [mozharness: 2017-03-20 20:46:31.877368Z] Finished clobber step (success) 13:46:31 INFO - [mozharness: 2017-03-20 20:46:31.877502Z] Running read-buildbot-config step. 13:46:31 INFO - Running pre-action listener: _resource_record_pre_action 13:46:31 INFO - Running main action method: read_buildbot_config 13:46:31 INFO - Using buildbot properties: 13:46:31 INFO - { 13:46:31 INFO - "project": "", 13:46:31 INFO - "product": "firefox", 13:46:31 INFO - "script_repo_revision": "production", 13:46:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:46:31 INFO - "repository": "", 13:46:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:46:31 INFO - "buildid": "20170320124223", 13:46:31 INFO - "pgo_build": "False", 13:46:31 INFO - "basedir": "/builds/slave/test", 13:46:31 INFO - "buildnumber": 20, 13:46:31 INFO - "slavename": "t-yosemite-r7-0379", 13:46:31 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 13:46:31 INFO - "platform": "yosemite_r7", 13:46:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:46:31 INFO - "branch": "mozilla-aurora", 13:46:31 INFO - "repo_path": "releases/mozilla-aurora", 13:46:31 INFO - "revision": "0496e366a8f4d1393a8caba1db5f6d1f49da468d", 13:46:31 INFO - "stage_platform": "macosx64", 13:46:31 INFO - "builduid": "cfbae3e14cfd43f5912b55b3753279c8", 13:46:31 INFO - "slavebuilddir": "test" 13:46:31 INFO - } 13:46:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 13:46:31 INFO - Running post-action listener: _resource_record_post_action 13:46:31 INFO - [mozharness: 2017-03-20 20:46:31.880767Z] Finished read-buildbot-config step (success) 13:46:31 INFO - [mozharness: 2017-03-20 20:46:31.880896Z] Running download-and-extract step. 13:46:31 INFO - Running pre-action listener: _resource_record_pre_action 13:46:31 INFO - Running main action method: download_and_extract 13:46:31 INFO - mkdir: /builds/slave/test/build/tests 13:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:46:31 INFO - trying https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 13:46:31 INFO - Downloading https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 13:46:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 13:46:32 INFO - Downloaded 1199 bytes. 13:46:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 13:46:32 INFO - Using the following test package requirements: 13:46:32 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 13:46:32 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:46:32 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 13:46:32 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:46:32 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 13:46:32 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:46:32 INFO - u'jsshell-mac.zip'], 13:46:32 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:46:32 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 13:46:32 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 13:46:32 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:46:32 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 13:46:32 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:46:32 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 13:46:32 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:46:32 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 13:46:32 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:46:32 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 13:46:32 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:46:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 13:46:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:46:32 INFO - Fetch https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 13:46:34 INFO - Expected file size: 51781921 13:46:34 INFO - Obtained file size: 51781921 13:46:50 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:46:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 13:46:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:46:50 INFO - Fetch https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 13:46:52 INFO - Expected file size: 51781921 13:46:52 INFO - Obtained file size: 51781921 13:47:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 13:47:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:47:07 INFO - Fetch https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 13:47:07 INFO - Expected file size: 14404438 13:47:07 INFO - Obtained file size: 14404438 13:47: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')]} 13:47:08 INFO - trying https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:47:08 INFO - Downloading https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 13:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 13:47:10 INFO - Downloaded 74683382 bytes. 13:47:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:47:10 INFO - mkdir: /builds/slave/test/properties 13:47:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:47:10 INFO - Writing to file /builds/slave/test/properties/build_url 13:47:10 INFO - Contents: 13:47:10 INFO - build_url:https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:47:11 INFO - Running post-action listener: _resource_record_post_action 13:47:11 INFO - Running post-action listener: set_extra_try_arguments 13:47:11 INFO - [mozharness: 2017-03-20 20:47:11.392787Z] Finished download-and-extract step (success) 13:47:11 INFO - [mozharness: 2017-03-20 20:47:11.392914Z] Running populate-webroot step. 13:47:11 INFO - Running pre-action listener: _resource_record_pre_action 13:47:11 INFO - Running main action method: populate_webroot 13:47:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:47:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:47:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:47:11 INFO - '--tppagecycles', 13:47:11 INFO - '7'], 13:47:11 INFO - 'tests': ['tsvgm']}}, 13:47:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:47:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:47:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:47:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:47:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:47:11 INFO - 'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:47:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:47:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:47:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:47:11 INFO - 'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['damp', 'tps']}, 13:47:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:47:11 INFO - 'tests': ['damp', 'tps']}, 13:47:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['dromaeo_dom']}, 13:47:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:47:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['basic_compositor_video']}, 13:47:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:47:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['a11yr', 13:47:11 INFO - 'ts_paint', 13:47:11 INFO - 'tpaint', 13:47:11 INFO - 'sessionrestore', 13:47:11 INFO - 'sessionrestore_no_auto_restore', 13:47:11 INFO - 'tabpaint']}, 13:47:11 INFO - 'other-e10s': {'tests': ['a11yr', 13:47:11 INFO - 'ts_paint', 13:47:11 INFO - 'tpaint', 13:47:11 INFO - 'sessionrestore', 13:47:11 INFO - 'sessionrestore_no_auto_restore', 13:47:11 INFO - 'tabpaint']}, 13:47:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:47:11 INFO - 'ts_paint', 13:47:11 INFO - 'tpaint', 13:47:11 INFO - 'sessionrestore', 13:47:11 INFO - 'sessionrestore_no_auto_restore', 13:47:11 INFO - 'tabpaint']}, 13:47:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:47:11 INFO - 'ts_paint', 13:47:11 INFO - 'tpaint', 13:47:11 INFO - 'sessionrestore', 13:47:11 INFO - 'sessionrestore_no_auto_restore', 13:47:11 INFO - 'tabpaint']}, 13:47:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['a11yr', 13:47:11 INFO - 'ts_paint', 13:47:11 INFO - 'tpaint', 13:47:11 INFO - 'sessionrestore', 13:47:11 INFO - 'sessionrestore_no_auto_restore', 13:47:11 INFO - 'tabpaint']}, 13:47:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['a11yr', 13:47:11 INFO - 'ts_paint', 13:47:11 INFO - 'tpaint', 13:47:11 INFO - 'sessionrestore', 13:47:11 INFO - 'sessionrestore_no_auto_restore', 13:47:11 INFO - 'tabpaint']}, 13:47:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['tsvgx', 13:47:11 INFO - 'tsvgr_opacity', 13:47:11 INFO - 'tart', 13:47:11 INFO - 'tscrollx', 13:47:11 INFO - 'cart', 13:47:11 INFO - 'tsvg_static']}, 13:47:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:47:11 INFO - 'tsvgr_opacity', 13:47:11 INFO - 'tart', 13:47:11 INFO - 'tscrollx', 13:47:11 INFO - 'cart', 13:47:11 INFO - 'tsvg_static']}, 13:47:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:47:11 INFO - 'talos_options': ['--disable-e10s'], 13:47:11 INFO - 'tests': ['tp5o']}, 13:47:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:47:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:47:11 INFO - 'talos_options': ['--disable-e10s', 13:47:11 INFO - '--xperf_path', 13:47:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:47:11 INFO - 'tests': ['tp5n']}, 13:47:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:47:11 INFO - 'talos_options': ['--xperf_path', 13:47:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:47:11 INFO - 'tests': ['tp5n']}}, 13:47:11 INFO - 'talos.zip': {'path': '', 13:47:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:47:11 INFO - Running post-action listener: _resource_record_post_action 13:47:11 INFO - [mozharness: 2017-03-20 20:47:11.411734Z] Finished populate-webroot step (success) 13:47:11 INFO - [mozharness: 2017-03-20 20:47:11.411868Z] Running create-virtualenv step. 13:47:11 INFO - Running pre-action listener: _resource_record_pre_action 13:47:11 INFO - Running main action method: create_virtualenv 13:47:11 INFO - Creating virtualenv /builds/slave/test/build/venv 13:47:11 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 13:47:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:47:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:47:11 INFO - Using real prefix '/tools/python27' 13:47:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:47:12 INFO - Installing distribute.............................................................................................................................................................................................done. 13:47:15 INFO - Installing pip.................done. 13:47:15 INFO - Return code: 0 13:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:47:15 INFO - Reading from file tmpfile_stdout 13:47:15 INFO - Output received: 13:47:15 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) 13:47:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:47:15 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')]} 13:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:15 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')]} 13:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:15 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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:15 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 13:47:15 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 13:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:15 INFO - 'HOME': '/Users/cltbld', 13:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:15 INFO - 'LOGNAME': 'cltbld', 13:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:15 INFO - 'MOZ_NO_REMOTE': '1', 13:47:15 INFO - 'NO_EM_RESTART': '1', 13:47:15 INFO - 'PAGER': '/bin/cat', 13:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:15 INFO - 'PWD': '/builds/slave/test', 13:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:15 INFO - 'SHELL': '/bin/bash', 13:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:15 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:15 INFO - 'USER': 'cltbld', 13:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:15 INFO - 'XPC_FLAGS': '0x0', 13:47:15 INFO - 'XPC_SERVICE_NAME': '0', 13:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:15 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 13:47:15 INFO - Cleaning up... 13:47:15 INFO - Return code: 0 13:47:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:47:15 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')]} 13:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:15 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')]} 13:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:15 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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:15 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 13:47:15 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 13:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:15 INFO - 'HOME': '/Users/cltbld', 13:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:15 INFO - 'LOGNAME': 'cltbld', 13:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:15 INFO - 'MOZ_NO_REMOTE': '1', 13:47:15 INFO - 'NO_EM_RESTART': '1', 13:47:15 INFO - 'PAGER': '/bin/cat', 13:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:15 INFO - 'PWD': '/builds/slave/test', 13:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:15 INFO - 'SHELL': '/bin/bash', 13:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:15 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:15 INFO - 'USER': 'cltbld', 13:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:15 INFO - 'XPC_FLAGS': '0x0', 13:47:15 INFO - 'XPC_SERVICE_NAME': '0', 13:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:15 INFO - Downloading/unpacking psutil>=3.1.1 13:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:47:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:47:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:47:19 INFO - Installing collected packages: psutil 13:47:19 INFO - Running setup.py install for psutil 13:47:19 INFO - building 'psutil._psutil_osx' extension 13:47:19 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 13:47:20 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 13:47:20 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 13:47:20 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 13:47:20 INFO - building 'psutil._psutil_posix' extension 13:47:20 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 13:47:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:47:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:47:20 INFO - ^ 13:47:20 INFO - 1 warning generated. 13:47:20 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 13:47:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:47:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:47:20 INFO - Successfully installed psutil 13:47:20 INFO - Cleaning up... 13:47:20 INFO - Return code: 0 13:47:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:47:20 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')]} 13:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:20 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')]} 13:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:47:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:21 INFO - 'HOME': '/Users/cltbld', 13:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:21 INFO - 'LOGNAME': 'cltbld', 13:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:21 INFO - 'MOZ_NO_REMOTE': '1', 13:47:21 INFO - 'NO_EM_RESTART': '1', 13:47:21 INFO - 'PAGER': '/bin/cat', 13:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:21 INFO - 'PWD': '/builds/slave/test', 13:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:21 INFO - 'SHELL': '/bin/bash', 13:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:21 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:21 INFO - 'USER': 'cltbld', 13:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:21 INFO - 'XPC_FLAGS': '0x0', 13:47:21 INFO - 'XPC_SERVICE_NAME': '0', 13:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:47:21 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 13:47:21 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 13:47:21 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 13:47:21 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 13:47:21 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 13:47:21 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 13:47:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:47:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:47:24 INFO - Installing collected packages: mozsystemmonitor 13:47:24 INFO - Running setup.py install for mozsystemmonitor 13:47:24 INFO - Successfully installed mozsystemmonitor 13:47:24 INFO - Cleaning up... 13:47:24 INFO - Return code: 0 13:47:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:47:24 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')]} 13:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:24 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')]} 13:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:24 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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:24 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 13:47:24 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 13:47:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:24 INFO - 'HOME': '/Users/cltbld', 13:47:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:24 INFO - 'LOGNAME': 'cltbld', 13:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:24 INFO - 'MOZ_NO_REMOTE': '1', 13:47:24 INFO - 'NO_EM_RESTART': '1', 13:47:24 INFO - 'PAGER': '/bin/cat', 13:47:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:24 INFO - 'PWD': '/builds/slave/test', 13:47:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:24 INFO - 'SHELL': '/bin/bash', 13:47:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:24 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:24 INFO - 'USER': 'cltbld', 13:47:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:24 INFO - 'XPC_FLAGS': '0x0', 13:47:24 INFO - 'XPC_SERVICE_NAME': '0', 13:47:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:25 INFO - Downloading/unpacking jsonschema==2.5.1 13:47:25 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 13:47:25 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 13:47:25 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 13:47:25 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 13:47:25 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 13:47:25 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 13:47:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:47:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:28 INFO - Downloading functools32-3.2.3-2.tar.gz 13:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:47:28 INFO - warning: no files found matching '*.txt' 13:47:28 INFO - no previously-included directories found matching 'build' 13:47:28 INFO - no previously-included directories found matching 'dist' 13:47:28 INFO - no previously-included directories found matching '.git*' 13:47:28 INFO - Installing collected packages: jsonschema, functools32 13:47:28 INFO - Running setup.py install for functools32 13:47:28 INFO - warning: no files found matching '*.txt' 13:47:28 INFO - no previously-included directories found matching 'build' 13:47:28 INFO - no previously-included directories found matching 'dist' 13:47:28 INFO - no previously-included directories found matching '.git*' 13:47:28 INFO - Successfully installed jsonschema functools32 13:47:28 INFO - Cleaning up... 13:47:28 INFO - Return code: 0 13:47:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:28 INFO - 'HOME': '/Users/cltbld', 13:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:28 INFO - 'LOGNAME': 'cltbld', 13:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:28 INFO - 'MOZ_NO_REMOTE': '1', 13:47:28 INFO - 'NO_EM_RESTART': '1', 13:47:28 INFO - 'PAGER': '/bin/cat', 13:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:28 INFO - 'PWD': '/builds/slave/test', 13:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:28 INFO - 'SHELL': '/bin/bash', 13:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:28 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:28 INFO - 'USER': 'cltbld', 13:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:28 INFO - 'XPC_FLAGS': '0x0', 13:47:28 INFO - 'XPC_SERVICE_NAME': '0', 13:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:47:28 INFO - Cleaning up... 13:47:28 INFO - Return code: 0 13:47:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:28 INFO - 'HOME': '/Users/cltbld', 13:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:28 INFO - 'LOGNAME': 'cltbld', 13:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:28 INFO - 'MOZ_NO_REMOTE': '1', 13:47:28 INFO - 'NO_EM_RESTART': '1', 13:47:28 INFO - 'PAGER': '/bin/cat', 13:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:28 INFO - 'PWD': '/builds/slave/test', 13:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:28 INFO - 'SHELL': '/bin/bash', 13:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:28 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:28 INFO - 'USER': 'cltbld', 13:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:28 INFO - 'XPC_FLAGS': '0x0', 13:47:28 INFO - 'XPC_SERVICE_NAME': '0', 13:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:29 INFO - Downloading/unpacking blobuploader==1.2.4 13:47:29 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 13:47:29 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 13:47:29 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 13:47:29 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 13:47:29 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 13:47:29 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 13:47:31 INFO - Downloading blobuploader-1.2.4.tar.gz 13:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:47:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:47:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:47:32 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 13:47:32 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 13:47:32 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 13:47:32 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 13:47:32 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 13:47:32 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 13:47:32 INFO - Downloading docopt-0.6.1.tar.gz 13:47:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:47:33 INFO - Installing collected packages: blobuploader, requests, docopt 13:47:33 INFO - Running setup.py install for blobuploader 13:47:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:47:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:47:33 INFO - Running setup.py install for requests 13:47:33 INFO - Running setup.py install for docopt 13:47:33 INFO - Successfully installed blobuploader requests docopt 13:47:33 INFO - Cleaning up... 13:47:33 INFO - Return code: 0 13:47:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47: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')]} 13:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47: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')]} 13:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:33 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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:33 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 13:47:33 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 13:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:33 INFO - 'HOME': '/Users/cltbld', 13:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:33 INFO - 'LOGNAME': 'cltbld', 13:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:33 INFO - 'MOZ_NO_REMOTE': '1', 13:47:33 INFO - 'NO_EM_RESTART': '1', 13:47:33 INFO - 'PAGER': '/bin/cat', 13:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:33 INFO - 'PWD': '/builds/slave/test', 13:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:33 INFO - 'SHELL': '/bin/bash', 13:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:33 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:33 INFO - 'USER': 'cltbld', 13:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:33 INFO - 'XPC_FLAGS': '0x0', 13:47:33 INFO - 'XPC_SERVICE_NAME': '0', 13:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-FSnVNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-CZIqMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-v1bouQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-jcGWaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-jkbuYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-0ORzOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-hGEq_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-b7iitk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:47:34 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ak3YPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:47:35 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-LL_0Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:47:35 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-nP0odZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:47:35 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-eCYFeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:47:35 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-cMjxdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:47:35 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-TJ1lrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:35 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Q4POB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:47:35 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-8i8xCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:47:35 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-kq6pkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:47:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:47:35 INFO - Running setup.py install for manifestparser 13:47:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:47:36 INFO - Running setup.py install for mozcrash 13:47:36 INFO - Running setup.py install for mozdebug 13:47:36 INFO - Running setup.py install for mozdevice 13:47:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:47:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:47:36 INFO - Running setup.py install for mozfile 13:47:36 INFO - Running setup.py install for mozhttpd 13:47:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:47:36 INFO - Running setup.py install for mozinfo 13:47:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:47:36 INFO - Running setup.py install for mozInstall 13:47:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Running setup.py install for mozleak 13:47:37 INFO - Running setup.py install for mozlog 13:47:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Running setup.py install for moznetwork 13:47:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Running setup.py install for mozprocess 13:47:37 INFO - Running setup.py install for mozprofile 13:47:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:47:37 INFO - Running setup.py install for mozrunner 13:47:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:47:38 INFO - Running setup.py install for mozscreenshot 13:47:38 INFO - Running setup.py install for moztest 13:47:38 INFO - Running setup.py install for mozversion 13:47:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:47:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:47:38 INFO - Cleaning up... 13:47:38 INFO - Return code: 0 13:47:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47: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')]} 13:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47: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')]} 13:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:38 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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:38 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 13:47:38 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 13:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:38 INFO - 'HOME': '/Users/cltbld', 13:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:38 INFO - 'LOGNAME': 'cltbld', 13:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:38 INFO - 'MOZ_NO_REMOTE': '1', 13:47:38 INFO - 'NO_EM_RESTART': '1', 13:47:38 INFO - 'PAGER': '/bin/cat', 13:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:38 INFO - 'PWD': '/builds/slave/test', 13:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:38 INFO - 'SHELL': '/bin/bash', 13:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:38 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:38 INFO - 'USER': 'cltbld', 13:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:38 INFO - 'XPC_FLAGS': '0x0', 13:47:38 INFO - 'XPC_SERVICE_NAME': '0', 13:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:47:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-NPDriV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:47:38 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)) 13:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:47:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-tEvLnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:47:38 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)) 13:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:47:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-lIbalc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-2iREUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-jpygW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-5YKGSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ALgnyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-q0xKRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-CZHbrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-b5cXA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-9D29SZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:47:39 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)) 13:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:47:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-8xo9Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:47:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:47:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vWO5Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:47:40 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)) 13:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:47:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-kGNWvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:47:40 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)) 13:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vxllAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:40 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)) 13:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:47:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-PClF7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:47:40 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)) 13:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:47:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-XmSicw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:47:40 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)) 13:47:40 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)) 13:47:40 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)) 13:47:40 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)) 13:47:40 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)) 13:47:40 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)) 13:47:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:47:40 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 13:47:40 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 13:47:40 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 13:47:40 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 13:47:40 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 13:47:40 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 13:47:43 INFO - Downloading blessings-1.6.tar.gz 13:47:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:47:43 INFO - Installing collected packages: blessings 13:47:43 INFO - Running setup.py install for blessings 13:47:43 INFO - Successfully installed blessings 13:47:43 INFO - Cleaning up... 13:47:43 INFO - Return code: 0 13:47:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:47:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:47:43 INFO - Reading from file tmpfile_stdout 13:47:43 INFO - Current package versions: 13:47:43 INFO - blessings == 1.6 13:47:43 INFO - blobuploader == 1.2.4 13:47:43 INFO - docopt == 0.6.1 13:47:43 INFO - functools32 == 3.2.3-2 13:47:43 INFO - jsonschema == 2.5.1 13:47:43 INFO - manifestparser == 1.1 13:47:43 INFO - mozInstall == 1.12 13:47:43 INFO - mozcrash == 1.0 13:47:43 INFO - mozdebug == 0.1 13:47:43 INFO - mozdevice == 0.49 13:47:43 INFO - mozfile == 1.2 13:47:43 INFO - mozhttpd == 0.7 13:47:43 INFO - mozinfo == 0.9 13:47:43 INFO - mozleak == 0.1 13:47:43 INFO - mozlog == 3.4 13:47:43 INFO - moznetwork == 0.27 13:47:43 INFO - mozprocess == 0.25 13:47:43 INFO - mozprofile == 0.28 13:47:43 INFO - mozrunner == 6.13 13:47:43 INFO - mozscreenshot == 0.1 13:47:43 INFO - mozsystemmonitor == 0.3 13:47:43 INFO - moztest == 0.8 13:47:43 INFO - mozversion == 1.4 13:47:43 INFO - psutil == 3.1.1 13:47:43 INFO - requests == 1.2.3 13:47:43 INFO - wsgiref == 0.1.2 13:47:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47:43 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')]} 13:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:43 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')]} 13:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:43 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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:43 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 13:47:43 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 13:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:43 INFO - 'HOME': '/Users/cltbld', 13:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:43 INFO - 'LOGNAME': 'cltbld', 13:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:43 INFO - 'MOZ_NO_REMOTE': '1', 13:47:43 INFO - 'NO_EM_RESTART': '1', 13:47:43 INFO - 'PAGER': '/bin/cat', 13:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:43 INFO - 'PWD': '/builds/slave/test', 13:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:43 INFO - 'SHELL': '/bin/bash', 13:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:43 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:43 INFO - 'USER': 'cltbld', 13:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:43 INFO - 'XPC_FLAGS': '0x0', 13:47:43 INFO - 'XPC_SERVICE_NAME': '0', 13:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 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)) 13:47:44 INFO - Cleaning up... 13:47:44 INFO - Return code: 0 13:47:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:44 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 0x1058f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058f6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c38585cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, '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.FqFuY9s1N4/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.YTGsqysE28/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 13:47:44 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 13:47:44 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 13:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:44 INFO - 'HOME': '/Users/cltbld', 13:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:44 INFO - 'LOGNAME': 'cltbld', 13:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:44 INFO - 'MOZ_NO_REMOTE': '1', 13:47:44 INFO - 'NO_EM_RESTART': '1', 13:47:44 INFO - 'PAGER': '/bin/cat', 13:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:44 INFO - 'PWD': '/builds/slave/test', 13:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:44 INFO - 'SHELL': '/bin/bash', 13:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:47:44 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:44 INFO - 'USER': 'cltbld', 13:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:44 INFO - 'XPC_FLAGS': '0x0', 13:47:44 INFO - 'XPC_SERVICE_NAME': '0', 13:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:47:44 INFO - Cleaning up... 13:47:44 INFO - Return code: 0 13:47:44 INFO - Running post-action listener: _resource_record_post_action 13:47:44 INFO - Running post-action listener: _start_resource_monitoring 13:47:44 INFO - Starting resource monitoring. 13:47:44 INFO - [mozharness: 2017-03-20 20:47:44.476606Z] Finished create-virtualenv step (success) 13:47:44 INFO - [mozharness: 2017-03-20 20:47:44.477591Z] Running install step. 13:47:44 INFO - Running pre-action listener: _resource_record_pre_action 13:47:44 INFO - Running main action method: install 13:47:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:47:44 INFO - Reading from file tmpfile_stdout 13:47:44 INFO - Detecting whether we're running mozinstall >=1.0... 13:47:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:47:44 INFO - Reading from file tmpfile_stdout 13:47:44 INFO - Output received: 13:47:44 INFO - Usage: mozinstall [options] installer 13:47:44 INFO - Options: 13:47:44 INFO - -h, --help show this help message and exit 13:47:44 INFO - -d DEST, --destination=DEST 13:47:44 INFO - Directory to install application into. [default: 13:47:44 INFO - "/builds/slave/test"] 13:47:44 INFO - --app=APP Application being installed. [default: firefox] 13:47:44 INFO - mkdir: /builds/slave/test/build/application 13:47:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:48:04 INFO - Reading from file tmpfile_stdout 13:48:04 INFO - Output received: 13:48:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:48:04 INFO - Running post-action listener: _resource_record_post_action 13:48:04 INFO - [mozharness: 2017-03-20 20:48:04.901582Z] Finished install step (success) 13:48:04 INFO - [mozharness: 2017-03-20 20:48:04.901734Z] Running run-tests step. 13:48:04 INFO - Running pre-action listener: _resource_record_pre_action 13:48:04 INFO - Running main action method: run_tests 13:48:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:48:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:48:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:48:04 INFO - 2017-03-20 13:48:04.915 screenresolution[1628:7572] starting screenresolution argv=screenresolution get 13:48:04 INFO - 2017-03-20 13:48:04.918 screenresolution[1628:7572] Display 0: 1600x1200x32@60 13:48:04 INFO - 2017-03-20 13:48:04.924 screenresolution[1629:7574] starting screenresolution argv=screenresolution list 13:48:04 INFO - Available Modes on Display 0 13:48:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:48:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:48:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:48:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:48:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:48:05 INFO - Intel Iris: 13:48:05 INFO - Chipset Model: Intel Iris 13:48:05 INFO - Type: GPU 13:48:05 INFO - Bus: Built-In 13:48:05 INFO - VRAM (Dynamic, Max): 1536 MB 13:48:05 INFO - Vendor: Intel (0x8086) 13:48:05 INFO - Device ID: 0x0a2e 13:48:05 INFO - Revision ID: 0x0009 13:48:05 INFO - Displays: 13:48:05 INFO - Display: 13:48:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:48:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:48:05 INFO - Display Serial Number: 1600x1200 60 13:48:05 INFO - Main Display: Yes 13:48:05 INFO - Mirror: Off 13:48:05 INFO - Online: Yes 13:48:05 INFO - Rotation: Supported 13:48:05 INFO - Return code: 0 13:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:48:05 INFO - Python 2.7.3 13:48:05 INFO - Return code: 0 13:48:05 INFO - grabbing minidump binary from tooltool 13:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:48:05 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 0x1056d1d30>, '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 0x1055f8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:48:05 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 13:48:05 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 13:48:05 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 13:48:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:48:05 INFO - Return code: 0 13:48:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:48:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:48:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:48:05 INFO - ENV: RUST_BACKTRACE is now 1 13:48:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:48:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 13:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0379 --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 13:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqFuY9s1N4/Render', 13:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:05 INFO - 'HOME': '/Users/cltbld', 13:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:05 INFO - 'LOGNAME': 'cltbld', 13:48:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:48:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:05 INFO - 'MOZ_NO_REMOTE': '1', 13:48:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:48:05 INFO - 'NO_EM_RESTART': '1', 13:48:05 INFO - 'PAGER': '/bin/cat', 13:48:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:05 INFO - 'PWD': '/builds/slave/test', 13:48:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:05 INFO - 'RUST_BACKTRACE': '1', 13:48:05 INFO - 'SHELL': '/bin/bash', 13:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YTGsqysE28/Listeners', 13:48:05 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 13:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:05 INFO - 'USER': 'cltbld', 13:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:05 INFO - 'XPC_FLAGS': '0x0', 13:48:05 INFO - 'XPC_SERVICE_NAME': '0', 13:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 13:48:05 INFO - mozversion application_buildid: 20170320124223 13:48:05 INFO - mozversion application_changeset: 0496e366a8f4d1393a8caba1db5f6d1f49da468d 13:48:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:48:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:48:05 INFO - mozversion application_name: Firefox 13:48:05 INFO - mozversion application_remotingname: firefox-dev 13:48:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:48:05 INFO - mozversion application_vendor: Mozilla 13:48:05 INFO - mozversion application_version: 54.0a2 13:48:05 INFO - mozversion platform_buildid: 20170320124223 13:48:05 INFO - mozversion platform_changeset: 0496e366a8f4d1393a8caba1db5f6d1f49da468d 13:48:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:48:05 INFO - mozversion platform_version: 54.0a2 13:48:05 INFO - using testdate: 1490042885 13:48:05 INFO - actual date: 1490042885 13:48:05 INFO - starting webserver on 'localhost:49245' 13:48:05 INFO - SUITE-START | Running 2 tests 13:48:05 INFO - TEST-START | dromaeo_css 13:48:05 INFO - operating with platform_type : mac_ 13:48:05 INFO - Initialising browser for dromaeo_css test... 13:48:05 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpWfoush/profile http://localhost:49245/getInfo.html) 13:48:08 INFO - PROCESS | 1639 | 2017-03-20 13:48:08.288 plugin-container[1641:7751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3b, name = 'com.apple.tsm.portname' 13:48:08 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:48:08 INFO - PROCESS | 1639 | 2017-03-20 13:48:08.292 plugin-container[1641:7751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 13:48:08 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:48:08 INFO - PROCESS | 1639 | 2017-03-20 13:48:08.292 plugin-container[1641:7751] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:48:08 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 13:48:08 INFO - PROCESS | 1639 | colorDepth:24 13:48:08 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 13:48:08 INFO - PROCESS | 1639 | __metrics 13:48:08 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 13:48:09 INFO - TEST-INFO | 1639: exit 0 13:48:09 INFO - Browser initialized. 13:48:09 INFO - Running cycle 1/1 for dromaeo_css test... 13:48:09 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpWfoush/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:48:11 INFO - PROCESS | 1642 | 2017-03-20 13:48:11.679 plugin-container[1643:7877] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 13:48:11 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:48:11 INFO - PROCESS | 1642 | 2017-03-20 13:48:11.679 plugin-container[1643:7877] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 13:48:11 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:48:11 INFO - PROCESS | 1642 | 2017-03-20 13:48:11.679 plugin-container[1643:7877] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:48:21 INFO - PROCESS | 1642 | RSS: Main: 220590080 13:48:21 INFO - PROCESS | 1642 | 13:51:24 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 13:51:26 INFO - PROCESS | 1642 | RSS: Main: 241471488 13:51:26 INFO - PROCESS | 1642 | 13:53:09 INFO - PROCESS | 1642 | ************************* 13:53:09 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 13:53:09 INFO - PROCESS | 1642 | 13:53:09 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:53:09 INFO - PROCESS | 1642 | Full stack: 13:53:09 INFO - PROCESS | 1642 | ************************* 13:54:28 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 13:54:29 INFO - PROCESS | 1642 | RSS: Main: 248377344 13:54:29 INFO - PROCESS | 1642 | 13:57:31 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 13:57:32 INFO - PROCESS | 1642 | RSS: Main: 257032192 13:57:32 INFO - PROCESS | 1642 | 14:00:34 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 14:00:34 INFO - PROCESS | 1642 | RSS: Main: 254009344 14:00:34 INFO - PROCESS | 1642 | 14:03:37 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 14:03:38 INFO - PROCESS | 1642 | RSS: Main: 259207168 14:03:38 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 14:06:41 INFO - PROCESS | 1642 | RSS: Main: 255361024 14:06:41 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | __start_tp_report 14:06:41 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 14:06:41 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 14:06:41 INFO - PROCESS | 1642 | |i|pagename|runs| 14:06:41 INFO - PROCESS | 1642 | |0;dojo.html;3375.5611770469836;3447.362237985971;3460.9307813843725;3462.740294477914;3466.7573269871104;5932.436418540243;5933.7626494940205;5990.251218597676;6011;6011.939880601192;5961.195238642782;6017.669028203447;6027.09593560965;6034.215551978247;6054.8183554493435;5371.75827087781;5429.968306021863;5430.56555475563;5435.673859568419;5562.3047119110115;5469.945300547003;5479.150731636602;5479.780808767645;5492.752826122814;5510.586705997066;5266.499682530172;5276.736163191844;5475.561955043607;5499.312585926759;5513.641613295143;5390.6226564140525;5392.622516423852;5413.891722165555;5444.5916556258435;5461.208124821918;5286.444923283077;5401.648892821954;5404.081306177959;5415.593830462731;5439.891202175934;5648.096304591246;5970.97014514929;6005.1892994445625;6061.363556826514;6082.5742198046155;6246.906296405512;6265.843353916189;6270.65511396873;6290.150829637986;6315.210598675166;6378.904316435257;6393.808185754435;6427.93572064278;6435.646039467827;6436.131122298477;6072.271327440735;6096.207493025879;6123.173371594867;6124.203853499017;6146.907796383058;6230.937690623126;6268.028455589391;6399.264084630234;6491.1886014247275;6500.057491663682;6310.432061114522;6330.905036424458;6353.872922541524;6366.045093235959;6366.267879193952;6361.363863613694;6391.424771770563;6391.904121438182;6397.26431460388;6397.712102955378;6203.100550420164;6211.627302361872;6235.407636274546;6340.904886426707;6352.36476352361;6201.56589038772;6218.533609979269;6278.183836101278;6289.0252010938375;6300.59046161998;4200.663946884303;5091;5134.589232861362;5143.537081662594;5152.149895267314;4540.909181816345;4552.476465206543;4553.567411095941;4559.544045595414;4604;5374.978754036721;5885.499732522698;6112.633242005405;6216.409441103088;6251.718672659741;5364.249005139283;6260.154879091356;6286.402791734778;6319.241690997018;6332.208473940757;6197.597156184834;6206.100115483229;6208.72060757267;6216.2540495140875;6218.973869311603;5678.4605462480995;5684.573656975744;5693.572982026365;5695.345035320908;5706.172604972338;6271;6303.180586523906;6493.642849643126;6535.869282614226;6653.500987425962;6349.0476428536085;6426.453751430994;6427.1002059710745;6437.130987316652;6479.222493300834;6618.07346971434;6669.2997235289595;6670.466362690876;6686.3313668632745;6730.461563074844;6118.345080237214;6242.438180563771;6273.71768270438;6379.138816259746;6490.772822951174;6226.719797609027;6318.747250109944;6332.050192471166;6384.840378990377;6400.039994000937;6295.276043255084;6307.211598550181;6308.558400911892;6308.747650094128;6316.210473690789;6130.295016073209;6152.138700581832;6264.279607844974;6267.592606480746;6338.112664227104;6105.084237364431;6118.479929206067;6163.445289923929;6186.876262474815;6234.563580549318;6289.339619339903;6306.842328941813;6316.14732011191;6327.430531252209;6327.462165715966;5958.106284057253;5963.821085367446;6107.219158344477;6113.841195312776;6261.68691565429 14:06:41 INFO - PROCESS | 1642 | |1;ext.html;750.470918002808;802.6227672993692;824.3034635732805;930.8324501589711;935.9953200233998;38378.46486140555;40341.59658403415;40453.19093618126;40469.3929591056;40474.78575642728;38058.619413805856;38314;38337;38385.808070959676;38466;7769.300762931335;7903.723369682033;10471.581136754521;10667;10863.402512861789;10567.418791966438;10601.04590586847;10661.253712240146;10669.506269122328;10679.893201067967;10641;10688;10710.732231694232;10710.892891071106;10728.946355268212;10592.15262778976;10610.151187904949;10622.946885265601;10644.733881652983;10646.733831654194;10405.635802746943;10474.371537707762;10481;10492.737681557946;10494.475276236233;33636.15909602255;33641;33651;33685.32629347424;33717.831410843035;3572.517710109128;3691.3909204981146;3716.224601744425;3717.8988132666746;3784.4701741756176;24933;25079;25347.112851050126;25590.23229303124;26476.8676156618;3064.69353064694;3071.9232019199476;3242.3191129862757;3261.4455542557585;3266.9673303267136;361145;361733;364421;365339.3466065332;365685;44939.77530112394;44977;45083;45085.323720144224;45291.773541132745;46044.769776151574;46355;46380;46490.53509464862;46511.534884651395;12861.099723019486;13003.544875929298;13023.348832558335;13066.0200484964;13134.211947283193;9665.468399237969;11051.17116216287;11083.390413527333;11103.777924441465;11106.777864442665;11334.829977550344;11753.4710938008;11859.88140118605;11943.940280298542;11976.76046479083;5621.690807005654;5694.259746232963;5697.173909783059;5720.170575266563;5749.8562535936935;753.2618034326281;875.6409871952469;894.2130924786277;903.2277402820494;905.6467977488785;22170.445738856335;26984.59523107155;27451.45097098047;27934.7429365679;28180.577291340647;809.9838003239902;969.7963427680266;997.6009596161593;1002.6240159940022;1030.8247597908373;13786.65533361654;14222.786658200135;14362.569122926328;14500.709985800226;14559;457374;458893.7055314702;461064.6946765312;462115;462568;41712.79143604262;42151.7892410536;42535;42564.787176063925;43746.781266093465;1242.9502819887284;1410.3935307817533;1413.4275618374575;1426.9072510286799;1439.2731670506537;29058.854705726335;29653.406931861675;29777.702222977492;30204.395912082076;30444;40034.39862405471;42949.570504294556;45115.87210319768;47100.52899470962;47322.816929577035;486489;488284;490530;492237;493250;29528.409431810815;29641.814327426662;29737.10788676343;29777;29832.70167298386;37432.81283593565;37537.87386378413;37658.05854853651;38030.61969380381;38506;31552.211194720316;31735.84132079325;31782.52326215155;31846.363072738877;32758.181045474055;31628.683713162573;31629.5255571162;32165;32243.838780805945;32493;31094.911678091157;31369.8431507841;31577;32016.67983320137;32069.358612827145;28089.157325280274;29040.41919161647;29202.707972919998;29236;29386.55920161242;21066.368008959755;22108.40887280068;22380.440488987904;22658.433539160993;22671.773282267626 14:06:41 INFO - PROCESS | 1642 | |2;jquery.html;391.8178047208049;417.45939008983385;417.6137073207286;428.79632174717005;451.88476938380705;19730.506737331554;20858.47853803654;21102.57794844104;21388.037538310775;21572.813495257753;19850.90074549629;20841;20861.165553377847;21370.572588548257;22029.559408811816;6394.808155755323;6613.371729685672;6647.268800431949;6668.199816022084;6669.599824010551;6372.362763723637;6499.967500162516;6539.378759017909;6567.277599464055;6577.079208910756;6448;6473.93526064738;6542.672866356687;6544.149260596116;6552.443042341407;6315.115883776275;6563.343665633399;6566.967165164144;6575.638339891304;6585.078089067534;6405;6412.262589802139;6614.669266536654;6958.130233720785;7085.46858985573;24286.271411857673;24617.876910615334;25279;25421.491570168677;25455.872720636464;2823.971760282412;2833.9433211335863;2867.5985362049328;2878.6977367376335;2955.852207389622;15174.013689110283;15469.845301546953;15720.449784257611;16147.677046459123;16231.269592868231;1985.7762083775588;1994.2222533211914;2018.7577490701062;2026.3313106674761;2037.4091513461078;130680.3465982664;151688;153518.23240883835;155372;160285;20290.188392464137;20306.289279875135;20678.896605517177;20864.791352086286;21217.363479095653;31334.963227390603;31522.896698615437;31625.525617115763;31722.365552688818;31842.363152736816;10116.140128089195;10118.08937195656;10205.081542661195;10323;10373.84439233412;7546.735864244725;8479.15208479147;8502.44734092282;8542.871856922027;8713.651453941771;7431;9233.215176710055;9273.72178834636;9337.626494940263;9363.110504502061;3471.531343268626;4134.607212314825;4186.58134186585;4224.2185195738275;4228.640565551963;854.5129276312442;855.1234984141181;869.9695510657097;875.9794839012479;902.5938327752538;15553.144577048144;18893.244270229035;20260.59478810445;21063.157473701187;21388.679169812465;920.0586761932317;921.530019689963;929.2287401456774;931.7111695374456;941.6892425499706;9086.772830679285;10439.060484556427;11065.004149626573;11086.279391839666;11337.43312834355;749761;756527;759746;760001;785163;112851;119092;125263.373683131;133289.3335533316;159189;1214.6416807041944;1230.2372529032057;1273.4969686973593;1277.7636137314441;1278.1628033637983;18845.528861778566;18967.43097707071;19390.4182874514;19446.8055319445;19643.508912277306;66006.66996665178;66323;66740.6662966682;68235.63528729496;68992;70445.64777176286;89006;89498.65752013856;90611.640825389;91774;11571.363575003119;11603.07175425947;11622.360770157722;11642.592509262475;11928.582499612472;11435.59975400857;12387.814182787446;13132.409041593342;13336.86663133395;13544.051916366154;10831.458427078456;11185.217034807485;11200.607978720705;11304.886951130713;11460.19778615489;9614.759631009001;11278.887211127783;11297.322160670386;11347.773044539228;11645.243059201124;11777.941110294394;11817.586384476503;12045.518179273082;12092.972097371821;12165.939170304091;11715.297082175097;11728.354940478355;11761.235519691192;11789;12122.030237581148;11203.60787372438;11293.661190164308;11483.540658373906;11512.309261444005;11727.648170554898 14:06:41 INFO - PROCESS | 1642 | |3;mootools.html;1445.4507287230851;1447.833499147598;1514.6667733098714;1546.3814474210321;1580.8577228049476;8243.546604936726;10568.365898046113;11217.719557011058;11305.773884522316;11338.319700817947;8283;10891.6732498025;11198.440077996109;11207.271527350716;11219.990200881919;2321.895514701834;2371.490129622131;2382.7140743110763;2383.7616238376195;2399.8560086394787;2347.647852822082;2350.5416443793465;2371.1463873005705;2378.310290015902;2384.046381447426;2331.988340058297;2349.1425629645228;2358.3396648938237;2358.6108292131776;2376.95246095078;2295.586794377028;2312.1329501436962;2315.8147348212187;2319.814414846808;2329.1382188590246;2285.48576570272;2323.5352929414016;2364.929052128439;2377.1323466934446;2381.5236952609375;9496.43021418717;11211.551537938472;11211.66365009043;11215.327080375204;11230.775384492346;5036.647434679574;5375.6774593524515;5557.7217240034615;5686.488216060575;5755.194272801811;3492.0396890854963;3517.6482351764876;3531.858725650971;3596.6403359664087;3603.1352475405974;1142.862856457222;1165.795985702507;1214.1015648420105;1232.8828761267666;1238.789405801016;12925.547605833845;13450;13565.050446468653;14163.433462661482;14219.50231741884;4567.634589232854;4706.623470122382;4718.504557021463;4728.692634978715;4729.219678753036;11563.42182890869;11683;11695.590654327229;11710.414479276002;11721.003714684177;5622;5631.4087020863035;5679.574031947538;5716.114002329646;5719.142128680648;4966.255061740696;5177.974110129425;5181.35233095863;5213.7393130343335;5223.059849227176;3181.7454603631654;3225.516172574086;3749.868754593631;3889.8913809564137;3913.5695073541888;998.5805961496189;1004.347174336687;1006.2000709436016;1007.9949600251952;1020.3061917895753;926.4805718621466;927.9257659387233;930.4928813796497;940.1303793990531;982.29274922056;5710.2576665033275;6659.9001014984815;6765.661716914135;6864.656767161622;6909.205441374206;883.9292856571459;990.5641517732175;1018.3024628129755;1024.109025148126;1041.047441590951;3914.269263605152;4046.008727861693;4455.22033644111;4542.613877820356;4592.563706447882;13551.86448135506;13813.24027178515;13968;13976.580702578736;14007.719845603235;13036.087473876738;13595.728085438235;13894.23581703016;13900.51348202808;13913.30433478322;8798.648054077894;10103.84844227337;10347.534360953776;10355.326903751233;10358.585656573652;5556.361018482926;5920.822375328747;6595.637239951657;6705.865882682221;6954.200266969363;11968.880311196777;12162.087843411602;12263.87736122663;12299.754004919672;12382.81425778632;11523.53905843757;11795.52817887275;12123;12581.496740130293;12737;9049.77375565616;9394.342396032485;9408.811823763623;9513.809723805347;9543.0456954304;10350.482475876026;10363.222758293008;10398.012188842047;10458.68623941283;10534.209934254808;8300.12848650883;8310.83378332442;8353.624086916225;8389.454685445426;8397.664093436439;8912.598933047899;9012.143846334588;9123.224525915371;9167.404118732262;9237.214836738953;8007.319377852948;8025.678972841021;8044.436889417684;8098.068722097034;8143.75568732939;5878.382769809108;5899.793507227227;5960.135780311917;6095.35998720128;6143.416375444325;5313.937212557581;5920.171176035273;6053.545984051126;6088.51291896656;6163.167972323679 14:06:41 INFO - PROCESS | 1642 | |4;prototype.html;324.8960332693536;367.5809577082125;384.2392063713846;388.1790014120135;388.88333499950016;4775.641826862986;5229.921551176735;5352.277442545265;5356.3036805215315;5373.9193912091305;4195.17509883201;4779.8805029874275;5226.242194881749;5235.55497782689;5242.21366794981;3508.877189298375;3861.046299580823;4176.561461046592;4363.912721745563;4369.781510924457;3662.963370366302;3898.3567711327737;4285.400043993844;4364.956350436487;4426.579474949891;4284.935725964113;4315.244832154376;4321.286987647034;4349.586384425059;4350.173467041268;3838.4818049563232;4064.3742502998743;4347.586979236968;4368.825246990133;4374.934375984363;3173.5239714042846;3344.5652065231607;3727.384981478053;4017.4375587417494;4067.9050289101137;3978.204359128157;4247.915041699149;4767.4994125617;4958.181899986498;5081.517255860687;4728.905421891577;4987.62592805541;5044.873878153039;5063.974680126613;5160.716160611165;5061.392632884077;5521.936459909222;5526.806561770359;5695.40198279183;5791.5656325775735;4003.7597744135446;4098.815553300109;4171.249175148472;4428.535003824616;4484.1255955088445;4965.477301887643;5049.697018178885;5412.5940554458175;5477.808276710336;5643.661380317194;3895.7857317847224;4017.794661601508;4343.4136391586735;4448.933266001012;4529.54704529551;3871.709621778378;4304.956950430518;4659.347691323217;4679.859604211879;4750.358701575312;4794.7362895040405;4957.504249575086;4964.652474326835;4976.1434887961395;5036.949630503721;4514.390557274726;5572.350110677513;5596.86007849807;5606.5331254248;5613.803516876889;1446.7902154187793;1519.2024187301533;1575.3698520591627;1588.1344667156427;1607.4695350534296;1092.4428541443763;1191.303087693709;1192.3859212505567;1196.9940150299192;1214.5688280660415;4470.441194850644;4569.680122391467;4657.115148121846;4840.70831834356;5299.330710826078;5827.76688932438;5830.504407125357;5836.533077353802;5843.678597677169;6018.6990650467305;4618.815247390066;4991.900161996739;5394.32570928634;5397.784088636489;5429.592780541475;2844.3787041386213;3077.615298087739;3088.313167544206;3105.208171916147;3112.891048813326;5333.493318134771;5486.561075113981;5701.743421546041;6070.2108725865355;6145.8156255312415;5113.335266415343;5428.67427954324;5839.649621022752;6000.549958753111;6035.909461358083;5436.70098144598;5554.583406244547;6108.266093777358;6132.63204207749;6177.598456100339;4961.305417241517;4981.5304485177685;5450;5640.984622767941;5873.213331135111;5349.839504814706;5461.071617825059;5626.268585084076;5752.223449834219;5776.884462310814;4363.738175709467;5739.474631162275;5855.238818953509;6012.849678757891;6041.879162416815;4080.000399902043;4101.46680931477;4520.683552151318;4537.773111344485;4544.7727613619845;5170.922436163385;5489.835304940858;5641.238432811518;5736.340320862987;5863.472287494146;4602.631789456768;5115.667481613683;5274.261603375454;5675.574331925205;5688.667646265011;5058.974705126451;5174.913268213567;5180.326557547494;5572.749226284908;5649.915251271316;4748.264019077049;5255.605829562722;5487.917681234864;5515.834524964257;5568.6658800472105;4922.040202160544;5273.894522109613;5310.566147140327;5599.664020158803;5624.578156638187;4873.561379475864;5469.835904922858;5487.780488780564;5503.394626591152;5518.641288316247 14:06:41 INFO - PROCESS | 1642 | |5;yui.html;707.7063018847178;729.0158286313477;766.82363056497;779.9649015794292;829.2785276809175;15242.618934526643;15529.301181446834;15866.603334916634;16286.185690715462;16403.671926561463;14653.633659158526;14802.703945921076;15167.62080947974;15200.847991520082;15212;820.2535692519808;951.1392190068;951.3720944176845;952.9047095290484;953.8521529162956;907.6051917415945;950.8573713942894;955.1546881010321;969.5636963366478;971.519098046468;998.1316254858283;1010.3129871687249;1012.7063151686039;1015.0559979219323;1015.8222311095566;986.0878687214298;1002.0280328081785;1007.1590222164501;1007.4005966449951;1007.4156988946394;732.2092140488313;747.768191860525;750.9399248060142;751.7894989402976;752.751591974647;15475;15742.685146297124;15828.366865325374;15873.04761714301;15928.840711592851;13645.863541364559;13925.582232533041;13976.510822121178;14188;14269.571912842632;11756.1182911282;11775.7056073598;11788.23376480526;11886.363861803567;12451.75096498074;3145.697839575386;3635.618260082706;3676.356637588411;3700.1859590889962;3821.2357528494413;107307.92692073135;118167.4091629545;119346;119375.01562460921;119681;10598.470076496144;10855.348679079281;10890.074343680875;10970.396628185526;10978.451077446096;22199.445013874458;22291.442713931956;22313;22364.55270894573;22415.663765043537;7398.0012698285045;7466.664000120008;7612.80967975805;7887.053553573608;7900.802479937933;4160.667146628262;4308.439902812677;4357.193919124972;4367.235733746582;4479.148961697267;2930.4432157889933;3427.640097789746;3505.1237190702323;3557.7153827693724;3563.501109844624;573.8049063318492;575.0999685492275;576.2336092996388;581.3750218515113;588.4497994375273;636.6782006920415;652.667139758717;656.9901451478231;672.5057083043959;688.6900894597451;9544.236461083097;9563.378380405251;9580.66467673628;9764.121229089416;10083.14293285079;567.1889198446179;576.1875755185168;576.2336092996305;578.8032069096528;582.2023827356549;4290.4636920384955;4925.464084538465;4929.594892227022;4967.868147403309;4984.150401722485;99155;104325;105033.47483262535;106174;108914;61715.074273885926;62739;83830.16169838345;84102.15897841066;93087;65469.67265163739;69807.30192698109;77488.61255693686;79449.60275198588;81090.99999999882;10918.4296933601;10998.615048473264;11023.724406889893;11267.549298027987;11304.491297891778;47470.287945681535;49849.75075124746;51181.23228151655;51744.22383664173;51860.74069629628;52418.689532761986;64240.393990150624;64412.67793661189;66332;66337.66831165813;12384.133110682165;12396.876031239572;12461.875381246435;12517.93597544219;12741.745165096832;14456.638584035483;14533.92733036328;14586.34361453716;14589.854101459274;15240.237988100329;13366.532171373956;13452.260125693181;13549.119307245392;13679.726405472034;14163.858361416254;12629.431675574437;13060.608181754562;13485.258310793;13490;14133.646658833612;12108.273503589813;13174.538891138764;13522.323883805573;13561.186328820302;13714.0400171987;10389.636362727268;11026.779464410507;11048.613298534512;11129;11674;9663.733579280717;11196.94401527987;11411.201215915138;11423.371714555778;11477.942610286895 14:06:41 INFO - PROCESS | 1642 | __end_tp_report 14:06:41 INFO - PROCESS | 1642 | __start_cc_report 14:06:41 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,414 14:06:41 INFO - PROCESS | 1642 | __end_cc_report 14:06:41 INFO - PROCESS | 1642 | __startTimestamp1490044001614__endTimestamp 14:06:41 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | ------- Summary: start ------- 14:06:41 INFO - PROCESS | 1642 | Number of tests: 6 14:06:41 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5931.76 Median:6203.10 stddev:653.43 (10.5%) stddev-sans-first:623.90 14:06:41 INFO - PROCESS | 1642 | Values: 3375.6 3447.4 3460.9 3462.7 3466.8 5932.4 5933.8 5990.3 6011.0 6011.9 5961.2 6017.7 6027.1 6034.2 6054.8 5371.8 5430.0 5430.6 5435.7 5562.3 5469.9 5479.2 5479.8 5492.8 5510.6 5266.5 5276.7 5475.6 5499.3 5513.6 5390.6 5392.6 5413.9 5444.6 5461.2 5286.4 5401.6 5404.1 5415.6 5439.9 5648.1 5971.0 6005.2 6061.4 6082.6 6246.9 6265.8 6270.7 6290.2 6315.2 6378.9 6393.8 6427.9 6435.6 6436.1 6072.3 6096.2 6123.2 6124.2 6146.9 6230.9 6268.0 6399.3 6491.2 6500.1 6310.4 6330.9 6353.9 6366.0 6366.3 6361.4 6391.4 6391.9 6397.3 6397.7 6203.1 6211.6 6235.4 6340.9 6352.4 6201.6 6218.5 6278.2 6289.0 6300.6 4200.7 5091.0 5134.6 5143.5 5152.1 4540.9 4552.5 4553.6 4559.5 4604.0 5375.0 5885.5 6112.6 6216.4 6251.7 5364.2 6260.2 6286.4 6319.2 6332.2 6197.6 6206.1 6208.7 6216.3 6219.0 5678.5 5684.6 5693.6 5695.3 5706.2 6271.0 6303.2 6493.6 6535.9 6653.5 6349.0 6426.5 6427.1 6437.1 6479.2 6618.1 6669.3 6670.5 6686.3 6730.5 6118.3 6242.4 6273.7 6379.1 6490.8 6226.7 6318.7 6332.1 6384.8 6400.0 6295.3 6307.2 6308.6 6308.7 6316.2 6130.3 6152.1 6264.3 6267.6 6338.1 6105.1 6118.5 6163.4 6186.9 6234.6 6289.3 6306.8 6316.1 6327.4 6327.5 5958.1 5963.8 6107.2 6113.8 6261.7 14:06:41 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:56092.75 Median:32043.02 stddev:117418.63 (366.4%) stddev-sans-first:117674.50 14:06:41 INFO - PROCESS | 1642 | Values: 750.5 802.6 824.3 930.8 936.0 38378.5 40341.6 40453.2 40469.4 40474.8 38058.6 38314.0 38337.0 38385.8 38466.0 7769.3 7903.7 10471.6 10667.0 10863.4 10567.4 10601.0 10661.3 10669.5 10679.9 10641.0 10688.0 10710.7 10710.9 10728.9 10592.2 10610.2 10622.9 10644.7 10646.7 10405.6 10474.4 10481.0 10492.7 10494.5 33636.2 33641.0 33651.0 33685.3 33717.8 3572.5 3691.4 3716.2 3717.9 3784.5 24933.0 25079.0 25347.1 25590.2 26476.9 3064.7 3071.9 3242.3 3261.4 3267.0 361145.0 361733.0 364421.0 365339.3 365685.0 44939.8 44977.0 45083.0 45085.3 45291.8 46044.8 46355.0 46380.0 46490.5 46511.5 12861.1 13003.5 13023.3 13066.0 13134.2 9665.5 11051.2 11083.4 11103.8 11106.8 11334.8 11753.5 11859.9 11943.9 11976.8 5621.7 5694.3 5697.2 5720.2 5749.9 753.3 875.6 894.2 903.2 905.6 22170.4 26984.6 27451.5 27934.7 28180.6 810.0 969.8 997.6 1002.6 1030.8 13786.7 14222.8 14362.6 14500.7 14559.0 457374.0 458893.7 461064.7 462115.0 462568.0 41712.8 42151.8 42535.0 42564.8 43746.8 1243.0 1410.4 1413.4 1426.9 1439.3 29058.9 29653.4 29777.7 30204.4 30444.0 40034.4 42949.6 45115.9 47100.5 47322.8 486489.0 488284.0 490530.0 492237.0 493250.0 29528.4 29641.8 29737.1 29777.0 29832.7 37432.8 37537.9 37658.1 38030.6 38506.0 31552.2 31735.8 31782.5 31846.4 32758.2 31628.7 31629.5 32165.0 32243.8 32493.0 31094.9 31369.8 31577.0 32016.7 32069.4 28089.2 29040.4 29202.7 29236.0 29386.6 21066.4 22108.4 22380.4 22658.4 22671.8 14:06:41 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:42637.52 Median:21480.75 stddev:126523.14 (589.0%) stddev-sans-first:126838.31 14:06:41 INFO - PROCESS | 1642 | Values: 391.8 417.5 417.6 428.8 451.9 19730.5 20858.5 21102.6 21388.0 21572.8 19850.9 20841.0 20861.2 21370.6 22029.6 6394.8 6613.4 6647.3 6668.2 6669.6 6372.4 6500.0 6539.4 6567.3 6577.1 6448.0 6473.9 6542.7 6544.1 6552.4 6315.1 6563.3 6567.0 6575.6 6585.1 6405.0 6412.3 6614.7 6958.1 7085.5 24286.3 24617.9 25279.0 25421.5 25455.9 2824.0 2833.9 2867.6 2878.7 2955.9 15174.0 15469.8 15720.4 16147.7 16231.3 1985.8 1994.2 2018.8 2026.3 2037.4 130680.3 151688.0 153518.2 155372.0 160285.0 20290.2 20306.3 20678.9 20864.8 21217.4 31335.0 31522.9 31625.5 31722.4 31842.4 10116.1 10118.1 10205.1 10323.0 10373.8 7546.7 8479.2 8502.4 8542.9 8713.7 7431.0 9233.2 9273.7 9337.6 9363.1 3471.5 4134.6 4186.6 4224.2 4228.6 854.5 855.1 870.0 876.0 902.6 15553.1 18893.2 20260.6 21063.2 21388.7 920.1 921.5 929.2 931.7 941.7 9086.8 10439.1 11065.0 11086.3 11337.4 749761.0 756527.0 759746.0 760001.0 785163.0 112851.0 119092.0 125263.4 133289.3 159189.0 1214.6 1230.2 1273.5 1277.8 1278.2 18845.5 18967.4 19390.4 19446.8 19643.5 66006.7 66323.0 66740.7 68235.6 68992.0 70445.6 89006.0 89498.7 90611.6 91774.0 11571.4 11603.1 11622.4 11642.6 11928.6 11435.6 12387.8 13132.4 13336.9 13544.1 10831.5 11185.2 11200.6 11304.9 11460.2 9614.8 11278.9 11297.3 11347.8 11645.2 11777.9 11817.6 12045.5 12093.0 12165.9 11715.3 11728.4 11761.2 11789.0 12122.0 11203.6 11293.7 11483.5 11512.3 11727.6 14:06:41 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6644.12 Median:2383.90 stddev:4137.66 (173.6%) stddev-sans-first:4130.83 14:06:41 INFO - PROCESS | 1642 | Values: 1445.5 1447.8 1514.7 1546.4 1580.9 8243.5 10568.4 11217.7 11305.8 11338.3 8283.0 10891.7 11198.4 11207.3 11220.0 2321.9 2371.5 2382.7 2383.8 2399.9 2347.6 2350.5 2371.1 2378.3 2384.0 2332.0 2349.1 2358.3 2358.6 2377.0 2295.6 2312.1 2315.8 2319.8 2329.1 2285.5 2323.5 2364.9 2377.1 2381.5 9496.4 11211.6 11211.7 11215.3 11230.8 5036.6 5375.7 5557.7 5686.5 5755.2 3492.0 3517.6 3531.9 3596.6 3603.1 1142.9 1165.8 1214.1 1232.9 1238.8 12925.5 13450.0 13565.1 14163.4 14219.5 4567.6 4706.6 4718.5 4728.7 4729.2 11563.4 11683.0 11695.6 11710.4 11721.0 5622.0 5631.4 5679.6 5716.1 5719.1 4966.3 5178.0 5181.4 5213.7 5223.1 3181.7 3225.5 3749.9 3889.9 3913.6 998.6 1004.3 1006.2 1008.0 1020.3 926.5 927.9 930.5 940.1 982.3 5710.3 6659.9 6765.7 6864.7 6909.2 883.9 990.6 1018.3 1024.1 1041.0 3914.3 4046.0 4455.2 4542.6 4592.6 13551.9 13813.2 13968.0 13976.6 14007.7 13036.1 13595.7 13894.2 13900.5 13913.3 8798.6 10103.8 10347.5 10355.3 10358.6 5556.4 5920.8 6595.6 6705.9 6954.2 11968.9 12162.1 12263.9 12299.8 12382.8 11523.5 11795.5 12123.0 12581.5 12737.0 9049.8 9394.3 9408.8 9513.8 9543.0 10350.5 10363.2 10398.0 10458.7 10534.2 8300.1 8310.8 8353.6 8389.5 8397.7 8912.6 9012.1 9123.2 9167.4 9237.2 8007.3 8025.7 8044.4 8098.1 8143.8 5878.4 5899.8 5960.1 6095.4 6143.4 5313.9 5920.2 6053.5 6088.5 6163.2 14:06:41 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4628.59 Median:4984.58 stddev:1308.11 (26.2%) stddev-sans-first:1271.27 14:06:41 INFO - PROCESS | 1642 | Values: 324.9 367.6 384.2 388.2 388.9 4775.6 5229.9 5352.3 5356.3 5373.9 4195.2 4779.9 5226.2 5235.6 5242.2 3508.9 3861.0 4176.6 4363.9 4369.8 3663.0 3898.4 4285.4 4365.0 4426.6 4284.9 4315.2 4321.3 4349.6 4350.2 3838.5 4064.4 4347.6 4368.8 4374.9 3173.5 3344.6 3727.4 4017.4 4067.9 3978.2 4247.9 4767.5 4958.2 5081.5 4728.9 4987.6 5044.9 5064.0 5160.7 5061.4 5521.9 5526.8 5695.4 5791.6 4003.8 4098.8 4171.2 4428.5 4484.1 4965.5 5049.7 5412.6 5477.8 5643.7 3895.8 4017.8 4343.4 4448.9 4529.5 3871.7 4305.0 4659.3 4679.9 4750.4 4794.7 4957.5 4964.7 4976.1 5036.9 4514.4 5572.4 5596.9 5606.5 5613.8 1446.8 1519.2 1575.4 1588.1 1607.5 1092.4 1191.3 1192.4 1197.0 1214.6 4470.4 4569.7 4657.1 4840.7 5299.3 5827.8 5830.5 5836.5 5843.7 6018.7 4618.8 4991.9 5394.3 5397.8 5429.6 2844.4 3077.6 3088.3 3105.2 3112.9 5333.5 5486.6 5701.7 6070.2 6145.8 5113.3 5428.7 5839.6 6000.5 6035.9 5436.7 5554.6 6108.3 6132.6 6177.6 4961.3 4981.5 5450.0 5641.0 5873.2 5349.8 5461.1 5626.3 5752.2 5776.9 4363.7 5739.5 5855.2 6012.8 6041.9 4080.0 4101.5 4520.7 4537.8 4544.8 5170.9 5489.8 5641.2 5736.3 5863.5 4602.6 5115.7 5274.3 5675.6 5688.7 5059.0 5174.9 5180.3 5572.7 5649.9 4748.3 5255.6 5487.9 5515.8 5568.7 4922.0 5273.9 5310.6 5599.7 5624.6 4873.6 5469.8 5487.8 5503.4 5518.6 14:06:41 INFO - PROCESS | 1642 | 14:06:41 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20374.59 Median:22390.11 stddev:29473.63 (131.6%) stddev-sans-first:29519.32 14:06:41 INFO - PROCESS | 1642 | Values: 707.7 729.0 766.8 780.0 829.3 15242.6 15529.3 15866.6 16286.2 16403.7 14653.6 14802.7 15167.6 15200.8 15212.0 820.3 951.1 951.4 952.9 953.9 907.6 950.9 955.2 969.6 971.5 998.1 1010.3 1012.7 1015.1 1015.8 986.1 1002.0 1007.2 1007.4 1007.4 732.2 747.8 750.9 751.8 752.8 15475.0 15742.7 15828.4 15873.0 15928.8 13645.9 13925.6 13976.5 14188.0 14269.6 11756.1 11775.7 11788.2 11886.4 12451.8 3145.7 3635.6 3676.4 3700.2 3821.2 107307.9 118167.4 119346.0 119375.0 119681.0 10598.5 10855.3 10890.1 10970.4 10978.5 22199.4 22291.4 22313.0 22364.6 22415.7 7398.0 7466.7 7612.8 7887.1 7900.8 4160.7 4308.4 4357.2 4367.2 4479.1 2930.4 3427.6 3505.1 3557.7 3563.5 573.8 575.1 576.2 581.4 588.4 636.7 652.7 657.0 672.5 688.7 9544.2 9563.4 9580.7 9764.1 10083.1 567.2 576.2 576.2 578.8 582.2 4290.5 4925.5 4929.6 4967.9 4984.2 99155.0 104325.0 105033.5 106174.0 108914.0 61715.1 62739.0 83830.2 84102.2 93087.0 65469.7 69807.3 77488.6 79449.6 81091.0 10918.4 10998.6 11023.7 11267.5 11304.5 47470.3 49849.8 51181.2 51744.2 51860.7 52418.7 64240.4 64412.7 66332.0 66337.7 12384.1 12396.9 12461.9 12517.9 12741.7 14456.6 14533.9 14586.3 14589.9 15240.2 13366.5 13452.3 13549.1 13679.7 14163.9 12629.4 13060.6 13485.3 13490.0 14133.6 12108.3 13174.5 13522.3 13561.2 13714.0 10389.6 11026.8 11048.6 11129.0 11674.0 9663.7 11196.9 11411.2 11423.4 11477.9 14:06:41 INFO - PROCESS | 1642 | -------- Summary: end -------- 14:06:41 INFO - PROCESS | 1642 | 14:06:42 INFO - TEST-INFO | 1642: exit 0 14:06:42 INFO - TEST-OK | dromaeo_css | took 1116772ms 14:06:42 INFO - TEST-START | kraken 14:06:42 INFO - operating with platform_type : mac_ 14:06:42 INFO - Initialising browser for kraken test... 14:06:42 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpRT3rTC/profile http://localhost:49245/getInfo.html) 14:06:45 INFO - PROCESS | 1653 | 2017-03-20 14:06:45.211 plugin-container[1654:10292] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 14:06:45 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:06:45 INFO - PROCESS | 1653 | 2017-03-20 14:06:45.213 plugin-container[1654:10292] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 14:06:45 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:06:45 INFO - PROCESS | 1653 | 2017-03-20 14:06:45.214 plugin-container[1654:10292] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:06:45 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 14:06:45 INFO - PROCESS | 1653 | colorDepth:24 14:06:45 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 14:06:45 INFO - PROCESS | 1653 | __metrics 14:06:45 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 14:06:46 INFO - TEST-INFO | 1653: exit 0 14:06:46 INFO - Browser initialized. 14:06:46 INFO - Running cycle 1/1 for kraken test... 14:06:46 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpRT3rTC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:06:48 INFO - PROCESS | 1655 | 2017-03-20 14:06:48.633 plugin-container[1656:10420] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882b, name = 'com.apple.tsm.portname' 14:06:48 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:06:48 INFO - PROCESS | 1655 | 2017-03-20 14:06:48.633 plugin-container[1656:10420] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 14:06:48 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:06:48 INFO - PROCESS | 1655 | 2017-03-20 14:06:48.633 plugin-container[1656:10420] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:06:58 INFO - PROCESS | 1655 | RSS: Main: 220688384 14:06:58 INFO - PROCESS | 1655 | 14:07:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 14:07:02 INFO - PROCESS | 1655 | RSS: Main: 235155456 14:07:02 INFO - PROCESS | 1655 | 14:07:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 14:07:05 INFO - PROCESS | 1655 | RSS: Main: 243580928 14:07:05 INFO - PROCESS | 1655 | 14:07:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 14:07:09 INFO - PROCESS | 1655 | RSS: Main: 247119872 14:07:09 INFO - PROCESS | 1655 | 14:07:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 14:07:12 INFO - PROCESS | 1655 | RSS: Main: 248582144 14:07:12 INFO - PROCESS | 1655 | 14:07:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:07:14 INFO - PROCESS | 1655 | RSS: Main: 248938496 14:07:14 INFO - PROCESS | 1655 | 14:07:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 14:07:18 INFO - PROCESS | 1655 | RSS: Main: 252854272 14:07:18 INFO - PROCESS | 1655 | 14:07:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 14:07:22 INFO - PROCESS | 1655 | RSS: Main: 252559360 14:07:22 INFO - PROCESS | 1655 | 14:07:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 14:07:25 INFO - PROCESS | 1655 | RSS: Main: 252477440 14:07:25 INFO - PROCESS | 1655 | 14:07:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:07:28 INFO - PROCESS | 1655 | RSS: Main: 251867136 14:07:28 INFO - PROCESS | 1655 | 14:07:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:07:30 INFO - PROCESS | 1655 | RSS: Main: 251904000 14:07:30 INFO - PROCESS | 1655 | 14:07:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:07:33 INFO - PROCESS | 1655 | RSS: Main: 251924480 14:07:33 INFO - PROCESS | 1655 | 14:07:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:07:37 INFO - PROCESS | 1655 | RSS: Main: 252329984 14:07:37 INFO - PROCESS | 1655 | 14:07:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:07:40 INFO - PROCESS | 1655 | RSS: Main: 252280832 14:07:40 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 14:07:42 INFO - PROCESS | 1655 | RSS: Main: 254017536 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | __start_tp_report 14:07:42 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 14:07:42 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 14:07:42 INFO - PROCESS | 1655 | |i|pagename|runs| 14:07:42 INFO - PROCESS | 1655 | |0;ai-astar;112;180;169;122;124;123;122;122;136;187 14:07:42 INFO - PROCESS | 1655 | |1;audio-beat-detection;146;115;113;147;123;138;99;144;161;113 14:07:42 INFO - PROCESS | 1655 | |2;audio-dft;238;263;200;197;200;203;195;193;201;197 14:07:42 INFO - PROCESS | 1655 | |3;audio-fft;74;84;64;92;72;63;63;60;73;62 14:07:42 INFO - PROCESS | 1655 | |4;audio-oscillator;89;71;86;75;71;71;83;70;71;84 14:07:42 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;106;89;87;91;89;108;100;88;88;89 14:07:42 INFO - PROCESS | 1655 | |6;imaging-darkroom;95;96;120;115;97;97;138;114;155;96 14:07:42 INFO - PROCESS | 1655 | |7;imaging-desaturate;111;106;90;89;98;90;96;91;91;89 14:07:42 INFO - PROCESS | 1655 | |8;json-parse-financial;62;65;69;65;70;68;68;69;69;82 14:07:42 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;58;64;59;67;73;94;53;72;56;51 14:07:42 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;85;81;83;85;87;89;85;85;77;99 14:07:42 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;157;156;154;146;160;142;170;145;181;153 14:07:42 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;168;168;167;170;167;169;168;167;168;166 14:07:42 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;65;67;69;72;80;86;72;66;66 14:07:42 INFO - PROCESS | 1655 | __end_tp_report 14:07:42 INFO - PROCESS | 1655 | __start_cc_report 14:07:42 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1014 14:07:42 INFO - PROCESS | 1655 | __end_cc_report 14:07:42 INFO - PROCESS | 1655 | __startTimestamp1490044062647__endTimestamp 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | ------- Summary: start ------- 14:07:42 INFO - PROCESS | 1655 | Number of tests: 14 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:139.70 Median:130.00 stddev:27.82 (21.4%) stddev-sans-first:27.64 14:07:42 INFO - PROCESS | 1655 | Values: 112.0 180.0 169.0 122.0 124.0 123.0 122.0 122.0 136.0 187.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:145.00 stddev:19.94 (13.8%) stddev-sans-first:20.28 14:07:42 INFO - PROCESS | 1655 | Values: 146.0 115.0 113.0 147.0 123.0 138.0 99.0 144.0 161.0 113.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:208.70 Median:200.50 stddev:22.99 (11.5%) stddev-sans-first:21.81 14:07:42 INFO - PROCESS | 1655 | Values: 238.0 263.0 200.0 197.0 200.0 203.0 195.0 193.0 201.0 197.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:70.70 Median:72.50 stddev:10.55 (14.6%) stddev-sans-first:11.12 14:07:42 INFO - PROCESS | 1655 | Values: 74.0 84.0 64.0 92.0 72.0 63.0 63.0 60.0 73.0 62.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:79.00 stddev:7.50 (9.5%) stddev-sans-first:6.61 14:07:42 INFO - PROCESS | 1655 | Values: 89.0 71.0 86.0 75.0 71.0 71.0 83.0 70.0 71.0 84.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:88.50 stddev:8.02 (9.1%) stddev-sans-first:7.11 14:07:42 INFO - PROCESS | 1655 | Values: 106.0 89.0 87.0 91.0 89.0 108.0 100.0 88.0 88.0 89.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:112.30 Median:95.50 stddev:20.69 (21.7%) stddev-sans-first:20.97 14:07:42 INFO - PROCESS | 1655 | Values: 95.0 96.0 120.0 115.0 97.0 97.0 138.0 114.0 155.0 96.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:90.50 stddev:7.75 (8.6%) stddev-sans-first:5.70 14:07:42 INFO - PROCESS | 1655 | Values: 111.0 106.0 90.0 89.0 98.0 90.0 96.0 91.0 91.0 89.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:69.00 stddev:5.29 (7.7%) stddev-sans-first:5.03 14:07:42 INFO - PROCESS | 1655 | Values: 62.0 65.0 69.0 65.0 70.0 68.0 68.0 69.0 69.0 82.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.50 stddev:12.75 (19.5%) stddev-sans-first:13.30 14:07:42 INFO - PROCESS | 1655 | Values: 58.0 64.0 59.0 67.0 73.0 94.0 53.0 72.0 56.0 51.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:5.74 (6.8%) stddev-sans-first:6.08 14:07:42 INFO - PROCESS | 1655 | Values: 85.0 81.0 83.0 85.0 87.0 89.0 85.0 85.0 77.0 99.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:156.40 Median:156.50 stddev:11.86 (7.6%) stddev-sans-first:12.58 14:07:42 INFO - PROCESS | 1655 | Values: 157.0 156.0 154.0 146.0 160.0 142.0 170.0 145.0 181.0 153.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 14:07:42 INFO - PROCESS | 1655 | Values: 168.0 168.0 167.0 170.0 167.0 169.0 168.0 167.0 168.0 166.0 14:07:42 INFO - PROCESS | 1655 | 14:07:42 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:70.50 stddev:6.91 (9.8%) stddev-sans-first:7.18 14:07:42 INFO - PROCESS | 1655 | Values: 67.0 65.0 67.0 69.0 72.0 80.0 86.0 72.0 66.0 66.0 14:07:42 INFO - PROCESS | 1655 | -------- Summary: end -------- 14:07:42 INFO - PROCESS | 1655 | 14:07:43 INFO - TEST-INFO | 1655: exit 0 14:07:43 INFO - TEST-OK | kraken | took 61274ms 14:07:43 INFO - SUITE-END | took 1178s 14:07:43 INFO - Completed test suite (00:19:38) 14:07:43 INFO - javascript benchmark 14:07:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7626.04302082752, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3375.5611770469836, 3447.362237985971, 3460.9307813843725, 3462.740294477914, 3466.7573269871104, 5932.436418540243, 5933.7626494940205, 5990.251218597676, 6011.0, 6011.939880601192, 5961.195238642782, 6017.669028203447, 6027.09593560965, 6034.215551978247, 6054.8183554493435, 5371.75827087781, 5429.968306021863, 5430.56555475563, 5435.673859568419, 5562.3047119110115, 5469.945300547003, 5479.150731636602, 5479.780808767645, 5492.752826122814, 5510.586705997066, 5266.499682530172, 5276.736163191844, 5475.561955043607, 5499.312585926759, 5513.641613295143, 5390.6226564140525, 5392.622516423852, 5413.891722165555, 5444.5916556258435, 5461.208124821918, 5286.444923283077, 5401.648892821954, 5404.081306177959, 5415.593830462731, 5439.891202175934, 5648.096304591246, 5970.97014514929, 6005.1892994445625, 6061.363556826514, 6082.5742198046155, 6246.906296405512, 6265.843353916189, 6270.65511396873, 6290.150829637986, 6315.210598675166, 6378.904316435257, 6393.808185754435, 6427.93572064278, 6435.646039467827, 6436.131122298477, 6072.271327440735, 6096.207493025879, 6123.173371594867, 6124.203853499017, 6146.907796383058, 6230.937690623126, 6268.028455589391, 6399.264084630234, 6491.1886014247275, 6500.057491663682, 6310.432061114522, 6330.905036424458, 6353.872922541524, 6366.045093235959, 6366.267879193952, 6361.363863613694, 6391.424771770563, 6391.904121438182, 6397.26431460388, 6397.712102955378, 6203.100550420164, 6211.627302361872, 6235.407636274546, 6340.904886426707, 6352.36476352361, 6201.56589038772, 6218.533609979269, 6278.183836101278, 6289.0252010938375, 6300.59046161998, 4200.663946884303, 5091.0, 5134.589232861362, 5143.537081662594, 5152.149895267314, 4540.909181816345, 4552.476465206543, 4553.567411095941, 4559.544045595414, 4604.0, 5374.978754036721, 5885.499732522698, 6112.633242005405, 6216.409441103088, 6251.718672659741, 5364.249005139283, 6260.154879091356, 6286.402791734778, 6319.241690997018, 6332.208473940757, 6197.597156184834, 6206.100115483229, 6208.72060757267, 6216.2540495140875, 6218.973869311603, 5678.4605462480995, 5684.573656975744, 5693.572982026365, 5695.345035320908, 5706.172604972338, 6271.0, 6303.180586523906, 6493.642849643126, 6535.869282614226, 6653.500987425962, 6349.0476428536085, 6426.453751430994, 6427.1002059710745, 6437.130987316652, 6479.222493300834, 6618.07346971434, 6669.2997235289595, 6670.466362690876, 6686.3313668632745, 6730.461563074844, 6118.345080237214, 6242.438180563771, 6273.71768270438, 6379.138816259746, 6490.772822951174, 6226.719797609027, 6318.747250109944, 6332.050192471166, 6384.840378990377, 6400.039994000937, 6295.276043255084, 6307.211598550181, 6308.558400911892, 6308.747650094128, 6316.210473690789, 6130.295016073209, 6152.138700581832, 6264.279607844974, 6267.592606480746, 6338.112664227104, 6105.084237364431, 6118.479929206067, 6163.445289923929, 6186.876262474815, 6234.563580549318, 6289.339619339903, 6306.842328941813, 6316.14732011191, 6327.430531252209, 6327.462165715966, 5958.106284057253, 5963.821085367446, 6107.219158344477, 6113.841195312776, 6261.68691565429], "value": 5889.905270481551, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.470918002808, 802.6227672993692, 824.3034635732805, 930.8324501589711, 935.9953200233998, 38378.46486140555, 40341.59658403415, 40453.19093618126, 40469.3929591056, 40474.78575642728, 38058.619413805856, 38314.0, 38337.0, 38385.808070959676, 38466.0, 7769.300762931335, 7903.723369682033, 10471.581136754521, 10667.0, 10863.402512861789, 10567.418791966438, 10601.04590586847, 10661.253712240146, 10669.506269122328, 10679.893201067967, 10641.0, 10688.0, 10710.732231694232, 10710.892891071106, 10728.946355268212, 10592.15262778976, 10610.151187904949, 10622.946885265601, 10644.733881652983, 10646.733831654194, 10405.635802746943, 10474.371537707762, 10481.0, 10492.737681557946, 10494.475276236233, 33636.15909602255, 33641.0, 33651.0, 33685.32629347424, 33717.831410843035, 3572.517710109128, 3691.3909204981146, 3716.224601744425, 3717.8988132666746, 3784.4701741756176, 24933.0, 25079.0, 25347.112851050126, 25590.23229303124, 26476.8676156618, 3064.69353064694, 3071.9232019199476, 3242.3191129862757, 3261.4455542557585, 3266.9673303267136, 361145.0, 361733.0, 364421.0, 365339.3466065332, 365685.0, 44939.77530112394, 44977.0, 45083.0, 45085.323720144224, 45291.773541132745, 46044.769776151574, 46355.0, 46380.0, 46490.53509464862, 46511.534884651395, 12861.099723019486, 13003.544875929298, 13023.348832558335, 13066.0200484964, 13134.211947283193, 9665.468399237969, 11051.17116216287, 11083.390413527333, 11103.777924441465, 11106.777864442665, 11334.829977550344, 11753.4710938008, 11859.88140118605, 11943.940280298542, 11976.76046479083, 5621.690807005654, 5694.259746232963, 5697.173909783059, 5720.170575266563, 5749.8562535936935, 753.2618034326281, 875.6409871952469, 894.2130924786277, 903.2277402820494, 905.6467977488785, 22170.445738856335, 26984.59523107155, 27451.45097098047, 27934.7429365679, 28180.577291340647, 809.9838003239902, 969.7963427680266, 997.6009596161593, 1002.6240159940022, 1030.8247597908373, 13786.65533361654, 14222.786658200135, 14362.569122926328, 14500.709985800226, 14559.0, 457374.0, 458893.7055314702, 461064.6946765312, 462115.0, 462568.0, 41712.79143604262, 42151.7892410536, 42535.0, 42564.787176063925, 43746.781266093465, 1242.9502819887284, 1410.3935307817533, 1413.4275618374575, 1426.9072510286799, 1439.2731670506537, 29058.854705726335, 29653.406931861675, 29777.702222977492, 30204.395912082076, 30444.0, 40034.39862405471, 42949.570504294556, 45115.87210319768, 47100.52899470962, 47322.816929577035, 486489.0, 488284.0, 490530.0, 492237.0, 493250.0, 29528.409431810815, 29641.814327426662, 29737.10788676343, 29777.0, 29832.70167298386, 37432.81283593565, 37537.87386378413, 37658.05854853651, 38030.61969380381, 38506.0, 31552.211194720316, 31735.84132079325, 31782.52326215155, 31846.363072738877, 32758.181045474055, 31628.683713162573, 31629.5255571162, 32165.0, 32243.838780805945, 32493.0, 31094.911678091157, 31369.8431507841, 31577.0, 32016.67983320137, 32069.358612827145, 28089.157325280274, 29040.41919161647, 29202.707972919998, 29236.0, 29386.55920161242, 21066.368008959755, 22108.40887280068, 22380.440488987904, 22658.433539160993, 22671.773282267626], "value": 17980.528744311363, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.8178047208049, 417.45939008983385, 417.6137073207286, 428.79632174717005, 451.88476938380705, 19730.506737331554, 20858.47853803654, 21102.57794844104, 21388.037538310775, 21572.813495257753, 19850.90074549629, 20841.0, 20861.165553377847, 21370.572588548257, 22029.559408811816, 6394.808155755323, 6613.371729685672, 6647.268800431949, 6668.199816022084, 6669.599824010551, 6372.362763723637, 6499.967500162516, 6539.378759017909, 6567.277599464055, 6577.079208910756, 6448.0, 6473.93526064738, 6542.672866356687, 6544.149260596116, 6552.443042341407, 6315.115883776275, 6563.343665633399, 6566.967165164144, 6575.638339891304, 6585.078089067534, 6405.0, 6412.262589802139, 6614.669266536654, 6958.130233720785, 7085.46858985573, 24286.271411857673, 24617.876910615334, 25279.0, 25421.491570168677, 25455.872720636464, 2823.971760282412, 2833.9433211335863, 2867.5985362049328, 2878.6977367376335, 2955.852207389622, 15174.013689110283, 15469.845301546953, 15720.449784257611, 16147.677046459123, 16231.269592868231, 1985.7762083775588, 1994.2222533211914, 2018.7577490701062, 2026.3313106674761, 2037.4091513461078, 130680.3465982664, 151688.0, 153518.23240883835, 155372.0, 160285.0, 20290.188392464137, 20306.289279875135, 20678.896605517177, 20864.791352086286, 21217.363479095653, 31334.963227390603, 31522.896698615437, 31625.525617115763, 31722.365552688818, 31842.363152736816, 10116.140128089195, 10118.08937195656, 10205.081542661195, 10323.0, 10373.84439233412, 7546.735864244725, 8479.15208479147, 8502.44734092282, 8542.871856922027, 8713.651453941771, 7431.0, 9233.215176710055, 9273.72178834636, 9337.626494940263, 9363.110504502061, 3471.531343268626, 4134.607212314825, 4186.58134186585, 4224.2185195738275, 4228.640565551963, 854.5129276312442, 855.1234984141181, 869.9695510657097, 875.9794839012479, 902.5938327752538, 15553.144577048144, 18893.244270229035, 20260.59478810445, 21063.157473701187, 21388.679169812465, 920.0586761932317, 921.530019689963, 929.2287401456774, 931.7111695374456, 941.6892425499706, 9086.772830679285, 10439.060484556427, 11065.004149626573, 11086.279391839666, 11337.43312834355, 749761.0, 756527.0, 759746.0, 760001.0, 785163.0, 112851.0, 119092.0, 125263.373683131, 133289.3335533316, 159189.0, 1214.6416807041944, 1230.2372529032057, 1273.4969686973593, 1277.7636137314441, 1278.1628033637983, 18845.528861778566, 18967.43097707071, 19390.4182874514, 19446.8055319445, 19643.508912277306, 66006.66996665178, 66323.0, 66740.6662966682, 68235.63528729496, 68992.0, 70445.64777176286, 89006.0, 89498.65752013856, 90611.640825389, 91774.0, 11571.363575003119, 11603.07175425947, 11622.360770157722, 11642.592509262475, 11928.582499612472, 11435.59975400857, 12387.814182787446, 13132.409041593342, 13336.86663133395, 13544.051916366154, 10831.458427078456, 11185.217034807485, 11200.607978720705, 11304.886951130713, 11460.19778615489, 9614.759631009001, 11278.887211127783, 11297.322160670386, 11347.773044539228, 11645.243059201124, 11777.941110294394, 11817.586384476503, 12045.518179273082, 12092.972097371821, 12165.939170304091, 11715.297082175097, 11728.354940478355, 11761.235519691192, 11789.0, 12122.030237581148, 11203.60787372438, 11293.661190164308, 11483.540658373906, 11512.309261444005, 11727.648170554898], "value": 11390.994529098894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.4507287230851, 1447.833499147598, 1514.6667733098714, 1546.3814474210321, 1580.8577228049476, 8243.546604936726, 10568.365898046113, 11217.719557011058, 11305.773884522316, 11338.319700817947, 8283.0, 10891.6732498025, 11198.440077996109, 11207.271527350716, 11219.990200881919, 2321.895514701834, 2371.490129622131, 2382.7140743110763, 2383.7616238376195, 2399.8560086394787, 2347.647852822082, 2350.5416443793465, 2371.1463873005705, 2378.310290015902, 2384.046381447426, 2331.988340058297, 2349.1425629645228, 2358.3396648938237, 2358.6108292131776, 2376.95246095078, 2295.586794377028, 2312.1329501436962, 2315.8147348212187, 2319.814414846808, 2329.1382188590246, 2285.48576570272, 2323.5352929414016, 2364.929052128439, 2377.1323466934446, 2381.5236952609375, 9496.43021418717, 11211.551537938472, 11211.66365009043, 11215.327080375204, 11230.775384492346, 5036.647434679574, 5375.6774593524515, 5557.7217240034615, 5686.488216060575, 5755.194272801811, 3492.0396890854963, 3517.6482351764876, 3531.858725650971, 3596.6403359664087, 3603.1352475405974, 1142.862856457222, 1165.795985702507, 1214.1015648420105, 1232.8828761267666, 1238.789405801016, 12925.547605833845, 13450.0, 13565.050446468653, 14163.433462661482, 14219.50231741884, 4567.634589232854, 4706.623470122382, 4718.504557021463, 4728.692634978715, 4729.219678753036, 11563.42182890869, 11683.0, 11695.590654327229, 11710.414479276002, 11721.003714684177, 5622.0, 5631.4087020863035, 5679.574031947538, 5716.114002329646, 5719.142128680648, 4966.255061740696, 5177.974110129425, 5181.35233095863, 5213.7393130343335, 5223.059849227176, 3181.7454603631654, 3225.516172574086, 3749.868754593631, 3889.8913809564137, 3913.5695073541888, 998.5805961496189, 1004.347174336687, 1006.2000709436016, 1007.9949600251952, 1020.3061917895753, 926.4805718621466, 927.9257659387233, 930.4928813796497, 940.1303793990531, 982.29274922056, 5710.2576665033275, 6659.9001014984815, 6765.661716914135, 6864.656767161622, 6909.205441374206, 883.9292856571459, 990.5641517732175, 1018.3024628129755, 1024.109025148126, 1041.047441590951, 3914.269263605152, 4046.008727861693, 4455.22033644111, 4542.613877820356, 4592.563706447882, 13551.86448135506, 13813.24027178515, 13968.0, 13976.580702578736, 14007.719845603235, 13036.087473876738, 13595.728085438235, 13894.23581703016, 13900.51348202808, 13913.30433478322, 8798.648054077894, 10103.84844227337, 10347.534360953776, 10355.326903751233, 10358.585656573652, 5556.361018482926, 5920.822375328747, 6595.637239951657, 6705.865882682221, 6954.200266969363, 11968.880311196777, 12162.087843411602, 12263.87736122663, 12299.754004919672, 12382.81425778632, 11523.53905843757, 11795.52817887275, 12123.0, 12581.496740130293, 12737.0, 9049.77375565616, 9394.342396032485, 9408.811823763623, 9513.809723805347, 9543.0456954304, 10350.482475876026, 10363.222758293008, 10398.012188842047, 10458.68623941283, 10534.209934254808, 8300.12848650883, 8310.83378332442, 8353.624086916225, 8389.454685445426, 8397.664093436439, 8912.598933047899, 9012.143846334588, 9123.224525915371, 9167.404118732262, 9237.214836738953, 8007.319377852948, 8025.678972841021, 8044.436889417684, 8098.068722097034, 8143.75568732939, 5878.382769809108, 5899.793507227227, 5960.135780311917, 6095.35998720128, 6143.416375444325, 5313.937212557581, 5920.171176035273, 6053.545984051126, 6088.51291896656, 6163.167972323679], "value": 5071.562494782312, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.8960332693536, 367.5809577082125, 384.2392063713846, 388.1790014120135, 388.88333499950016, 4775.641826862986, 5229.921551176735, 5352.277442545265, 5356.3036805215315, 5373.9193912091305, 4195.17509883201, 4779.8805029874275, 5226.242194881749, 5235.55497782689, 5242.21366794981, 3508.877189298375, 3861.046299580823, 4176.561461046592, 4363.912721745563, 4369.781510924457, 3662.963370366302, 3898.3567711327737, 4285.400043993844, 4364.956350436487, 4426.579474949891, 4284.935725964113, 4315.244832154376, 4321.286987647034, 4349.586384425059, 4350.173467041268, 3838.4818049563232, 4064.3742502998743, 4347.586979236968, 4368.825246990133, 4374.934375984363, 3173.5239714042846, 3344.5652065231607, 3727.384981478053, 4017.4375587417494, 4067.9050289101137, 3978.204359128157, 4247.915041699149, 4767.4994125617, 4958.181899986498, 5081.517255860687, 4728.905421891577, 4987.62592805541, 5044.873878153039, 5063.974680126613, 5160.716160611165, 5061.392632884077, 5521.936459909222, 5526.806561770359, 5695.40198279183, 5791.5656325775735, 4003.7597744135446, 4098.815553300109, 4171.249175148472, 4428.535003824616, 4484.1255955088445, 4965.477301887643, 5049.697018178885, 5412.5940554458175, 5477.808276710336, 5643.661380317194, 3895.7857317847224, 4017.794661601508, 4343.4136391586735, 4448.933266001012, 4529.54704529551, 3871.709621778378, 4304.956950430518, 4659.347691323217, 4679.859604211879, 4750.358701575312, 4794.7362895040405, 4957.504249575086, 4964.652474326835, 4976.1434887961395, 5036.949630503721, 4514.390557274726, 5572.350110677513, 5596.86007849807, 5606.5331254248, 5613.803516876889, 1446.7902154187793, 1519.2024187301533, 1575.3698520591627, 1588.1344667156427, 1607.4695350534296, 1092.4428541443763, 1191.303087693709, 1192.3859212505567, 1196.9940150299192, 1214.5688280660415, 4470.441194850644, 4569.680122391467, 4657.115148121846, 4840.70831834356, 5299.330710826078, 5827.76688932438, 5830.504407125357, 5836.533077353802, 5843.678597677169, 6018.6990650467305, 4618.815247390066, 4991.900161996739, 5394.32570928634, 5397.784088636489, 5429.592780541475, 2844.3787041386213, 3077.615298087739, 3088.313167544206, 3105.208171916147, 3112.891048813326, 5333.493318134771, 5486.561075113981, 5701.743421546041, 6070.2108725865355, 6145.8156255312415, 5113.335266415343, 5428.67427954324, 5839.649621022752, 6000.549958753111, 6035.909461358083, 5436.70098144598, 5554.583406244547, 6108.266093777358, 6132.63204207749, 6177.598456100339, 4961.305417241517, 4981.5304485177685, 5450.0, 5640.984622767941, 5873.213331135111, 5349.839504814706, 5461.071617825059, 5626.268585084076, 5752.223449834219, 5776.884462310814, 4363.738175709467, 5739.474631162275, 5855.238818953509, 6012.849678757891, 6041.879162416815, 4080.000399902043, 4101.46680931477, 4520.683552151318, 4537.773111344485, 4544.7727613619845, 5170.922436163385, 5489.835304940858, 5641.238432811518, 5736.340320862987, 5863.472287494146, 4602.631789456768, 5115.667481613683, 5274.261603375454, 5675.574331925205, 5688.667646265011, 5058.974705126451, 5174.913268213567, 5180.326557547494, 5572.749226284908, 5649.915251271316, 4748.264019077049, 5255.605829562722, 5487.917681234864, 5515.834524964257, 5568.6658800472105, 4922.040202160544, 5273.894522109613, 5310.566147140327, 5599.664020158803, 5624.578156638187, 4873.561379475864, 5469.835904922858, 5487.780488780564, 5503.394626591152, 5518.641288316247], "value": 4249.2007952891945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.7063018847178, 729.0158286313477, 766.82363056497, 779.9649015794292, 829.2785276809175, 15242.618934526643, 15529.301181446834, 15866.603334916634, 16286.185690715462, 16403.671926561463, 14653.633659158526, 14802.703945921076, 15167.62080947974, 15200.847991520082, 15212.0, 820.2535692519808, 951.1392190068, 951.3720944176845, 952.9047095290484, 953.8521529162956, 907.6051917415945, 950.8573713942894, 955.1546881010321, 969.5636963366478, 971.519098046468, 998.1316254858283, 1010.3129871687249, 1012.7063151686039, 1015.0559979219323, 1015.8222311095566, 986.0878687214298, 1002.0280328081785, 1007.1590222164501, 1007.4005966449951, 1007.4156988946394, 732.2092140488313, 747.768191860525, 750.9399248060142, 751.7894989402976, 752.751591974647, 15475.0, 15742.685146297124, 15828.366865325374, 15873.04761714301, 15928.840711592851, 13645.863541364559, 13925.582232533041, 13976.510822121178, 14188.0, 14269.571912842632, 11756.1182911282, 11775.7056073598, 11788.23376480526, 11886.363861803567, 12451.75096498074, 3145.697839575386, 3635.618260082706, 3676.356637588411, 3700.1859590889962, 3821.2357528494413, 107307.92692073135, 118167.4091629545, 119346.0, 119375.01562460921, 119681.0, 10598.470076496144, 10855.348679079281, 10890.074343680875, 10970.396628185526, 10978.451077446096, 22199.445013874458, 22291.442713931956, 22313.0, 22364.55270894573, 22415.663765043537, 7398.0012698285045, 7466.664000120008, 7612.80967975805, 7887.053553573608, 7900.802479937933, 4160.667146628262, 4308.439902812677, 4357.193919124972, 4367.235733746582, 4479.148961697267, 2930.4432157889933, 3427.640097789746, 3505.1237190702323, 3557.7153827693724, 3563.501109844624, 573.8049063318492, 575.0999685492275, 576.2336092996388, 581.3750218515113, 588.4497994375273, 636.6782006920415, 652.667139758717, 656.9901451478231, 672.5057083043959, 688.6900894597451, 9544.236461083097, 9563.378380405251, 9580.66467673628, 9764.121229089416, 10083.14293285079, 567.1889198446179, 576.1875755185168, 576.2336092996305, 578.8032069096528, 582.2023827356549, 4290.4636920384955, 4925.464084538465, 4929.594892227022, 4967.868147403309, 4984.150401722485, 99155.0, 104325.0, 105033.47483262535, 106174.0, 108914.0, 61715.074273885926, 62739.0, 83830.16169838345, 84102.15897841066, 93087.0, 65469.67265163739, 69807.30192698109, 77488.61255693686, 79449.60275198588, 81090.99999999882, 10918.4296933601, 10998.615048473264, 11023.724406889893, 11267.549298027987, 11304.491297891778, 47470.287945681535, 49849.75075124746, 51181.23228151655, 51744.22383664173, 51860.74069629628, 52418.689532761986, 64240.393990150624, 64412.67793661189, 66332.0, 66337.66831165813, 12384.133110682165, 12396.876031239572, 12461.875381246435, 12517.93597544219, 12741.745165096832, 14456.638584035483, 14533.92733036328, 14586.34361453716, 14589.854101459274, 15240.237988100329, 13366.532171373956, 13452.260125693181, 13549.119307245392, 13679.726405472034, 14163.858361416254, 12629.431675574437, 13060.608181754562, 13485.258310793, 13490.0, 14133.646658833612, 12108.273503589813, 13174.538891138764, 13522.323883805573, 13561.186328820302, 13714.0400171987, 10389.636362727268, 11026.779464410507, 11048.613298534512, 11129.0, 11674.0, 9663.733579280717, 11196.94401527987, 11411.201215915138, 11423.371714555778, 11477.942610286895], "value": 7565.445416263665, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 180.0, 169.0, 122.0, 124.0, 123.0, 122.0, 122.0, 136.0, 187.0], "value": 139.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 113.0, 147.0, 123.0, 138.0, 99.0, 144.0, 161.0, 113.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [238.0, 263.0, 200.0, 197.0, 200.0, 203.0, 195.0, 193.0, 201.0, 197.0], "value": 208.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 84.0, 64.0, 92.0, 72.0, 63.0, 63.0, 60.0, 73.0, 62.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 75.0, 71.0, 71.0, 83.0, 70.0, 71.0, 84.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 89.0, 87.0, 91.0, 89.0, 108.0, 100.0, 88.0, 88.0, 89.0], "value": 93.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 120.0, 115.0, 97.0, 97.0, 138.0, 114.0, 155.0, 96.0], "value": 112.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 90.0, 89.0, 98.0, 90.0, 96.0, 91.0, 91.0, 89.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 69.0, 65.0, 70.0, 68.0, 68.0, 69.0, 69.0, 82.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 59.0, 67.0, 73.0, 94.0, 53.0, 72.0, 56.0, 51.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 83.0, 85.0, 87.0, 89.0, 85.0, 85.0, 77.0, 99.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 154.0, 146.0, 160.0, 142.0, 170.0, 145.0, 181.0, 153.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 167.0, 170.0, 167.0, 169.0, 168.0, 167.0, 168.0, 166.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 69.0, 72.0, 80.0, 86.0, 72.0, 66.0, 66.0], "value": 71.0, "unit": "score"}]}]} 14:07:43 INFO - Return code: 0 14:07:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:07:43 ERROR - # TBPL SUCCESS # 14:07:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:07:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:07:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:07:43 INFO - 2017-03-20 14:07:43.997 screenresolution[1660:10792] starting screenresolution argv=screenresolution get 14:07:44 INFO - 2017-03-20 14:07:44.000 screenresolution[1660:10792] Display 0: 1600x1200x32@60 14:07:44 INFO - 2017-03-20 14:07:44.006 screenresolution[1661:10795] starting screenresolution argv=screenresolution list 14:07:44 INFO - Available Modes on Display 0 14:07:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:07:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:07:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:07:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:07:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:07:44 INFO - Intel Iris: 14:07:44 INFO - Chipset Model: Intel Iris 14:07:44 INFO - Type: GPU 14:07:44 INFO - Bus: Built-In 14:07:44 INFO - VRAM (Dynamic, Max): 1536 MB 14:07:44 INFO - Vendor: Intel (0x8086) 14:07:44 INFO - Device ID: 0x0a2e 14:07:44 INFO - Revision ID: 0x0009 14:07:44 INFO - Displays: 14:07:44 INFO - Display: 14:07:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:07:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:07:44 INFO - Display Serial Number: 1600x1200 60 14:07:44 INFO - Main Display: Yes 14:07:44 INFO - Mirror: Off 14:07:44 INFO - Online: Yes 14:07:44 INFO - Rotation: Supported 14:07:44 INFO - Return code: 0 14:07:44 INFO - Running post-action listener: _resource_record_post_action 14:07:44 INFO - [mozharness: 2017-03-20 21:07:44.242811Z] Finished run-tests step (success) 14:07:44 INFO - Running post-run listener: _resource_record_post_run 14:07:44 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 38996480; Write bytes: 512133120; Read time: 243; Write time: 1619 14:07:44 INFO - TinderboxPrint: CPU usage
54.7% 14:07:44 INFO - TinderboxPrint: I/O read bytes / time
38,996,480 / 243 14:07:44 INFO - TinderboxPrint: I/O write bytes / time
512,133,120 / 1,619 14:07:44 INFO - TinderboxPrint: CPU idle
2,174.1 (45.3%) 14:07:44 INFO - TinderboxPrint: CPU system
963.8 (20.1%) 14:07:44 INFO - TinderboxPrint: CPU user
1,659.9 (34.6%) 14:07:44 INFO - TinderboxPrint: Swap in / out
247,894,016 / 0 14:07:44 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204349952; Write bytes: 202233856; Read time: 15705; Write time: 446 14:07:44 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 36923904; Write bytes: 299012096; Read time: 223; Write time: 1137 14:07:44 INFO - Running post-run listener: _upload_blobber_files 14:07:44 INFO - Blob upload gear active. 14:07:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:07:44 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 14:07:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:07:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:07:45 INFO - (blobuploader) - INFO - Open directory for files ... 14:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:46 INFO - (blobuploader) - INFO - Done attempting. 14:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:07:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:47 INFO - (blobuploader) - INFO - Done attempting. 14:07:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:07:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:07:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:48 INFO - (blobuploader) - INFO - Done attempting. 14:07:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:07:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:07:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:50 INFO - (blobuploader) - INFO - Done attempting. 14:07:50 INFO - (blobuploader) - INFO - Iteration through files over. 14:07:50 INFO - Return code: 0 14:07:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:07:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:07:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1382f5bb060d2eb13621395209048a8173921cbcab3cb2c22ac5806bef0160c88edc9d0870163e92496aaa40d3870da514c7264601c180c7d71c20497bfe2ef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87cc08c102d076efc316930bd2e3fefcf6f6da3fa2cd85dde1259f07c67c757c33a77fc2faf0f9135e2382c2e766c7a6f532ffc7bd19676a6564aee91518a7b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/516ce7440216b0998446668d3fd1668e84566a785c631fdeeb0eac809f14dab6e92f2dc67d451526a884c14abdf9fb2029bcba755bd2beeb89580d493308dc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/870c844e2a6fc126c2ffac83d3266a081980941ff2ae9596d8d8521b2bd1447e5e2d080a957ee317332f7468cf3f24bbbe4d2146c86cb63c1448068e4c4da7d2"} 14:07:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:07:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:07:50 INFO - Contents: 14:07:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1382f5bb060d2eb13621395209048a8173921cbcab3cb2c22ac5806bef0160c88edc9d0870163e92496aaa40d3870da514c7264601c180c7d71c20497bfe2ef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87cc08c102d076efc316930bd2e3fefcf6f6da3fa2cd85dde1259f07c67c757c33a77fc2faf0f9135e2382c2e766c7a6f532ffc7bd19676a6564aee91518a7b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/516ce7440216b0998446668d3fd1668e84566a785c631fdeeb0eac809f14dab6e92f2dc67d451526a884c14abdf9fb2029bcba755bd2beeb89580d493308dc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/870c844e2a6fc126c2ffac83d3266a081980941ff2ae9596d8d8521b2bd1447e5e2d080a957ee317332f7468cf3f24bbbe4d2146c86cb63c1448068e4c4da7d2"} 14:07:50 INFO - Running post-run listener: copy_logs_to_upload_dir 14:07:50 INFO - Copying logs to upload dir... 14:07:50 INFO - mkdir: /builds/slave/test/build/upload/logs 14:07:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.972528 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-03-20 14:07:50.309055) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 14:07:50.312166) ========= 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.FqFuY9s1N4/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.YTGsqysE28/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1382f5bb060d2eb13621395209048a8173921cbcab3cb2c22ac5806bef0160c88edc9d0870163e92496aaa40d3870da514c7264601c180c7d71c20497bfe2ef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87cc08c102d076efc316930bd2e3fefcf6f6da3fa2cd85dde1259f07c67c757c33a77fc2faf0f9135e2382c2e766c7a6f532ffc7bd19676a6564aee91518a7b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/516ce7440216b0998446668d3fd1668e84566a785c631fdeeb0eac809f14dab6e92f2dc67d451526a884c14abdf9fb2029bcba755bd2beeb89580d493308dc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/870c844e2a6fc126c2ffac83d3266a081980941ff2ae9596d8d8521b2bd1447e5e2d080a957ee317332f7468cf3f24bbbe4d2146c86cb63c1448068e4c4da7d2"} build_url:https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/X8lTH_9mRAyxJj0B5N585A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1382f5bb060d2eb13621395209048a8173921cbcab3cb2c22ac5806bef0160c88edc9d0870163e92496aaa40d3870da514c7264601c180c7d71c20497bfe2ef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87cc08c102d076efc316930bd2e3fefcf6f6da3fa2cd85dde1259f07c67c757c33a77fc2faf0f9135e2382c2e766c7a6f532ffc7bd19676a6564aee91518a7b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/516ce7440216b0998446668d3fd1668e84566a785c631fdeeb0eac809f14dab6e92f2dc67d451526a884c14abdf9fb2029bcba755bd2beeb89580d493308dc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/870c844e2a6fc126c2ffac83d3266a081980941ff2ae9596d8d8521b2bd1447e5e2d080a957ee317332f7468cf3f24bbbe4d2146c86cb63c1448068e4c4da7d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 14:07:50.346719) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 14:07:50.347092) ========= 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.FqFuY9s1N4/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.YTGsqysE28/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.004453 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 14:07:50.395759) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 14:07:50.396113) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 14:07:50.396463) ========= ========= Total master_lag: 0.18 =========