builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0065 starttime: 1494466014.04 results: success (0) revision: d8762cb967423618ff0a488f14745f60964e5c49 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:54.038040) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:54.038688) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:54.039162) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:54.066135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:54.066622) ========= 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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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-05-10 18:26:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.5M=0.001s 2017-05-10 18:26:54 (17.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126474 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:54.214853) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:54.215174) ========= 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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.035072 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:54.271427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 18:26:54.271818) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d8762cb967423618ff0a488f14745f60964e5c49 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev d8762cb967423618ff0a488f14745f60964e5c49 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pnIA8PwjRW/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.zXVjzW1J1v/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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-05-10 18:26:54,371 truncating revision to first 12 chars 2017-05-10 18:26:54,372 Setting DEBUG logging. 2017-05-10 18:26:54,372 attempt 1/10 2017-05-10 18:26:54,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d8762cb96742?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-10 18:26:56,068 unpacking tar archive at: mozilla-central-d8762cb96742/testing/mozharness/ program finished with exit code 0 elapsedTime=1.964538 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 18:26:56.258137) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:56.258451) ========= script_repo_revision: d8762cb967423618ff0a488f14745f60964e5c49 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:56.258810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:56.259123) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 18:26:56.277943) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-10 18:26:56.278275) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pnIA8PwjRW/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.zXVjzW1J1v/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:26:56 INFO - MultiFileLogger online at 20170510 18:26:56 in /builds/slave/test 18:26:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:26:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:26:56 INFO - {'append_to_log': False, 18:26:56 INFO - 'base_work_dir': '/builds/slave/test', 18:26:56 INFO - 'blob_upload_branch': 'Firefox', 18:26:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:26:56 INFO - 'branch': 'Firefox', 18:26:56 INFO - 'buildbot_json_path': 'buildprops.json', 18:26:56 INFO - 'config_files': ('talos/mac_config.py',), 18:26:56 INFO - 'default_actions': ('clobber', 18:26:56 INFO - 'read-buildbot-config', 18:26:56 INFO - 'download-and-extract', 18:26:56 INFO - 'populate-webroot', 18:26:56 INFO - 'create-virtualenv', 18:26:56 INFO - 'install', 18:26:56 INFO - 'run-tests'), 18:26:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:26:56 INFO - 'download_minidump_stackwalk': True, 18:26:56 INFO - 'download_symbols': 'ondemand', 18:26:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:26:56 INFO - 'tooltool.py': '/tools/tooltool.py', 18:26:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:26:56 INFO - '/tools/misc-python/virtualenv.py')}, 18:26:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:26:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:26:56 INFO - 'gecko_profile': False, 18:26:56 INFO - 'gecko_profile_interval': 0, 18:26:56 INFO - 'installer_path': 'installer.exe', 18:26:56 INFO - 'log_level': 'info', 18:26:56 INFO - 'log_name': 'talos', 18:26:56 INFO - 'log_to_console': True, 18:26:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:26:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:26:56 INFO - 'opt_config_files': (), 18:26:56 INFO - 'pip_index': False, 18:26:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:26:56 INFO - 'cmd': ('bash', 18:26:56 INFO - '-c', 18:26:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:26:56 INFO - 'enabled': True, 18:26:56 INFO - 'halt_on_failure': False, 18:26:56 INFO - 'name': 'check_screen_resolution'},), 18:26:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:26:56 INFO - 'cmd': ('bash', 18:26:56 INFO - '-c', 18:26:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:26:56 INFO - 'enabled': True, 18:26:56 INFO - 'halt_on_failure': False, 18:26:56 INFO - 'name': 'check_screen_resolution'},), 18:26:56 INFO - 'run_cmd_checks_enabled': True, 18:26:56 INFO - 'suite': 'dromaeojs-e10s', 18:26:56 INFO - 'system_bits': '32', 18:26:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:26:56 INFO - 'title': 't-yosemite-r7-0065', 18:26:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:26:56 INFO - 'use_talos_json': True, 18:26:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:26:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:26:56 INFO - 'work_dir': 'build'} 18:26:56 INFO - [mozharness: 2017-05-11 01:26:56.493102Z] Running clobber step. 18:26:56 INFO - Running pre-action listener: _resource_record_pre_action 18:26:56 INFO - Running main action method: clobber 18:26:56 INFO - rmtree: /builds/slave/test/build 18:26:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:26:58 INFO - Running post-action listener: _resource_record_post_action 18:26:58 INFO - [mozharness: 2017-05-11 01:26:58.882336Z] Finished clobber step (success) 18:26:58 INFO - [mozharness: 2017-05-11 01:26:58.882518Z] Running read-buildbot-config step. 18:26:58 INFO - Running pre-action listener: _resource_record_pre_action 18:26:58 INFO - Running main action method: read_buildbot_config 18:26:58 INFO - Using buildbot properties: 18:26:58 INFO - { 18:26:58 INFO - "project": "", 18:26:58 INFO - "product": "firefox", 18:26:58 INFO - "who": "kwierso@gmail.com", 18:26:58 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:26:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:26:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:26:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:26:58 INFO - "stage_platform": "macosx64", 18:26:58 INFO - "basedir": "/builds/slave/test", 18:26:58 INFO - "buildnumber": 71, 18:26:58 INFO - "platform": "yosemite_r7", 18:26:58 INFO - "parent_task_id": "Ys3BKH0eQmWa1KnDy7DyhA", 18:26:58 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:26:58 INFO - "slavebuilddir": "test", 18:26:58 INFO - "taskId": "KoST7mweQNGdr4QjmerIfQ", 18:26:58 INFO - "branch": "mozilla-central", 18:26:58 INFO - "script_repo_revision": "production", 18:26:58 INFO - "revision": "d8762cb967423618ff0a488f14745f60964e5c49", 18:26:58 INFO - "slavename": "t-yosemite-r7-0065", 18:26:58 INFO - "repo_path": "mozilla-central" 18:26:58 INFO - } 18:26:58 INFO - Finding installer, test and symbols from parent task. 18:26:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KoST7mweQNGdr4QjmerIfQ'}, attempt #1 18:26:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KoST7mweQNGdr4QjmerIfQ'}, attempt #1 18:27:00 INFO - Task dependencies: UpZmctepSIiKRvgq2AmMDg 18:27:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA'}, attempt #1 18:27:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/test_packages.json 18:27:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/target.crashreporter-symbols.zip 18:27:00 INFO - Running post-action listener: _resource_record_post_action 18:27:00 INFO - [mozharness: 2017-05-11 01:27:00.592809Z] Finished read-buildbot-config step (success) 18:27:00 INFO - [mozharness: 2017-05-11 01:27:00.592919Z] Running download-and-extract step. 18:27:00 INFO - Running pre-action listener: _resource_record_pre_action 18:27:00 INFO - Running main action method: download_and_extract 18:27:00 INFO - mkdir: /builds/slave/test/build/tests 18:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:00 INFO - trying https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:27:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:27:01 INFO - Downloaded 1325 bytes. 18:27:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:27:01 INFO - Using the following test package requirements: 18:27:01 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:27:01 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:27:01 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:27:01 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:27:01 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'jsshell-mac.zip'], 18:27:01 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:27:01 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:27:01 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:27:01 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:27:01 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:27:01 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:27:01 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:27:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:27:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:27:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:27:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:27:03 INFO - Content-Length response header: 52707557 18:27:03 INFO - Bytes received: 52707557 18:27:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:27:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:27:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:27:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:27:20 INFO - Content-Length response header: 52707557 18:27:20 INFO - Bytes received: 52707557 18:27:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:27:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:27:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:27:38 INFO - Content-Length response header: 14409362 18:27:38 INFO - Bytes received: 14409362 18:27: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')]} 18:27:38 INFO - trying https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:27:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:27:40 INFO - Downloaded 75526176 bytes. 18:27:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:40 INFO - mkdir: /builds/slave/test/properties 18:27:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:27:40 INFO - Writing to file /builds/slave/test/properties/build_url 18:27:40 INFO - Contents: 18:27:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:40 INFO - Running post-action listener: _resource_record_post_action 18:27:40 INFO - Running post-action listener: set_extra_try_arguments 18:27:40 INFO - [mozharness: 2017-05-11 01:27:40.838483Z] Finished download-and-extract step (success) 18:27:40 INFO - [mozharness: 2017-05-11 01:27:40.838591Z] Running populate-webroot step. 18:27:40 INFO - Running pre-action listener: _resource_record_pre_action 18:27:40 INFO - Running main action method: populate_webroot 18:27:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:27:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:27:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:27:40 INFO - '--tppagecycles', 18:27:40 INFO - '7'], 18:27:40 INFO - 'tests': ['tsvgm']}}, 18:27:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:27:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:27:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:27:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:27:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:27:40 INFO - 'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:27:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:27:40 INFO - 'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['damp', 'tps']}, 18:27:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:27:40 INFO - 'tests': ['damp', 'tps']}, 18:27:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['dromaeo_dom']}, 18:27:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:27:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:27:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:27:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['a11yr', 18:27:40 INFO - 'ts_paint', 18:27:40 INFO - 'tpaint', 18:27:40 INFO - 'sessionrestore', 18:27:40 INFO - 'sessionrestore_no_auto_restore', 18:27:40 INFO - 'tabpaint']}, 18:27:40 INFO - 'other-e10s': {'tests': ['a11yr', 18:27:40 INFO - 'ts_paint', 18:27:40 INFO - 'tpaint', 18:27:40 INFO - 'sessionrestore', 18:27:40 INFO - 'sessionrestore_no_auto_restore', 18:27:40 INFO - 'tabpaint']}, 18:27:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:27:40 INFO - 'ts_paint', 18:27:40 INFO - 'tpaint', 18:27:40 INFO - 'sessionrestore', 18:27:40 INFO - 'sessionrestore_no_auto_restore', 18:27:40 INFO - 'tabpaint']}, 18:27:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:27:40 INFO - 'ts_paint', 18:27:40 INFO - 'tpaint', 18:27:40 INFO - 'sessionrestore', 18:27:40 INFO - 'sessionrestore_no_auto_restore', 18:27:40 INFO - 'tabpaint']}, 18:27:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['a11yr', 18:27:40 INFO - 'ts_paint', 18:27:40 INFO - 'tpaint', 18:27:40 INFO - 'sessionrestore', 18:27:40 INFO - 'sessionrestore_no_auto_restore', 18:27:40 INFO - 'tabpaint']}, 18:27:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['a11yr', 18:27:40 INFO - 'ts_paint', 18:27:40 INFO - 'tpaint', 18:27:40 INFO - 'sessionrestore', 18:27:40 INFO - 'sessionrestore_no_auto_restore', 18:27:40 INFO - 'tabpaint']}, 18:27:40 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:27:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:27:40 INFO - 'bloom_basic_ref']}, 18:27:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['tsvgx', 18:27:40 INFO - 'tsvgr_opacity', 18:27:40 INFO - 'tart', 18:27:40 INFO - 'tscrollx', 18:27:40 INFO - 'cart', 18:27:40 INFO - 'tsvg_static']}, 18:27:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:27:40 INFO - 'tsvgr_opacity', 18:27:40 INFO - 'tart', 18:27:40 INFO - 'tscrollx', 18:27:40 INFO - 'cart', 18:27:40 INFO - 'tsvg_static']}, 18:27:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:27:40 INFO - 'talos_options': ['--disable-e10s'], 18:27:40 INFO - 'tests': ['tp5o']}, 18:27:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:27:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:27:40 INFO - 'talos_options': ['--disable-e10s', 18:27:40 INFO - '--xperf_path', 18:27:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:40 INFO - 'tests': ['tp5n']}, 18:27:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:27:40 INFO - 'talos_options': ['--xperf_path', 18:27:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:40 INFO - 'tests': ['tp5n']}}, 18:27:40 INFO - 'talos.zip': {'path': '', 18:27:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:27:40 INFO - Running post-action listener: _resource_record_post_action 18:27:40 INFO - [mozharness: 2017-05-11 01:27:40.859645Z] Finished populate-webroot step (success) 18:27:40 INFO - [mozharness: 2017-05-11 01:27:40.859768Z] Running create-virtualenv step. 18:27:40 INFO - Running pre-action listener: _resource_record_pre_action 18:27:40 INFO - Running main action method: create_virtualenv 18:27:40 INFO - Creating virtualenv /builds/slave/test/build/venv 18:27:40 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 18:27:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:27:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:27:40 INFO - Using real prefix '/tools/python27' 18:27:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:27:41 INFO - Installing distribute.............................................................................................................................................................................................done. 18:27:44 INFO - Installing pip.................done. 18:27:44 INFO - Return code: 0 18:27:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:27:44 INFO - Reading from file tmpfile_stdout 18:27:44 INFO - Output received: 18:27:44 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) 18:27:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:27: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')]} 18:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:27: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', 'pip>=1.5'] in /builds/slave/test/build 18:27: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 pip>=1.5 18:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:44 INFO - 'HOME': '/Users/cltbld', 18:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:44 INFO - 'LOGNAME': 'cltbld', 18:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:44 INFO - 'MOZ_NO_REMOTE': '1', 18:27:44 INFO - 'NO_EM_RESTART': '1', 18:27:44 INFO - 'PAGER': '/bin/cat', 18:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:44 INFO - 'PWD': '/builds/slave/test', 18:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:44 INFO - 'SHELL': '/bin/bash', 18:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:27:44 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:44 INFO - 'USER': 'cltbld', 18:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:44 INFO - 'XPC_FLAGS': '0x0', 18:27:44 INFO - 'XPC_SERVICE_NAME': '0', 18:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:45 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 18:27:45 INFO - Cleaning up... 18:27:45 INFO - Return code: 0 18:27:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:27:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:45 INFO - 'HOME': '/Users/cltbld', 18:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:45 INFO - 'LOGNAME': 'cltbld', 18:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:45 INFO - 'MOZ_NO_REMOTE': '1', 18:27:45 INFO - 'NO_EM_RESTART': '1', 18:27:45 INFO - 'PAGER': '/bin/cat', 18:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:45 INFO - 'PWD': '/builds/slave/test', 18:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:45 INFO - 'SHELL': '/bin/bash', 18:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:27:45 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:45 INFO - 'USER': 'cltbld', 18:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:45 INFO - 'XPC_FLAGS': '0x0', 18:27:45 INFO - 'XPC_SERVICE_NAME': '0', 18:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:45 INFO - Downloading/unpacking psutil>=3.1.1 18:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:27:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:47 INFO - Installing collected packages: psutil 18:27:47 INFO - Running setup.py install for psutil 18:27:47 INFO - building 'psutil._psutil_osx' extension 18:27:47 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 18:27:48 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 18:27:48 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 18:27:48 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 18:27:48 INFO - building 'psutil._psutil_posix' extension 18:27:48 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 18:27:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:27:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:27:48 INFO - ^ 18:27:48 INFO - 1 warning generated. 18:27:48 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 18:27:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:48 INFO - Successfully installed psutil 18:27:48 INFO - Cleaning up... 18:27:48 INFO - Return code: 0 18:27:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:27:48 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')]} 18:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:48 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')]} 18:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:48 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 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:27:48 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 18:27:48 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 18:27:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:27:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:48 INFO - 'HOME': '/Users/cltbld', 18:27:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:48 INFO - 'LOGNAME': 'cltbld', 18:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:48 INFO - 'MOZ_NO_REMOTE': '1', 18:27:48 INFO - 'NO_EM_RESTART': '1', 18:27:48 INFO - 'PAGER': '/bin/cat', 18:27:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:48 INFO - 'PWD': '/builds/slave/test', 18:27:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:48 INFO - 'SHELL': '/bin/bash', 18:27:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:27:48 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:27:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:48 INFO - 'USER': 'cltbld', 18:27:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:48 INFO - 'XPC_FLAGS': '0x0', 18:27:48 INFO - 'XPC_SERVICE_NAME': '0', 18:27:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:27:50 INFO - Installing collected packages: mozsystemmonitor 18:27:50 INFO - Running setup.py install for mozsystemmonitor 18:27:50 INFO - Successfully installed mozsystemmonitor 18:27:50 INFO - Cleaning up... 18:27:50 INFO - Return code: 0 18:27:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:51 INFO - 'HOME': '/Users/cltbld', 18:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:51 INFO - 'LOGNAME': 'cltbld', 18:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:51 INFO - 'MOZ_NO_REMOTE': '1', 18:27:51 INFO - 'NO_EM_RESTART': '1', 18:27:51 INFO - 'PAGER': '/bin/cat', 18:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:51 INFO - 'PWD': '/builds/slave/test', 18:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:51 INFO - 'SHELL': '/bin/bash', 18:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:27:51 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:51 INFO - 'USER': 'cltbld', 18:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:51 INFO - 'XPC_FLAGS': '0x0', 18:27:51 INFO - 'XPC_SERVICE_NAME': '0', 18:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:51 INFO - Downloading/unpacking jsonschema==2.5.1 18:27:51 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 18:27:51 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 18:27:51 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 18:27:51 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 18:27:51 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 18:27:51 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 18:27:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:27:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:27:53 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 18:27:53 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 18:27:53 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 18:27:53 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 18:27:53 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 18:27:53 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 18:27:53 INFO - Downloading functools32-3.2.3-2.tar.gz 18:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:27:53 INFO - warning: no files found matching '*.txt' 18:27:53 INFO - no previously-included directories found matching 'build' 18:27:53 INFO - no previously-included directories found matching 'dist' 18:27:53 INFO - no previously-included directories found matching '.git*' 18:27:53 INFO - Installing collected packages: jsonschema, functools32 18:27:53 INFO - Running setup.py install for functools32 18:27:53 INFO - warning: no files found matching '*.txt' 18:27:53 INFO - no previously-included directories found matching 'build' 18:27:53 INFO - no previously-included directories found matching 'dist' 18:27:53 INFO - no previously-included directories found matching '.git*' 18:27:53 INFO - Successfully installed jsonschema functools32 18:27:53 INFO - Cleaning up... 18:27:54 INFO - Return code: 0 18:27:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:27:54 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')]} 18:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:54 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')]} 18:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:54 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 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:27:54 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 18:27:54 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 18:27:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:27:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:54 INFO - 'HOME': '/Users/cltbld', 18:27:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:54 INFO - 'LOGNAME': 'cltbld', 18:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:54 INFO - 'MOZ_NO_REMOTE': '1', 18:27:54 INFO - 'NO_EM_RESTART': '1', 18:27:54 INFO - 'PAGER': '/bin/cat', 18:27:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:54 INFO - 'PWD': '/builds/slave/test', 18:27:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:54 INFO - 'SHELL': '/bin/bash', 18:27:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:27:54 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:27:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:54 INFO - 'USER': 'cltbld', 18:27:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:54 INFO - 'XPC_FLAGS': '0x0', 18:27:54 INFO - 'XPC_SERVICE_NAME': '0', 18:27:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:27:54 INFO - Cleaning up... 18:27:54 INFO - Return code: 0 18:27:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:27:54 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')]} 18:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:54 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')]} 18:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:54 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 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:27:54 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 18:27:54 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 18:27:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:27:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:54 INFO - 'HOME': '/Users/cltbld', 18:27:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:54 INFO - 'LOGNAME': 'cltbld', 18:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:54 INFO - 'MOZ_NO_REMOTE': '1', 18:27:54 INFO - 'NO_EM_RESTART': '1', 18:27:54 INFO - 'PAGER': '/bin/cat', 18:27:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:54 INFO - 'PWD': '/builds/slave/test', 18:27:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:54 INFO - 'SHELL': '/bin/bash', 18:27:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:27:54 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:27:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:54 INFO - 'USER': 'cltbld', 18:27:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:54 INFO - 'XPC_FLAGS': '0x0', 18:27:54 INFO - 'XPC_SERVICE_NAME': '0', 18:27:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:27:54 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 18:27:54 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 18:27:54 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 18:27:54 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 18:27:54 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 18:27:54 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 18:27:55 INFO - Downloading blobuploader-1.2.4.tar.gz 18:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:27:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:27:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 INFO - Downloading docopt-0.6.1.tar.gz 18:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:27:56 INFO - Installing collected packages: blobuploader, requests, docopt 18:27:56 INFO - Running setup.py install for blobuploader 18:27:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:27:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:27:57 INFO - Running setup.py install for requests 18:27:57 INFO - Running setup.py install for docopt 18:27:57 INFO - Successfully installed blobuploader requests docopt 18:27:57 INFO - Cleaning up... 18:27:57 INFO - Return code: 0 18:27:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:57 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')]} 18:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:57 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')]} 18:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:57 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 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:27:57 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 18:27:57 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 18:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:57 INFO - 'HOME': '/Users/cltbld', 18:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:57 INFO - 'LOGNAME': 'cltbld', 18:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:57 INFO - 'MOZ_NO_REMOTE': '1', 18:27:57 INFO - 'NO_EM_RESTART': '1', 18:27:57 INFO - 'PAGER': '/bin/cat', 18:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:57 INFO - 'PWD': '/builds/slave/test', 18:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:57 INFO - 'SHELL': '/bin/bash', 18:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:27:57 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:57 INFO - 'USER': 'cltbld', 18:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:57 INFO - 'XPC_FLAGS': '0x0', 18:27:57 INFO - 'XPC_SERVICE_NAME': '0', 18:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Y0cqor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-xn7GPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-bVn_UT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-C8cBcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-bmaWkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-sQLohi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Fvz90j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-zVd70H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-hRjVr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:27:58 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-EbbW8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:27:59 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-DeF5iZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:27:59 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-W8a0aT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:27:59 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-tvHJ80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:27:59 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-uMZy3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:59 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-b73sAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:27:59 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-q7Kzb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:27:59 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-WnTdOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:27:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:27:59 INFO - Running setup.py install for manifestparser 18:27:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:27:59 INFO - Running setup.py install for mozcrash 18:28:00 INFO - Running setup.py install for mozdebug 18:28:00 INFO - Running setup.py install for mozdevice 18:28:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:28:00 INFO - Running setup.py install for mozfile 18:28:00 INFO - Running setup.py install for mozhttpd 18:28:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:28:00 INFO - Running setup.py install for mozinfo 18:28:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:28:00 INFO - Running setup.py install for mozInstall 18:28:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:28:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:28:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:28:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:28:00 INFO - Running setup.py install for mozleak 18:28:01 INFO - Running setup.py install for mozlog 18:28:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:28:01 INFO - Running setup.py install for moznetwork 18:28:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:28:01 INFO - Running setup.py install for mozprocess 18:28:01 INFO - Running setup.py install for mozprofile 18:28:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:28:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:28:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:28:01 INFO - Running setup.py install for mozrunner 18:28:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:28:01 INFO - Running setup.py install for mozscreenshot 18:28:02 INFO - Running setup.py install for moztest 18:28:02 INFO - Running setup.py install for mozversion 18:28:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:28:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:28:02 INFO - Cleaning up... 18:28:02 INFO - Return code: 0 18:28:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:02 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')]} 18:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:02 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')]} 18:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:02 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 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:28:02 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 18:28:02 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 18:28:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:28:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:02 INFO - 'HOME': '/Users/cltbld', 18:28:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:02 INFO - 'LOGNAME': 'cltbld', 18:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:02 INFO - 'MOZ_NO_REMOTE': '1', 18:28:02 INFO - 'NO_EM_RESTART': '1', 18:28:02 INFO - 'PAGER': '/bin/cat', 18:28:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:02 INFO - 'PWD': '/builds/slave/test', 18:28:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:02 INFO - 'SHELL': '/bin/bash', 18:28:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:28:02 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:28:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:02 INFO - 'USER': 'cltbld', 18:28:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:02 INFO - 'XPC_FLAGS': '0x0', 18:28:02 INFO - 'XPC_SERVICE_NAME': '0', 18:28:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:28:02 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-m4jroB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:28:02 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)) 18:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:28:02 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-1y2zFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:28:02 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)) 18:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:28:02 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-WjQYoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:28:02 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)) 18:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:28:02 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-HkPgQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-wIamQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-xxbVdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-XhiJH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Mioc3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Yqmxu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Z8lell-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-jc5ijQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-gxlmbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:28:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-hPO8DP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:28:03 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)) 18:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:28:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-hBldQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:28:04 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)) 18:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-u9bDC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:04 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)) 18:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:28:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-eXTQuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:28:04 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)) 18:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:28:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-f_WwzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:28:04 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:28:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:28:04 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 18:28:04 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 18:28:04 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 18:28:04 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 18:28:04 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 18:28:04 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 18:28:05 INFO - Downloading blessings-1.6.tar.gz 18:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:28:05 INFO - Installing collected packages: blessings 18:28:05 INFO - Running setup.py install for blessings 18:28:06 INFO - Successfully installed blessings 18:28:06 INFO - Cleaning up... 18:28:06 INFO - Return code: 0 18:28:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:06 INFO - Reading from file tmpfile_stdout 18:28:06 INFO - Current package versions: 18:28:06 INFO - blessings == 1.6 18:28:06 INFO - blobuploader == 1.2.4 18:28:06 INFO - docopt == 0.6.1 18:28:06 INFO - functools32 == 3.2.3-2 18:28:06 INFO - jsonschema == 2.5.1 18:28:06 INFO - manifestparser == 1.1 18:28:06 INFO - mozInstall == 1.12 18:28:06 INFO - mozcrash == 1.0 18:28:06 INFO - mozdebug == 0.1 18:28:06 INFO - mozdevice == 0.50 18:28:06 INFO - mozfile == 1.2 18:28:06 INFO - mozhttpd == 0.7 18:28:06 INFO - mozinfo == 0.9 18:28:06 INFO - mozleak == 0.1 18:28:06 INFO - mozlog == 3.4 18:28:06 INFO - moznetwork == 0.27 18:28:06 INFO - mozprocess == 0.25 18:28:06 INFO - mozprofile == 0.28 18:28:06 INFO - mozrunner == 6.13 18:28:06 INFO - mozscreenshot == 0.1 18:28:06 INFO - mozsystemmonitor == 0.3 18:28:06 INFO - moztest == 0.8 18:28:06 INFO - mozversion == 1.4 18:28:06 INFO - psutil == 3.1.1 18:28:06 INFO - requests == 1.2.3 18:28:06 INFO - wsgiref == 0.1.2 18:28:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:06 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')]} 18:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:06 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')]} 18:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:06 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 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:28:06 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 18:28:06 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 18:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:06 INFO - 'HOME': '/Users/cltbld', 18:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:06 INFO - 'LOGNAME': 'cltbld', 18:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:06 INFO - 'MOZ_NO_REMOTE': '1', 18:28:06 INFO - 'NO_EM_RESTART': '1', 18:28:06 INFO - 'PAGER': '/bin/cat', 18:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:06 INFO - 'PWD': '/builds/slave/test', 18:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:06 INFO - 'SHELL': '/bin/bash', 18:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:28:06 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:06 INFO - 'USER': 'cltbld', 18:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:06 INFO - 'XPC_FLAGS': '0x0', 18:28:06 INFO - 'XPC_SERVICE_NAME': '0', 18:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 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)) 18:28:06 INFO - Cleaning up... 18:28:06 INFO - Return code: 0 18:28:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:28:06 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')]} 18:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:06 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')]} 18:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:06 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 0x10e17db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e180768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980d67250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, '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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 18:28:06 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 18:28:06 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 18:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:06 INFO - 'HOME': '/Users/cltbld', 18:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:06 INFO - 'LOGNAME': 'cltbld', 18:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:06 INFO - 'MOZ_NO_REMOTE': '1', 18:28:06 INFO - 'NO_EM_RESTART': '1', 18:28:06 INFO - 'PAGER': '/bin/cat', 18:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:06 INFO - 'PWD': '/builds/slave/test', 18:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:06 INFO - 'SHELL': '/bin/bash', 18:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:28:06 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:06 INFO - 'USER': 'cltbld', 18:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:06 INFO - 'XPC_FLAGS': '0x0', 18:28:06 INFO - 'XPC_SERVICE_NAME': '0', 18:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:28:07 INFO - Cleaning up... 18:28:07 INFO - Return code: 0 18:28:07 INFO - Running post-action listener: _resource_record_post_action 18:28:07 INFO - Running post-action listener: _start_resource_monitoring 18:28:07 INFO - Starting resource monitoring. 18:28:07 INFO - [mozharness: 2017-05-11 01:28:07.076101Z] Finished create-virtualenv step (success) 18:28:07 INFO - [mozharness: 2017-05-11 01:28:07.077102Z] Running install step. 18:28:07 INFO - Running pre-action listener: _resource_record_pre_action 18:28:07 INFO - Running main action method: install 18:28:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:07 INFO - Reading from file tmpfile_stdout 18:28:07 INFO - Detecting whether we're running mozinstall >=1.0... 18:28:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:28:07 INFO - Reading from file tmpfile_stdout 18:28:07 INFO - Output received: 18:28:07 INFO - Usage: mozinstall [options] installer 18:28:07 INFO - Options: 18:28:07 INFO - -h, --help show this help message and exit 18:28:07 INFO - -d DEST, --destination=DEST 18:28:07 INFO - Directory to install application into. [default: 18:28:07 INFO - "/builds/slave/test"] 18:28:07 INFO - --app=APP Application being installed. [default: firefox] 18:28:07 INFO - mkdir: /builds/slave/test/build/application 18:28:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:28:27 INFO - Reading from file tmpfile_stdout 18:28:27 INFO - Output received: 18:28:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:28:27 INFO - Running post-action listener: _resource_record_post_action 18:28:27 INFO - [mozharness: 2017-05-11 01:28:27.920835Z] Finished install step (success) 18:28:27 INFO - [mozharness: 2017-05-11 01:28:27.920992Z] Running run-tests step. 18:28:27 INFO - Running pre-action listener: _resource_record_pre_action 18:28:27 INFO - Running main action method: run_tests 18:28:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:28:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:28:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:28:27 INFO - 2017-05-10 18:28:27.935 screenresolution[1621:7408] starting screenresolution argv=screenresolution get 18:28:27 INFO - 2017-05-10 18:28:27.937 screenresolution[1621:7408] Display 0: 1600x1200x32@60 18:28:27 INFO - 2017-05-10 18:28:27.943 screenresolution[1622:7411] starting screenresolution argv=screenresolution list 18:28:27 INFO - Available Modes on Display 0 18:28:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:28:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:28:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:28:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:28:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:28:28 INFO - Intel Iris: 18:28:28 INFO - Chipset Model: Intel Iris 18:28:28 INFO - Type: GPU 18:28:28 INFO - Bus: Built-In 18:28:28 INFO - VRAM (Dynamic, Max): 1536 MB 18:28:28 INFO - Vendor: Intel (0x8086) 18:28:28 INFO - Device ID: 0x0a2e 18:28:28 INFO - Revision ID: 0x0009 18:28:28 INFO - Displays: 18:28:28 INFO - Display: 18:28:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:28:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:28:28 INFO - Display Serial Number: 1600x1200 60 18:28:28 INFO - Main Display: Yes 18:28:28 INFO - Mirror: Off 18:28:28 INFO - Online: Yes 18:28:28 INFO - Rotation: Supported 18:28:28 INFO - Return code: 0 18:28:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:28:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:28:28 INFO - Python 2.7.3 18:28:28 INFO - Return code: 0 18:28:28 INFO - grabbing minidump binary from tooltool 18:28: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')]} 18:28:28 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 0x10de8ed30>, '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 0x10de41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de82be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:28:28 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 18:28:28 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 18:28:28 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 18:28:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:28:28 INFO - Return code: 0 18:28:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:28:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:28:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:28:28 INFO - ENV: RUST_BACKTRACE is now 1 18:28:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:28:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:28:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--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 18:28:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0065 --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 18:28:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pnIA8PwjRW/Render', 18:28:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:28 INFO - 'HOME': '/Users/cltbld', 18:28:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:28 INFO - 'LOGNAME': 'cltbld', 18:28:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:28:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:28 INFO - 'MOZ_NO_REMOTE': '1', 18:28:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:28:28 INFO - 'NO_EM_RESTART': '1', 18:28:28 INFO - 'PAGER': '/bin/cat', 18:28:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:28 INFO - 'PWD': '/builds/slave/test', 18:28:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:28:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:28 INFO - 'RUST_BACKTRACE': '1', 18:28:28 INFO - 'SHELL': '/bin/bash', 18:28:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zXVjzW1J1v/Listeners', 18:28:28 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 18:28:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:28 INFO - 'USER': 'cltbld', 18:28:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:28 INFO - 'XPC_FLAGS': '0x0', 18:28:28 INFO - 'XPC_SERVICE_NAME': '0', 18:28:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--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 18:28:28 INFO - mozversion application_buildid: 20170510165125 18:28:28 INFO - mozversion application_changeset: d8762cb967423618ff0a488f14745f60964e5c49 18:28:28 INFO - mozversion application_display_name: Nightly 18:28:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:28:28 INFO - mozversion application_name: Firefox 18:28:28 INFO - mozversion application_remotingname: firefox 18:28:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:28:28 INFO - mozversion application_vendor: Mozilla 18:28:28 INFO - mozversion application_version: 55.0a1 18:28:28 INFO - mozversion platform_buildid: 20170510165125 18:28:28 INFO - mozversion platform_changeset: d8762cb967423618ff0a488f14745f60964e5c49 18:28:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:28:28 INFO - mozversion platform_version: 55.0a1 18:28:28 INFO - using testdate: 1494466108 18:28:28 INFO - actual date: 1494466108 18:28:28 INFO - starting webserver on 'localhost:49245' 18:28:28 INFO - SUITE-START | Running 2 tests 18:28:28 INFO - TEST-START | dromaeo_css 18:28:28 INFO - operating with platform_type : mac_ 18:28:28 INFO - Initialising browser for dromaeo_css test... 18:28:28 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpQ9anMB/profile http://localhost:49245/getInfo.html) 18:28:31 INFO - PID 1632 | 2017-05-10 18:28:31.003 plugin-container[1634:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 18:28:31 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:31 INFO - PID 1632 | 2017-05-10 18:28:31.006 plugin-container[1634:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 18:28:31 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:31 INFO - PID 1632 | 2017-05-10 18:28:31.006 plugin-container[1634:7598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:28:31 INFO - PID 1632 | __metrics Screen width/height:1600/1200 18:28:31 INFO - PID 1632 | colorDepth:24 18:28:31 INFO - PID 1632 | Browser inner width/height: 1024/691 18:28:31 INFO - PID 1632 | __metrics 18:28:31 INFO - PID 1632 | 18:28:31 INFO - PID 1632 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 18:28:31 INFO - PID 1632 | 18:28:32 INFO - TEST-INFO | 1632: exit 0 18:28:32 INFO - Browser initialized. 18:28:32 INFO - Running cycle 1/1 for dromaeo_css test... 18:28:32 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpQ9anMB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:34 INFO - PID 1636 | 2017-05-10 18:28:34.076 plugin-container[1637:7758] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4637, name = 'com.apple.tsm.portname' 18:28:34 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:34 INFO - PID 1636 | 2017-05-10 18:28:34.077 plugin-container[1637:7758] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 18:28:34 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:34 INFO - PID 1636 | 2017-05-10 18:28:34.077 plugin-container[1637:7758] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:28:34 INFO - PID 1636 | 2017-05-10 18:28:34.787 plugin-container[1638:7808] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 18:28:34 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:34 INFO - PID 1636 | 2017-05-10 18:28:34.788 plugin-container[1638:7808] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 18:28:34 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:34 INFO - PID 1636 | 2017-05-10 18:28:34.788 plugin-container[1638:7808] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:28:44 INFO - PID 1636 | RSS: Main: 228413440 18:28:44 INFO - PID 1636 | 18:31:47 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 18:31:49 INFO - PID 1636 | RSS: Main: 251703296 18:31:49 INFO - PID 1636 | 18:34:51 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 18:34:51 INFO - PID 1636 | RSS: Main: 256634880 18:34:51 INFO - PID 1636 | 18:37:53 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 18:37:53 INFO - PID 1636 | RSS: Main: 267247616 18:37:53 INFO - PID 1636 | 18:40:55 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 18:40:56 INFO - PID 1636 | RSS: Main: 260108288 18:40:56 INFO - PID 1636 | 18:43:59 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 18:43:59 INFO - PID 1636 | RSS: Main: 263864320 18:43:59 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 18:47:01 INFO - PID 1636 | RSS: Main: 262541312 18:47:01 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | __start_tp_report 18:47:01 INFO - PID 1636 | _x_x_mozilla_page_load 18:47:01 INFO - PID 1636 | _x_x_mozilla_page_load_details 18:47:01 INFO - PID 1636 | |i|pagename|runs| 18:47:01 INFO - PID 1636 | |0;dojo.html;2820.7602353799925;3627.818609069546;3638.1632224588343;3655.03141667458;3692.61227571105;6070.9392906070925;6135.232666669988;6270.52971027173;6273.592216505924;6295.6537390443455;6210.8136755897285;6237.625742455456;6245.250569931603;6257.4368306852375;6313.40022697843;5494.478024587658;5495.670259784406;5529.778808847651;5556.777728890839;5561.360443548983;5589.888202235973;5651.1523271509395;5661.801836935708;5683.488486036256;5714.857128571798;5417.6207665463435;5530.889382212374;5574.9721251393885;5622.578306626999;5644.266245388114;5474.7262636868;5537.833864984057;5555.722213889329;5571;5616.550675945915;5491.148871924858;5508.44915508446;5514.724263786795;5535.667859928377;5555.972220138873;6091.451769340736;6244.687765611701;6291.433770960635;6306.211723534559;6314.905276420857;6560.737570497175;6646.700898459581;6703.899441508382;6801.557898736566;6817.5909445433435;5733.541316694655;6892.620905850176;6900.275471075515;6921.8961715574305;6923.273056329113;6296.338884417162;6320.304766475684;6376.0754690570075;6390.424861762417;6391.58454700443;6767.39093481589;6935.895961560581;6940.167179938439;7013.824654383681;7081.291870812981;6106.096658337741;6110.052941794029;6672.9332706673285;6706.329367063255;6712.463002959751;6800.93199068003;6844.726210951606;6850.38346548803;6857.828554286183;6873.965630171917;6158.661273629993;6524;6526.641034743135;6741.292164322774;6759.155105611799;6396.200474940632;6594.241662208811;6689.732410703615;6744.730210791513;6819;5276.841694749163;5329.25390445338;5339.385970613352;5354.116570765858;5371.274877891513;4088.9182216356103;4171.4368560243975;4207.810648520824;4731.787069581808;4832.057748739033;6043.425874541911;6241.812745617639;6321.336259692758;6375.681215939185;6426.036094585849;6936.549124306904;6952.791416257419;6957.886738121876;6968.860622787516;6980.371766541036;5515.558755299647;6316.336784637547;6410.743570257137;6431.549791514549;6448.097266382711;5810.448007439287;5938.732757025944;5952.464278214981;5960.821175364746;6063.54523410746;6039.4262545058145;7034.085568876013;7061.6469176542;7076.71693132269;7092.184398794205;6657.833554161185;6761.188657360952;6901.689423976033;6902;6937.861242775217;6946.374826265458;7076.787696369117;7087.078679771596;7102.538335008208;7116.537425067354;6517.250516190509;6777.762778302933;6797.592144471149;6812;6827.2490026098085;5581.302337207849;6750.426213771688;6753.054572359972;6754.324567543206;6853.623050732058;5228.555572776205;6436.871262574628;6655.101561289164;6693.564918280296;6695.06269122333;6661.4004739573675;6689.46484281266;6696.732130714717;6699.196096468455;6702.564333318319;6309.1167236587835;6342.651154186564;6441.098246245436;6479.51403644738;6542.149520562297;6658.800235992928;6668.733250669919;6671.833204169935;6686.632235226914;6745.561538500178;6496.7726129585235;6524.510661700487;6528.934710652833;6534.738610455529;6552.410283074546 18:47:01 INFO - PID 1636 | |1;ext.html;794.5113755040649;809.3808236698926;1070.7109080548253;1238.510788238645;1245.4271035323757;29245.707542924563;29282.707172928265;33351.833240833796;37216.441753373685;37305.81347093268;36627.81686091572;36748.44877326842;37158.44262336061;37192.81403592985;37220;6062.333143354227;6744.75222083916;7683.769486915374;7718.807029824257;7885.763427097168;8285.254327110559;8375.329973602098;8379.539125348103;8389.161083891622;8401.327893768514;6911.6889739961825;7003.684834182474;8279.337652987777;8384.455010424334;8400.45397049193;6961.721531138748;8329.625166867505;8345.749627511153;8374.246317831394;8375.246227839523;7284.344409003163;8145.104038555754;8165.63254653542;8223.712170074079;8235.794105147359;36539;36698.44952325717;36787.63212367869;36789;36801.44797828035;3093.3967876264137;3523.048776830267;3603.0091724775634;3629.4192929131214;3631.7276204284785;30287.637056332518;30354.93757718491;30389.24026899323;30405.391892162253;30417;2897.985510072457;2924.853757312147;2926.853657317147;2934.985325073361;2945.661248956376;257865.71067144544;269692;288902;289205;290031.5498422515;37867.86396408112;38582.42126368107;47518.76240618775;47594.04811903743;47670;48349.274760878616;48754.26868596974;48785.268220976715;48959;49052;13903.026788124735;14054.929725351309;14129.081609695337;14151.009429340053;14174.291285435687;11199.776004480027;11412.37231952234;11439.59961401347;11442.656720298404;11443.771124577628;13345.66635834112;13398;13401.731965360637;13409.128406653732;13542.45830166802;5948.7323070461935;5995.61028533144;6026.728797204137;6036.094585812076;6041.459427845871;1018.7809620931536;1027.111548510533;1027.6916924922493;1049.3179433368218;1060.3531845574194;21091.68362474564;23255.418614534432;24341;24374.512509749704;24421.389465263153;1107.1198397274186;1134.8297755336766;1142.4230763464398;1151.4185336405062;1155.7284038251005;10455.895441045644;11606.187566870238;12347.135700501058;12471.875281247252;12477.937610312072;308143;330381;330772;331287;331455;52213;52510;52550.21174682383;52648.47351526513;52655.73672131615;1470.3383477435198;1490.8956373054;1535.917178389233;1538.7845701601784;1561.6564355841697;24056.594340565804;24062.037518499765;24091.638625420983;24291.271261862174;24301.39246518851;37494.625053749114;37617.87146385613;37622;37646;37649.24701506009;274788;276777;278074;278208;278466;32715;32815.34369312648;33107;33180.83409582936;33206.83396583002;41245;43510;43514.782426087666;43584.56415435805;43787.34318985154;24248.515029698956;28802.567961481014;29688.406231875673;30176.24559386033;30583.694163058084;24535.50928981446;28169.732362044168;29407.03005094285;30140.84929575338;30879.919202827794;31644.367112657157;36320.636793631726;36757.44863827098;36765.81617091897;36783.63216367909;24786.752132478443;27600.585991209748;28947;30750;30937.0718878434;20257.59484810325;23887.641685374387;24080.87959560191;24291.149809756575;24442 18:47:01 INFO - PID 1636 | |2;jquery.html;438.27684320870577;441.8033974881178;442.5729171349647;447.49433140551173;463.7148153885361;19797.911114888677;21552.245671401502;22317.77682223177;22350.088734471443;22365.55268894623;19110.904445477754;22220.666689999664;23380.88309558454;23475.41311467214;23701.170459033936;5643.927653745795;5950.37521060289;6182.629042257457;6210.1616281801835;6215.440610345068;5595.524380427669;6127.325994140641;6150.630962142263;6152.169457123276;6155.230596175478;5132.255822905695;5877.970610146965;5944.256967879015;6058.969705151468;6113.633182009094;6055.333913269559;6121.938780612181;6128.509719222496;6132.110843927606;6139.355367686396;5361.597880159004;5377.327834020747;5428.348598168205;5439.3200849893765;5450.645708028966;27458.5881211782;27533.86233068842;27554.448911021667;27600;27664;1909.6467153576493;1961.8136277053657;1964.5481539245911;1969.7537807774029;1969.8424126070024;14023.719525609533;18027.008514531695;18155.27378904842;18279.90320580756;18547.629047418977;2130.2650585547963;2165.101482884601;2202.50443650179;2206.5255969966374;2209.668549717539;155561;156768.21615891962;157849;159765.99999999884;159851;12392;12796.360181990864;13080.28058456794;13136.949044076451;13157.473701051851;16559.337626495046;16700.331986720637;16705.41531046432;16730.414435494942;16769.580760481087;8807.269746378643;9146.725598232064;9155.496447695441;9161.633534658538;9290.44257344548;8622.784430389165;8670.783230419163;8675.219230269306;8739.694110706096;8805.735827925173;8044.155363686846;8090.919090809134;8206;8210.58947052657;8224.547649879194;2292.32376448948;2293.1515339324224;2298.678185054094;2300.9309720708407;2303.608386574286;849.8427790858711;884.4648987362553;1002.8044531316323;1009.6769033909079;1032.6850310655323;20739.79260207409;21187.894060529597;21268.893655531625;21283.78716212849;21286.254981075897;824.814416756237;1032.8915463876185;1054.7890421915647;1080.8054550180887;1086.2721976275757;8349.791255218668;8445.310937812339;10868.347899126078;10965.671029869116;11012.339259644446;390664.7736929974;535083;535912;544763;548444;130662.34668826596;134280;136149.3192534031;136370;139337.3033134828;1221.859486159103;1259.596928982735;1278.6483716977905;1282.6280378690262;1284.178125999361;19185.8081419184;21107.683384748932;21396.358109256747;21518.784812151676;22666.546669066196;56000;56211;56397;56967.14549281681;57138;78596;80028.59985700034;81184;82714;84393;7309.4152467803515;8959.1936725695;9027.09729027092;9129.087091290818;9156.221721153777;14373.425062997781;14419.78370324425;14439.566812995627;14553.448967364478;14824.851751482347;11818.349990750592;12050.09624278165;12220.45007974661;12234.204776689487;12283.631491055283;8975.461472311683;11076.778464430827;11328;11571.016463600688;11598.94200528992;9934.304598678023;10784.651918510186;11332.376719280519;11977.161598688357;12406;8812.779680508038;8879.334049946408;8949.239314658327;9279.582418791035;9280.025597312455;10092.24308176875;12071.456784444897;12171.235170899992;12302.507899683913;12324.876751232372 18:47:01 INFO - PID 1636 | |3;mootools.html;1435.3038776193548;1473.0940471609963;1474.9410023599057;1495.0954672423184;1503.7894694742733;11251;11843.940780296109;11891.702707432307;11899.52401903925;12164.93917530411;11848;11853.466594003268;11981;11994;12007.03943684503;2430.9392265193333;2483.615039668854;2484.85090894546;2485.8135639826996;2486.428121532049;2414.5895197816412;2441.5483135619966;2450.5588993981078;2475.6410320503514;2485.2668462803517;2437.293184976355;2445.5231229910173;2449.9142530011454;2483.5653760591913;2487.9253622391357;2425.575524283252;2428.963565546518;2435.038159926828;2452.0927256914965;2469.296250568579;2413.227767114524;2433.671454353675;2442.963355549668;2448.338948483917;2449.1183174057323;11520.827187592193;11588.13089018327;11753.35356555389;11770.882291177064;11790.292582444996;6404.167458230447;6611.669416529155;6651.80044598658;6655.168103987002;6667.866642667168;3759;4023.4970628671417;4092.7953602319767;4114.403411505345;4121.072758629314;1133.863936327646;1139.5783560082739;1158.1024705852947;1160.3096157786104;1190.6070996571111;15815.525534233991;16276.593085172846;16390.754138687927;16398;16557;4487.955120448819;4507.684462087688;4604.424446944132;5267.631265811357;5441.891162176735;11378.431078446045;11570.305781653127;11647.184697071123;11658.591949281734;11683.299002059903;6430.260520040255;6434.7104380303;6459;6463.547551671338;6473.967630161819;5788.450097240755;6173.104899789505;6181.381861813783;6184.81445556625;6201.224846894138;3587.618766774758;4290.184864875664;4401.141777353385;4444.066745983315;4446.9777651111535;1250.4560516175493;1275.1456524128855;1283.3198404845448;1289.0589869395394;1291.6706239908763;791.5765065689828;807.4307613132727;828.1966492502263;839.3998291221684;854.8033952191031;6123.479504242189;6138.938610613836;6256.217972753406;6291.842703932347;6368.267649220306;596.976120955157;604.815531262969;612.2041346249857;911.2026976395654;984.8522721591676;4018.578049304839;4186.853460128881;4287.721298115613;4295.72077814941;4324.524302326741;11874.584389546324;14670.413183472541;14872.182029988455;15028.398864045315;15035.248237588077;14141.29293535319;14710.41158353654;14887.957842951106;14894.255287235595;15068.54794356171;8144.185581441809;8581.227689507848;8783.824323513621;8813.206811387006;8826.779330516789;5739.426057394394;6289.339619339903;6639.3692599203;6962.860742785218;6982.162140543167;13164.341782910624;13204.537841175512;13221.40503677356;13499.190048597116;13598.320083995959;13397.732045359233;13755.656108597364;13786.517471888435;13875.444982201003;14003.089799163023;9321.72034838956;9405.294602904673;9451.102145296185;9503.382280151767;9507.334486585873;10469.842952355873;10681;10741.087007604441;10759.731006724895;10774.24580279373;9108.134727200906;9166.770830729018;9194.034626364135;9246.815063698823;9258.53707314645;9012.909870901207;9032.051634578274;9117.225035872025;9134.954325228597;9252.074792520694;8103.230193131643;8121.8375632488205;8147.714829980922;8191.344692424473;8209.917900820916;6098.085287206777;6104.11490333908;6122.2959359674205;6125.897338478938;6146.323904370605;6003.309619393825;6017.849553761191;6045.879082418239;6103.908441373294;6182.412670796267 18:47:01 INFO - PID 1636 | |4;prototype.html;383.40189304684674;388.7453717814829;392.94105884117397;400.03391808781805;404.8380647740904;5347.652402593833;5457.534465614333;5652.547796176306;5979.521638268939;6539.4877534501165;6251.531135164858;6298.29173455414;6353.7140828662705;6386.30606504219;6409.8718025639455;4735.5264473552625;4755.500672429397;4766.3088852116425;4773.498782627827;4793.736344501069;4756.143894099078;4805.303231031498;4806;4811.446683631392;4824.927626085611;3559.911002224952;4612.331211974261;4612.400387949562;4707.24684050552;4744.93239021221;4701;4706.576408123286;4713.5993440557595;4718.598919091847;4744.525547445262;3669.5779985301765;3924.95988563031;4161.10536234711;4182.330827067685;4185.321394307645;5927.229460170193;6055.849388616193;6162.84592885177;6189.907151392733;6233.719482623293;4784.091022705709;5635.408282130358;5639.15412688096;5640.520555752766;5644.463775941279;6615.6030638161865;6732.663366831688;6732.899006514906;6772.322767723188;6878.931210687879;4192;4485.641148708128;4735.100330937111;4741.193997020515;4741.383620129395;5899.581171719575;7143.678534465961;7200.279972002811;7217.963910180468;7220.963895180543;4434.401355816924;4480.260756975128;4487.88780280489;4491.8203271869415;4495.550444955544;4176.394422808737;4191.5539138997;4567.977160114178;4582.747948862844;4586.174488592085;5397.784088636489;5412.377576578665;5414.83755487336;5451.672899626035;5461.754221059982;5978.282606087298;6174.938250617526;6428.614283143026;6734.225564060098;6780.694868730919;1321.7311381074276;1497.408523633159;1530.043722673329;1532.9156896370584;1536.6696160325585;982.0898352703922;1011.8566020397052;1013.9201750136082;1019.230480986865;1026.471367245869;5473.343198816167;6254.905391556459;6392.328805475358;6403.367141378919;6408.327125651739;6719.294474080249;6917.89623155653;7014.930595474348;7052.153741551047;7079.964600176966;5276.709780962084;5838.474537291664;6449.355064493607;6460.224773027267;6478.870422591522;3315.7181639560426;3346.481295399217;3372.966270337314;3459.290845376692;3614.2771445710964;6925.688344024531;7143.9642801786695;7226.674799634029;7275.636218189069;7389.519681220703;6388.936110638834;7123.323284287984;7230.602316872622;7251.274872512707;7274.381677557361;5956.434138756811;6353.777617783448;7346.265373462717;7414.5541619384785;7420.109586849612;7137.144342470891;7143.285671433023;7168.713251470092;7176.452359412542;7208.35124838767;5725.53801865202;6687;6690.02994565795;6938.548994315555;7033.613151276729;6065.484433823174;6936.861262774817;7195.892061618975;7200.567965922061;7397.593132377771;4421.607193733964;4425.911481770411;4431.357453169337;4436.622887054507;4437.955620443754;6029.87940241184;6863.691133899087;7018.175274428583;7113.679884405317;7206.783796486114;6016.067509536029;6225.190725205694;6839.863202736017;6846.87746798494;6852.109225800614;5654.6324488908085;6188.257409110935;6456.773213089498;6762.830929226809;6784.151981002375;5652.208690783212;6610.4050635443045;6676.599404035774;6709.295523970108;6726.260111387645;5427.918581221206;5574.9442505574425;5683.914741278802;6507.6746162692625;6882.659895635307;6528.738850445929;6548.705308261235;6585.6377899216;6629.46964242869;6678.699458524476 18:47:01 INFO - PID 1636 | |5;yui.html;821.704186492862;826.4297634632107;827.8799574061761;851.3529717414767;864.9264812490937;13234.007449441295;16566.585835354123;16661.416850410224;18673.439796806084;19376.612467750638;17992.550186245317;18390.540236494093;18542.629147417043;18555.721664175013;18801.717974230396;997.5710444508858;1002.268344108801;1019.9286049976505;1025.0774303127207;1027.1885210683588;839.3369238301734;1022.1822541966434;1029.6807989523272;1032.6437379104198;1033.4729288063068;1063.9361638301689;1085.0885256384674;1087.798757229911;1088.2871719024051;1090.9908334790327;1061.3737894642159;1073.0557109743454;1080.9783804323908;1083.6044843632096;1084.8698156221226;777.4829738224074;786.5909726942016;795.7930937956115;797.2704974947916;799.3245707377315;16934.49196524106;17277.222524986406;17443.476695699148;17462;17478.213480393413;15247.085174889542;15278.541643750705;15645.530634080997;15727.842721572752;15758.212089395485;13842.100263482942;13892.93053534736;14083.577492675236;14228.786568201485;14235.28823558818;3287.7041066304146;3352.379809735207;3356.5300857879915;3358.0261724099983;3360.327934413103;128699.35650321782;129286.3535682325;129570;131150;132237.33881330627;10995.34027958325;13126.278054707083;13126.803097953538;13161.341932903317;13201.669958251121;20540;24888;25696.743032569808;25701;25924.96300147973;7158.928410715826;7664.65509052094;7704.807379815549;7758.262965018314;7765.873948277468;3935.9803200984384;3977.940330895039;4015.1768887378016;4044.312466880637;4054.4526488924207;4037.4347591337237;4069.2878746219885;4162.250794856895;4193.412922190896;4267.829286828554;778.3461892010738;907.3557773980548;915.7527467583715;919.1497864475333;921.3090182363228;632.5983000794529;653.4870126950416;657.1719633262124;657.3557913245047;676.0400231671039;10895.727606809734;11098.833517497244;11559.133065020158;11626.941865290619;12165.75668486643;604.9745681070543;608.5364697901554;611.5780111722897;631.2614241337554;640.2029473305671;4655.674102812771;4917.827876024394;5185.792568297301;5314.12316967696;5354.3574771027725;100031.49984250033;103199;103653.48173259085;106260;107016.46491767491;92792;92927;93466.0653393471;94067.52966235262;95429;83394.74907876387;83521.16478835134;84168;84548.7317690235;85038.14961850426;10188.592456301665;10518.316309439862;11803.586874459354;11887.76224475523;13709.862901370858;31123.533147003265;34495.655043449246;38146;40011.399829002;42135;75471.24528754642;76552.23447765673;77128.90146534533;77959.10204489867;79016;12814.55149069778;12936.417861196456;13602.455901763818;15887.52337429879;15907.965982211119;16648.584870286162;18330.450086497425;18404.17181226821;18579.349722759638;18636.97496637698;16462;17116.914415427844;17219.22513486921;17444.30222791125;17504.649907002044;15435.536933892;16800.831991679926;17067.40264090751;17091.65816683684;17316.913415432842;16665.583360415603;16834.494965151065;16930.068846213082;17132.657346853244;17150.970941743537;10918.508667110158;11150.81702119167;11753.764924701629;11778.705532361417;12140.514379425078;12718.936405317914;12893.548725794552;13009.414576344276;13147.53983610569;13503.72992540124 18:47:01 INFO - PID 1636 | __end_tp_report 18:47:01 INFO - PID 1636 | __start_cc_report 18:47:01 INFO - PID 1636 | _x_x_mozilla_cycle_collect,407 18:47:01 INFO - PID 1636 | __end_cc_report 18:47:01 INFO - PID 1636 | __startTimestamp1494467221659__endTimestamp 18:47:01 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | ------- Summary: start ------- 18:47:01 INFO - PID 1636 | Number of tests: 6 18:47:01 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6213.98 Median:6426.04 stddev:782.30 (12.2%) stddev-sans-first:738.02 18:47:01 INFO - PID 1636 | Values: 2820.8 3627.8 3638.2 3655.0 3692.6 6070.9 6135.2 6270.5 6273.6 6295.7 6210.8 6237.6 6245.3 6257.4 6313.4 5494.5 5495.7 5529.8 5556.8 5561.4 5589.9 5651.2 5661.8 5683.5 5714.9 5417.6 5530.9 5575.0 5622.6 5644.3 5474.7 5537.8 5555.7 5571.0 5616.6 5491.1 5508.4 5514.7 5535.7 5556.0 6091.5 6244.7 6291.4 6306.2 6314.9 6560.7 6646.7 6703.9 6801.6 6817.6 5733.5 6892.6 6900.3 6921.9 6923.3 6296.3 6320.3 6376.1 6390.4 6391.6 6767.4 6935.9 6940.2 7013.8 7081.3 6106.1 6110.1 6672.9 6706.3 6712.5 6800.9 6844.7 6850.4 6857.8 6874.0 6158.7 6524.0 6526.6 6741.3 6759.2 6396.2 6594.2 6689.7 6744.7 6819.0 5276.8 5329.3 5339.4 5354.1 5371.3 4088.9 4171.4 4207.8 4731.8 4832.1 6043.4 6241.8 6321.3 6375.7 6426.0 6936.5 6952.8 6957.9 6968.9 6980.4 5515.6 6316.3 6410.7 6431.5 6448.1 5810.4 5938.7 5952.5 5960.8 6063.5 6039.4 7034.1 7061.6 7076.7 7092.2 6657.8 6761.2 6901.7 6902.0 6937.9 6946.4 7076.8 7087.1 7102.5 7116.5 6517.3 6777.8 6797.6 6812.0 6827.2 5581.3 6750.4 6753.1 6754.3 6853.6 5228.6 6436.9 6655.1 6693.6 6695.1 6661.4 6689.5 6696.7 6699.2 6702.6 6309.1 6342.7 6441.1 6479.5 6542.1 6658.8 6668.7 6671.8 6686.6 6745.6 6496.8 6524.5 6528.9 6534.7 6552.4 18:47:01 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:43863.28 Median:17015.23 stddev:77495.56 (455.4%) stddev-sans-first:77645.49 18:47:01 INFO - PID 1636 | Values: 794.5 809.4 1070.7 1238.5 1245.4 29245.7 29282.7 33351.8 37216.4 37305.8 36627.8 36748.4 37158.4 37192.8 37220.0 6062.3 6744.8 7683.8 7718.8 7885.8 8285.3 8375.3 8379.5 8389.2 8401.3 6911.7 7003.7 8279.3 8384.5 8400.5 6961.7 8329.6 8345.7 8374.2 8375.2 7284.3 8145.1 8165.6 8223.7 8235.8 36539.0 36698.4 36787.6 36789.0 36801.4 3093.4 3523.0 3603.0 3629.4 3631.7 30287.6 30354.9 30389.2 30405.4 30417.0 2898.0 2924.9 2926.9 2935.0 2945.7 257865.7 269692.0 288902.0 289205.0 290031.5 37867.9 38582.4 47518.8 47594.0 47670.0 48349.3 48754.3 48785.3 48959.0 49052.0 13903.0 14054.9 14129.1 14151.0 14174.3 11199.8 11412.4 11439.6 11442.7 11443.8 13345.7 13398.0 13401.7 13409.1 13542.5 5948.7 5995.6 6026.7 6036.1 6041.5 1018.8 1027.1 1027.7 1049.3 1060.4 21091.7 23255.4 24341.0 24374.5 24421.4 1107.1 1134.8 1142.4 1151.4 1155.7 10455.9 11606.2 12347.1 12471.9 12477.9 308143.0 330381.0 330772.0 331287.0 331455.0 52213.0 52510.0 52550.2 52648.5 52655.7 1470.3 1490.9 1535.9 1538.8 1561.7 24056.6 24062.0 24091.6 24291.3 24301.4 37494.6 37617.9 37622.0 37646.0 37649.2 274788.0 276777.0 278074.0 278208.0 278466.0 32715.0 32815.3 33107.0 33180.8 33206.8 41245.0 43510.0 43514.8 43584.6 43787.3 24248.5 28802.6 29688.4 30176.2 30583.7 24535.5 28169.7 29407.0 30140.8 30879.9 31644.4 36320.6 36757.4 36765.8 36783.6 24786.8 27600.6 28947.0 30750.0 30937.1 20257.6 23887.6 24080.9 24291.1 24442.0 18:47:01 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:34917.00 Median:2292.74 stddev:88241.64 (3848.7%) stddev-sans-first:88451.21 18:47:01 INFO - PID 1636 | Values: 438.3 441.8 442.6 447.5 463.7 19797.9 21552.2 22317.8 22350.1 22365.6 19110.9 22220.7 23380.9 23475.4 23701.2 5643.9 5950.4 6182.6 6210.2 6215.4 5595.5 6127.3 6150.6 6152.2 6155.2 5132.3 5878.0 5944.3 6059.0 6113.6 6055.3 6121.9 6128.5 6132.1 6139.4 5361.6 5377.3 5428.3 5439.3 5450.6 27458.6 27533.9 27554.4 27600.0 27664.0 1909.6 1961.8 1964.5 1969.8 1969.8 14023.7 18027.0 18155.3 18279.9 18547.6 2130.3 2165.1 2202.5 2206.5 2209.7 155561.0 156768.2 157849.0 159766.0 159851.0 12392.0 12796.4 13080.3 13136.9 13157.5 16559.3 16700.3 16705.4 16730.4 16769.6 8807.3 9146.7 9155.5 9161.6 9290.4 8622.8 8670.8 8675.2 8739.7 8805.7 8044.2 8090.9 8206.0 8210.6 8224.5 2292.3 2293.2 2298.7 2300.9 2303.6 849.8 884.5 1002.8 1009.7 1032.7 20739.8 21187.9 21268.9 21283.8 21286.3 824.8 1032.9 1054.8 1080.8 1086.3 8349.8 8445.3 10868.3 10965.7 11012.3 390664.8 535083.0 535912.0 544763.0 548444.0 130662.3 134280.0 136149.3 136370.0 139337.3 1221.9 1259.6 1278.6 1282.6 1284.2 19185.8 21107.7 21396.4 21518.8 22666.5 56000.0 56211.0 56397.0 56967.1 57138.0 78596.0 80028.6 81184.0 82714.0 84393.0 7309.4 8959.2 9027.1 9129.1 9156.2 14373.4 14419.8 14439.6 14553.4 14824.9 11818.3 12050.1 12220.5 12234.2 12283.6 8975.5 11076.8 11328.0 11571.0 11598.9 9934.3 10784.7 11332.4 11977.2 12406.0 8812.8 8879.3 8949.2 9279.6 9280.0 10092.2 12071.5 12171.2 12302.5 12324.9 18:47:01 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7051.33 Median:3673.31 stddev:4489.63 (122.2%) stddev-sans-first:4482.39 18:47:01 INFO - PID 1636 | Values: 1435.3 1473.1 1474.9 1495.1 1503.8 11251.0 11843.9 11891.7 11899.5 12164.9 11848.0 11853.5 11981.0 11994.0 12007.0 2430.9 2483.6 2484.9 2485.8 2486.4 2414.6 2441.5 2450.6 2475.6 2485.3 2437.3 2445.5 2449.9 2483.6 2487.9 2425.6 2429.0 2435.0 2452.1 2469.3 2413.2 2433.7 2443.0 2448.3 2449.1 11520.8 11588.1 11753.4 11770.9 11790.3 6404.2 6611.7 6651.8 6655.2 6667.9 3759.0 4023.5 4092.8 4114.4 4121.1 1133.9 1139.6 1158.1 1160.3 1190.6 15815.5 16276.6 16390.8 16398.0 16557.0 4488.0 4507.7 4604.4 5267.6 5441.9 11378.4 11570.3 11647.2 11658.6 11683.3 6430.3 6434.7 6459.0 6463.5 6474.0 5788.5 6173.1 6181.4 6184.8 6201.2 3587.6 4290.2 4401.1 4444.1 4447.0 1250.5 1275.1 1283.3 1289.1 1291.7 791.6 807.4 828.2 839.4 854.8 6123.5 6138.9 6256.2 6291.8 6368.3 597.0 604.8 612.2 911.2 984.9 4018.6 4186.9 4287.7 4295.7 4324.5 11874.6 14670.4 14872.2 15028.4 15035.2 14141.3 14710.4 14888.0 14894.3 15068.5 8144.2 8581.2 8783.8 8813.2 8826.8 5739.4 6289.3 6639.4 6962.9 6982.2 13164.3 13204.5 13221.4 13499.2 13598.3 13397.7 13755.7 13786.5 13875.4 14003.1 9321.7 9405.3 9451.1 9503.4 9507.3 10469.8 10681.0 10741.1 10759.7 10774.2 9108.1 9166.8 9194.0 9246.8 9258.5 9012.9 9032.1 9117.2 9135.0 9252.1 8103.2 8121.8 8147.7 8191.3 8209.9 6098.1 6104.1 6122.3 6125.9 6146.3 6003.3 6017.8 6045.9 6103.9 6182.4 18:47:01 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5450.20 Median:5997.79 stddev:1730.02 (28.8%) stddev-sans-first:1692.55 18:47:01 INFO - PID 1636 | Values: 383.4 388.7 392.9 400.0 404.8 5347.7 5457.5 5652.5 5979.5 6539.5 6251.5 6298.3 6353.7 6386.3 6409.9 4735.5 4755.5 4766.3 4773.5 4793.7 4756.1 4805.3 4806.0 4811.4 4824.9 3559.9 4612.3 4612.4 4707.2 4744.9 4701.0 4706.6 4713.6 4718.6 4744.5 3669.6 3925.0 4161.1 4182.3 4185.3 5927.2 6055.8 6162.8 6189.9 6233.7 4784.1 5635.4 5639.2 5640.5 5644.5 6615.6 6732.7 6732.9 6772.3 6878.9 4192.0 4485.6 4735.1 4741.2 4741.4 5899.6 7143.7 7200.3 7218.0 7221.0 4434.4 4480.3 4487.9 4491.8 4495.6 4176.4 4191.6 4568.0 4582.7 4586.2 5397.8 5412.4 5414.8 5451.7 5461.8 5978.3 6174.9 6428.6 6734.2 6780.7 1321.7 1497.4 1530.0 1532.9 1536.7 982.1 1011.9 1013.9 1019.2 1026.5 5473.3 6254.9 6392.3 6403.4 6408.3 6719.3 6917.9 7014.9 7052.2 7080.0 5276.7 5838.5 6449.4 6460.2 6478.9 3315.7 3346.5 3373.0 3459.3 3614.3 6925.7 7144.0 7226.7 7275.6 7389.5 6388.9 7123.3 7230.6 7251.3 7274.4 5956.4 6353.8 7346.3 7414.6 7420.1 7137.1 7143.3 7168.7 7176.5 7208.4 5725.5 6687.0 6690.0 6938.5 7033.6 6065.5 6936.9 7195.9 7200.6 7397.6 4421.6 4425.9 4431.4 4436.6 4438.0 6029.9 6863.7 7018.2 7113.7 7206.8 6016.1 6225.2 6839.9 6846.9 6852.1 5654.6 6188.3 6456.8 6762.8 6784.2 5652.2 6610.4 6676.6 6709.3 6726.3 5427.9 5574.9 5683.9 6507.7 6882.7 6528.7 6548.7 6585.6 6629.5 6678.7 18:47:01 INFO - PID 1636 | 18:47:01 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:22474.03 Median:17491.43 stddev:32178.35 (184.0%) stddev-sans-first:32227.55 18:47:01 INFO - PID 1636 | Values: 821.7 826.4 827.9 851.4 864.9 13234.0 16566.6 16661.4 18673.4 19376.6 17992.6 18390.5 18542.6 18555.7 18801.7 997.6 1002.3 1019.9 1025.1 1027.2 839.3 1022.2 1029.7 1032.6 1033.5 1063.9 1085.1 1087.8 1088.3 1091.0 1061.4 1073.1 1081.0 1083.6 1084.9 777.5 786.6 795.8 797.3 799.3 16934.5 17277.2 17443.5 17462.0 17478.2 15247.1 15278.5 15645.5 15727.8 15758.2 13842.1 13892.9 14083.6 14228.8 14235.3 3287.7 3352.4 3356.5 3358.0 3360.3 128699.4 129286.4 129570.0 131150.0 132237.3 10995.3 13126.3 13126.8 13161.3 13201.7 20540.0 24888.0 25696.7 25701.0 25925.0 7158.9 7664.7 7704.8 7758.3 7765.9 3936.0 3977.9 4015.2 4044.3 4054.5 4037.4 4069.3 4162.3 4193.4 4267.8 778.3 907.4 915.8 919.1 921.3 632.6 653.5 657.2 657.4 676.0 10895.7 11098.8 11559.1 11626.9 12165.8 605.0 608.5 611.6 631.3 640.2 4655.7 4917.8 5185.8 5314.1 5354.4 100031.5 103199.0 103653.5 106260.0 107016.5 92792.0 92927.0 93466.1 94067.5 95429.0 83394.7 83521.2 84168.0 84548.7 85038.1 10188.6 10518.3 11803.6 11887.8 13709.9 31123.5 34495.7 38146.0 40011.4 42135.0 75471.2 76552.2 77128.9 77959.1 79016.0 12814.6 12936.4 13602.5 15887.5 15908.0 16648.6 18330.5 18404.2 18579.3 18637.0 16462.0 17116.9 17219.2 17444.3 17504.6 15435.5 16800.8 17067.4 17091.7 17316.9 16665.6 16834.5 16930.1 17132.7 17151.0 10918.5 11150.8 11753.8 11778.7 12140.5 12718.9 12893.5 13009.4 13147.5 13503.7 18:47:01 INFO - PID 1636 | -------- Summary: end -------- 18:47:01 INFO - PID 1636 | 18:47:02 INFO - TEST-INFO | 1636: exit 0 18:47:02 INFO - TEST-OK | dromaeo_css | took 1113782ms 18:47:02 INFO - TEST-START | kraken 18:47:02 INFO - operating with platform_type : mac_ 18:47:02 INFO - Initialising browser for kraken test... 18:47:02 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpDYX5dX/profile http://localhost:49245/getInfo.html) 18:47:04 INFO - PID 1651 | 2017-05-10 18:47:04.905 plugin-container[1652:10179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.tsm.portname' 18:47:04 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:04 INFO - PID 1651 | 2017-05-10 18:47:04.907 plugin-container[1652:10179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970f, name = 'com.apple.CFPasteboardClient' 18:47:04 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:04 INFO - PID 1651 | 2017-05-10 18:47:04.907 plugin-container[1652:10179] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:05 INFO - PID 1651 | __metrics Screen width/height:1600/1200 18:47:05 INFO - PID 1651 | colorDepth:24 18:47:05 INFO - PID 1651 | Browser inner width/height: 1024/691 18:47:05 INFO - PID 1651 | __metrics 18:47:06 INFO - TEST-INFO | 1651: exit 0 18:47:06 INFO - Browser initialized. 18:47:06 INFO - Running cycle 1/1 for kraken test... 18:47:06 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpDYX5dX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:08 INFO - PID 1654 | 2017-05-10 18:47:08.366 plugin-container[1655:10337] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 18:47:08 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:08 INFO - PID 1654 | 2017-05-10 18:47:08.367 plugin-container[1655:10337] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 18:47:08 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:08 INFO - PID 1654 | 2017-05-10 18:47:08.367 plugin-container[1655:10337] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:08 INFO - PID 1654 | 2017-05-10 18:47:08.977 plugin-container[1656:10383] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 18:47:08 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:08 INFO - PID 1654 | 2017-05-10 18:47:08.978 plugin-container[1656:10383] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 18:47:08 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:08 INFO - PID 1654 | 2017-05-10 18:47:08.978 plugin-container[1656:10383] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:18 INFO - PID 1654 | RSS: Main: 229249024 18:47:18 INFO - PID 1654 | 18:47:21 INFO - PID 1654 | 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) 18:47:22 INFO - PID 1654 | RSS: Main: 242077696 18:47:22 INFO - PID 1654 | 18:47:24 INFO - PID 1654 | 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) 18:47:25 INFO - PID 1654 | RSS: Main: 250757120 18:47:25 INFO - PID 1654 | 18:47:28 INFO - PID 1654 | 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) 18:47:29 INFO - PID 1654 | RSS: Main: 253026304 18:47:29 INFO - PID 1654 | 18:47:31 INFO - PID 1654 | 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) 18:47:31 INFO - PID 1654 | RSS: Main: 254173184 18:47:31 INFO - PID 1654 | 18:47:33 INFO - PID 1654 | 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) 18:47:34 INFO - PID 1654 | RSS: Main: 255942656 18:47:34 INFO - PID 1654 | 18:47:37 INFO - PID 1654 | 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) 18:47:37 INFO - PID 1654 | RSS: Main: 262057984 18:47:37 INFO - PID 1654 | 18:47:41 INFO - PID 1654 | 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) 18:47:41 INFO - PID 1654 | RSS: Main: 264212480 18:47:41 INFO - PID 1654 | 18:47:44 INFO - PID 1654 | 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) 18:47:45 INFO - PID 1654 | RSS: Main: 263397376 18:47:45 INFO - PID 1654 | 18:47:46 INFO - PID 1654 | 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) 18:47:47 INFO - PID 1654 | RSS: Main: 262492160 18:47:47 INFO - PID 1654 | 18:47:49 INFO - PID 1654 | 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) 18:47:49 INFO - PID 1654 | RSS: Main: 262672384 18:47:49 INFO - PID 1654 | 18:47:51 INFO - PID 1654 | 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) 18:47:52 INFO - PID 1654 | RSS: Main: 262066176 18:47:52 INFO - PID 1654 | 18:47:55 INFO - PID 1654 | 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) 18:47:56 INFO - PID 1654 | RSS: Main: 262418432 18:47:56 INFO - PID 1654 | 18:47:59 INFO - PID 1654 | 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) 18:48:00 INFO - PID 1654 | RSS: Main: 262561792 18:48:00 INFO - PID 1654 | 18:48:01 INFO - PID 1654 | 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) 18:48:02 INFO - PID 1654 | RSS: Main: 264155136 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | __start_tp_report 18:48:02 INFO - PID 1654 | _x_x_mozilla_page_load 18:48:02 INFO - PID 1654 | _x_x_mozilla_page_load_details 18:48:02 INFO - PID 1654 | |i|pagename|runs| 18:48:02 INFO - PID 1654 | |0;ai-astar;107;115;117;124;171;167;113;116;129;117 18:48:02 INFO - PID 1654 | |1;audio-beat-detection;130;108;101;184;146;144;148;144;140;110 18:48:02 INFO - PID 1654 | |2;audio-dft;199;197;195;213;211;244;213;195;200;196 18:48:02 INFO - PID 1654 | |3;audio-fft;79;56;55;86;63;51;55;70;93;86 18:48:02 INFO - PID 1654 | |4;audio-oscillator;80;71;83;71;68;72;85;69;69;93 18:48:02 INFO - PID 1654 | |5;imaging-gaussian-blur;88;89;80;136;138;90;89;103;88;89 18:48:02 INFO - PID 1654 | |6;imaging-darkroom;125;96;96;96;96;97;136;97;97;97 18:48:02 INFO - PID 1654 | |7;imaging-desaturate;88;101;91;92;92;91;87;91;95;93 18:48:02 INFO - PID 1654 | |8;json-parse-financial;51;49;50;49;50;50;51;53;50;49 18:48:02 INFO - PID 1654 | |9;json-stringify-tinderbox;59;64;65;91;96;57;83;82;83;49 18:48:02 INFO - PID 1654 | |10;stanford-crypto-aes;84;82;84;82;83;82;80;85;81;84 18:48:02 INFO - PID 1654 | |11;stanford-crypto-ccm;156;155;157;156;154;147;153;159;184;153 18:48:02 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;185;188;186;219;206;183;186;187;179;184 18:48:02 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;68;68;70;66;69;67;69;68;68;70 18:48:02 INFO - PID 1654 | __end_tp_report 18:48:02 INFO - PID 1654 | __start_cc_report 18:48:02 INFO - PID 1654 | _x_x_mozilla_cycle_collect,991 18:48:02 INFO - PID 1654 | __end_cc_report 18:48:02 INFO - PID 1654 | __startTimestamp1494467282035__endTimestamp 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | ------- Summary: start ------- 18:48:02 INFO - PID 1654 | Number of tests: 14 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:127.60 Median:120.50 stddev:22.62 (18.8%) stddev-sans-first:22.73 18:48:02 INFO - PID 1654 | Values: 107.0 115.0 117.0 124.0 171.0 167.0 113.0 116.0 129.0 117.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:144.00 stddev:24.52 (17.0%) stddev-sans-first:25.93 18:48:02 INFO - PID 1654 | Values: 130.0 108.0 101.0 184.0 146.0 144.0 148.0 144.0 140.0 110.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:206.30 Median:205.50 stddev:15.18 (7.4%) stddev-sans-first:15.87 18:48:02 INFO - PID 1654 | Values: 199.0 197.0 195.0 213.0 211.0 244.0 213.0 195.0 200.0 196.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:69.40 Median:74.50 stddev:15.54 (20.9%) stddev-sans-first:16.09 18:48:02 INFO - PID 1654 | Values: 79.0 56.0 55.0 86.0 63.0 51.0 55.0 70.0 93.0 86.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:76.00 stddev:8.58 (11.3%) stddev-sans-first:8.99 18:48:02 INFO - PID 1654 | Values: 80.0 71.0 83.0 71.0 68.0 72.0 85.0 69.0 69.0 93.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:88.50 stddev:20.79 (23.5%) stddev-sans-first:21.67 18:48:02 INFO - PID 1654 | Values: 88.0 89.0 80.0 136.0 138.0 90.0 89.0 103.0 88.0 89.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:96.50 stddev:14.58 (15.1%) stddev-sans-first:13.18 18:48:02 INFO - PID 1654 | Values: 125.0 96.0 96.0 96.0 96.0 97.0 136.0 97.0 97.0 97.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:91.50 stddev:3.87 (4.2%) stddev-sans-first:3.81 18:48:02 INFO - PID 1654 | Values: 88.0 101.0 91.0 92.0 92.0 91.0 87.0 91.0 95.0 93.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:1.23 (2.5%) stddev-sans-first:1.27 18:48:02 INFO - PID 1654 | Values: 51.0 49.0 50.0 49.0 50.0 50.0 51.0 53.0 50.0 49.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:82.50 stddev:16.01 (19.4%) stddev-sans-first:16.17 18:48:02 INFO - PID 1654 | Values: 59.0 64.0 65.0 91.0 96.0 57.0 83.0 82.0 83.0 49.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.50 stddev:1.57 (1.9%) stddev-sans-first:1.59 18:48:02 INFO - PID 1654 | Values: 84.0 82.0 84.0 82.0 83.0 82.0 80.0 85.0 81.0 84.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:157.40 Median:156.00 stddev:9.88 (6.3%) stddev-sans-first:10.47 18:48:02 INFO - PID 1654 | Values: 156.0 155.0 157.0 156.0 154.0 147.0 153.0 159.0 184.0 153.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.30 Median:186.50 stddev:12.35 (6.6%) stddev-sans-first:12.95 18:48:02 INFO - PID 1654 | Values: 185.0 188.0 186.0 219.0 206.0 183.0 186.0 187.0 179.0 184.0 18:48:02 INFO - PID 1654 | 18:48:02 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:1.25 (1.8%) stddev-sans-first:1.32 18:48:02 INFO - PID 1654 | Values: 68.0 68.0 70.0 66.0 69.0 67.0 69.0 68.0 68.0 70.0 18:48:02 INFO - PID 1654 | -------- Summary: end -------- 18:48:02 INFO - PID 1654 | 18:48:02 INFO - TEST-INFO | 1654: exit 0 18:48:02 INFO - TEST-OK | kraken | took 60662ms 18:48:02 INFO - SUITE-END | took 1174s 18:48:03 INFO - Completed test suite (00:19:34) 18:48:03 INFO - javascript benchmark 18:48:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7878.793803724025, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2820.7602353799925, 3627.818609069546, 3638.1632224588343, 3655.03141667458, 3692.61227571105, 6070.9392906070925, 6135.232666669988, 6270.52971027173, 6273.592216505924, 6295.6537390443455, 6210.8136755897285, 6237.625742455456, 6245.250569931603, 6257.4368306852375, 6313.40022697843, 5494.478024587658, 5495.670259784406, 5529.778808847651, 5556.777728890839, 5561.360443548983, 5589.888202235973, 5651.1523271509395, 5661.801836935708, 5683.488486036256, 5714.857128571798, 5417.6207665463435, 5530.889382212374, 5574.9721251393885, 5622.578306626999, 5644.266245388114, 5474.7262636868, 5537.833864984057, 5555.722213889329, 5571.0, 5616.550675945915, 5491.148871924858, 5508.44915508446, 5514.724263786795, 5535.667859928377, 5555.972220138873, 6091.451769340736, 6244.687765611701, 6291.433770960635, 6306.211723534559, 6314.905276420857, 6560.737570497175, 6646.700898459581, 6703.899441508382, 6801.557898736566, 6817.5909445433435, 5733.541316694655, 6892.620905850176, 6900.275471075515, 6921.8961715574305, 6923.273056329113, 6296.338884417162, 6320.304766475684, 6376.0754690570075, 6390.424861762417, 6391.58454700443, 6767.39093481589, 6935.895961560581, 6940.167179938439, 7013.824654383681, 7081.291870812981, 6106.096658337741, 6110.052941794029, 6672.9332706673285, 6706.329367063255, 6712.463002959751, 6800.93199068003, 6844.726210951606, 6850.38346548803, 6857.828554286183, 6873.965630171917, 6158.661273629993, 6524.0, 6526.641034743135, 6741.292164322774, 6759.155105611799, 6396.200474940632, 6594.241662208811, 6689.732410703615, 6744.730210791513, 6819.0, 5276.841694749163, 5329.25390445338, 5339.385970613352, 5354.116570765858, 5371.274877891513, 4088.9182216356103, 4171.4368560243975, 4207.810648520824, 4731.787069581808, 4832.057748739033, 6043.425874541911, 6241.812745617639, 6321.336259692758, 6375.681215939185, 6426.036094585849, 6936.549124306904, 6952.791416257419, 6957.886738121876, 6968.860622787516, 6980.371766541036, 5515.558755299647, 6316.336784637547, 6410.743570257137, 6431.549791514549, 6448.097266382711, 5810.448007439287, 5938.732757025944, 5952.464278214981, 5960.821175364746, 6063.54523410746, 6039.4262545058145, 7034.085568876013, 7061.6469176542, 7076.71693132269, 7092.184398794205, 6657.833554161185, 6761.188657360952, 6901.689423976033, 6902.0, 6937.861242775217, 6946.374826265458, 7076.787696369117, 7087.078679771596, 7102.538335008208, 7116.537425067354, 6517.250516190509, 6777.762778302933, 6797.592144471149, 6812.0, 6827.2490026098085, 5581.302337207849, 6750.426213771688, 6753.054572359972, 6754.324567543206, 6853.623050732058, 5228.555572776205, 6436.871262574628, 6655.101561289164, 6693.564918280296, 6695.06269122333, 6661.4004739573675, 6689.46484281266, 6696.732130714717, 6699.196096468455, 6702.564333318319, 6309.1167236587835, 6342.651154186564, 6441.098246245436, 6479.51403644738, 6542.149520562297, 6658.800235992928, 6668.733250669919, 6671.833204169935, 6686.632235226914, 6745.561538500178, 6496.7726129585235, 6524.510661700487, 6528.934710652833, 6534.738610455529, 6552.410283074546], "value": 6159.1492157147595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.5113755040649, 809.3808236698926, 1070.7109080548253, 1238.510788238645, 1245.4271035323757, 29245.707542924563, 29282.707172928265, 33351.833240833796, 37216.441753373685, 37305.81347093268, 36627.81686091572, 36748.44877326842, 37158.44262336061, 37192.81403592985, 37220.0, 6062.333143354227, 6744.75222083916, 7683.769486915374, 7718.807029824257, 7885.763427097168, 8285.254327110559, 8375.329973602098, 8379.539125348103, 8389.161083891622, 8401.327893768514, 6911.6889739961825, 7003.684834182474, 8279.337652987777, 8384.455010424334, 8400.45397049193, 6961.721531138748, 8329.625166867505, 8345.749627511153, 8374.246317831394, 8375.246227839523, 7284.344409003163, 8145.104038555754, 8165.63254653542, 8223.712170074079, 8235.794105147359, 36539.0, 36698.44952325717, 36787.63212367869, 36789.0, 36801.44797828035, 3093.3967876264137, 3523.048776830267, 3603.0091724775634, 3629.4192929131214, 3631.7276204284785, 30287.637056332518, 30354.93757718491, 30389.24026899323, 30405.391892162253, 30417.0, 2897.985510072457, 2924.853757312147, 2926.853657317147, 2934.985325073361, 2945.661248956376, 257865.71067144544, 269692.0, 288902.0, 289205.0, 290031.5498422515, 37867.86396408112, 38582.42126368107, 47518.76240618775, 47594.04811903743, 47670.0, 48349.274760878616, 48754.26868596974, 48785.268220976715, 48959.0, 49052.0, 13903.026788124735, 14054.929725351309, 14129.081609695337, 14151.009429340053, 14174.291285435687, 11199.776004480027, 11412.37231952234, 11439.59961401347, 11442.656720298404, 11443.771124577628, 13345.66635834112, 13398.0, 13401.731965360637, 13409.128406653732, 13542.45830166802, 5948.7323070461935, 5995.61028533144, 6026.728797204137, 6036.094585812076, 6041.459427845871, 1018.7809620931536, 1027.111548510533, 1027.6916924922493, 1049.3179433368218, 1060.3531845574194, 21091.68362474564, 23255.418614534432, 24341.0, 24374.512509749704, 24421.389465263153, 1107.1198397274186, 1134.8297755336766, 1142.4230763464398, 1151.4185336405062, 1155.7284038251005, 10455.895441045644, 11606.187566870238, 12347.135700501058, 12471.875281247252, 12477.937610312072, 308143.0, 330381.0, 330772.0, 331287.0, 331455.0, 52213.0, 52510.0, 52550.21174682383, 52648.47351526513, 52655.73672131615, 1470.3383477435198, 1490.8956373054, 1535.917178389233, 1538.7845701601784, 1561.6564355841697, 24056.594340565804, 24062.037518499765, 24091.638625420983, 24291.271261862174, 24301.39246518851, 37494.625053749114, 37617.87146385613, 37622.0, 37646.0, 37649.24701506009, 274788.0, 276777.0, 278074.0, 278208.0, 278466.0, 32715.0, 32815.34369312648, 33107.0, 33180.83409582936, 33206.83396583002, 41245.0, 43510.0, 43514.782426087666, 43584.56415435805, 43787.34318985154, 24248.515029698956, 28802.567961481014, 29688.406231875673, 30176.24559386033, 30583.694163058084, 24535.50928981446, 28169.732362044168, 29407.03005094285, 30140.84929575338, 30879.919202827794, 31644.367112657157, 36320.636793631726, 36757.44863827098, 36765.81617091897, 36783.63216367909, 24786.752132478443, 27600.585991209748, 28947.0, 30750.0, 30937.0718878434, 20257.59484810325, 23887.641685374387, 24080.87959560191, 24291.149809756575, 24442.0], "value": 17019.64387672034, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.27684320870577, 441.8033974881178, 442.5729171349647, 447.49433140551173, 463.7148153885361, 19797.911114888677, 21552.245671401502, 22317.77682223177, 22350.088734471443, 22365.55268894623, 19110.904445477754, 22220.666689999664, 23380.88309558454, 23475.41311467214, 23701.170459033936, 5643.927653745795, 5950.37521060289, 6182.629042257457, 6210.1616281801835, 6215.440610345068, 5595.524380427669, 6127.325994140641, 6150.630962142263, 6152.169457123276, 6155.230596175478, 5132.255822905695, 5877.970610146965, 5944.256967879015, 6058.969705151468, 6113.633182009094, 6055.333913269559, 6121.938780612181, 6128.509719222496, 6132.110843927606, 6139.355367686396, 5361.597880159004, 5377.327834020747, 5428.348598168205, 5439.3200849893765, 5450.645708028966, 27458.5881211782, 27533.86233068842, 27554.448911021667, 27600.0, 27664.0, 1909.6467153576493, 1961.8136277053657, 1964.5481539245911, 1969.7537807774029, 1969.8424126070024, 14023.719525609533, 18027.008514531695, 18155.27378904842, 18279.90320580756, 18547.629047418977, 2130.2650585547963, 2165.101482884601, 2202.50443650179, 2206.5255969966374, 2209.668549717539, 155561.0, 156768.21615891962, 157849.0, 159765.99999999884, 159851.0, 12392.0, 12796.360181990864, 13080.28058456794, 13136.949044076451, 13157.473701051851, 16559.337626495046, 16700.331986720637, 16705.41531046432, 16730.414435494942, 16769.580760481087, 8807.269746378643, 9146.725598232064, 9155.496447695441, 9161.633534658538, 9290.44257344548, 8622.784430389165, 8670.783230419163, 8675.219230269306, 8739.694110706096, 8805.735827925173, 8044.155363686846, 8090.919090809134, 8206.0, 8210.58947052657, 8224.547649879194, 2292.32376448948, 2293.1515339324224, 2298.678185054094, 2300.9309720708407, 2303.608386574286, 849.8427790858711, 884.4648987362553, 1002.8044531316323, 1009.6769033909079, 1032.6850310655323, 20739.79260207409, 21187.894060529597, 21268.893655531625, 21283.78716212849, 21286.254981075897, 824.814416756237, 1032.8915463876185, 1054.7890421915647, 1080.8054550180887, 1086.2721976275757, 8349.791255218668, 8445.310937812339, 10868.347899126078, 10965.671029869116, 11012.339259644446, 390664.7736929974, 535083.0, 535912.0, 544763.0, 548444.0, 130662.34668826596, 134280.0, 136149.3192534031, 136370.0, 139337.3033134828, 1221.859486159103, 1259.596928982735, 1278.6483716977905, 1282.6280378690262, 1284.178125999361, 19185.8081419184, 21107.683384748932, 21396.358109256747, 21518.784812151676, 22666.546669066196, 56000.0, 56211.0, 56397.0, 56967.14549281681, 57138.0, 78596.0, 80028.59985700034, 81184.0, 82714.0, 84393.0, 7309.4152467803515, 8959.1936725695, 9027.09729027092, 9129.087091290818, 9156.221721153777, 14373.425062997781, 14419.78370324425, 14439.566812995627, 14553.448967364478, 14824.851751482347, 11818.349990750592, 12050.09624278165, 12220.45007974661, 12234.204776689487, 12283.631491055283, 8975.461472311683, 11076.778464430827, 11328.0, 11571.016463600688, 11598.94200528992, 9934.304598678023, 10784.651918510186, 11332.376719280519, 11977.161598688357, 12406.0, 8812.779680508038, 8879.334049946408, 8949.239314658327, 9279.582418791035, 9280.025597312455, 10092.24308176875, 12071.456784444897, 12171.235170899992, 12302.507899683913, 12324.876751232372], "value": 10488.015721036962, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.3038776193548, 1473.0940471609963, 1474.9410023599057, 1495.0954672423184, 1503.7894694742733, 11251.0, 11843.940780296109, 11891.702707432307, 11899.52401903925, 12164.93917530411, 11848.0, 11853.466594003268, 11981.0, 11994.0, 12007.03943684503, 2430.9392265193333, 2483.615039668854, 2484.85090894546, 2485.8135639826996, 2486.428121532049, 2414.5895197816412, 2441.5483135619966, 2450.5588993981078, 2475.6410320503514, 2485.2668462803517, 2437.293184976355, 2445.5231229910173, 2449.9142530011454, 2483.5653760591913, 2487.9253622391357, 2425.575524283252, 2428.963565546518, 2435.038159926828, 2452.0927256914965, 2469.296250568579, 2413.227767114524, 2433.671454353675, 2442.963355549668, 2448.338948483917, 2449.1183174057323, 11520.827187592193, 11588.13089018327, 11753.35356555389, 11770.882291177064, 11790.292582444996, 6404.167458230447, 6611.669416529155, 6651.80044598658, 6655.168103987002, 6667.866642667168, 3759.0, 4023.4970628671417, 4092.7953602319767, 4114.403411505345, 4121.072758629314, 1133.863936327646, 1139.5783560082739, 1158.1024705852947, 1160.3096157786104, 1190.6070996571111, 15815.525534233991, 16276.593085172846, 16390.754138687927, 16398.0, 16557.0, 4487.955120448819, 4507.684462087688, 4604.424446944132, 5267.631265811357, 5441.891162176735, 11378.431078446045, 11570.305781653127, 11647.184697071123, 11658.591949281734, 11683.299002059903, 6430.260520040255, 6434.7104380303, 6459.0, 6463.547551671338, 6473.967630161819, 5788.450097240755, 6173.104899789505, 6181.381861813783, 6184.81445556625, 6201.224846894138, 3587.618766774758, 4290.184864875664, 4401.141777353385, 4444.066745983315, 4446.9777651111535, 1250.4560516175493, 1275.1456524128855, 1283.3198404845448, 1289.0589869395394, 1291.6706239908763, 791.5765065689828, 807.4307613132727, 828.1966492502263, 839.3998291221684, 854.8033952191031, 6123.479504242189, 6138.938610613836, 6256.217972753406, 6291.842703932347, 6368.267649220306, 596.976120955157, 604.815531262969, 612.2041346249857, 911.2026976395654, 984.8522721591676, 4018.578049304839, 4186.853460128881, 4287.721298115613, 4295.72077814941, 4324.524302326741, 11874.584389546324, 14670.413183472541, 14872.182029988455, 15028.398864045315, 15035.248237588077, 14141.29293535319, 14710.41158353654, 14887.957842951106, 14894.255287235595, 15068.54794356171, 8144.185581441809, 8581.227689507848, 8783.824323513621, 8813.206811387006, 8826.779330516789, 5739.426057394394, 6289.339619339903, 6639.3692599203, 6962.860742785218, 6982.162140543167, 13164.341782910624, 13204.537841175512, 13221.40503677356, 13499.190048597116, 13598.320083995959, 13397.732045359233, 13755.656108597364, 13786.517471888435, 13875.444982201003, 14003.089799163023, 9321.72034838956, 9405.294602904673, 9451.102145296185, 9503.382280151767, 9507.334486585873, 10469.842952355873, 10681.0, 10741.087007604441, 10759.731006724895, 10774.24580279373, 9108.134727200906, 9166.770830729018, 9194.034626364135, 9246.815063698823, 9258.53707314645, 9012.909870901207, 9032.051634578274, 9117.225035872025, 9134.954325228597, 9252.074792520694, 8103.230193131643, 8121.8375632488205, 8147.714829980922, 8191.344692424473, 8209.917900820916, 6098.085287206777, 6104.11490333908, 6122.2959359674205, 6125.897338478938, 6146.323904370605, 6003.309619393825, 6017.849553761191, 6045.879082418239, 6103.908441373294, 6182.412670796267], "value": 5297.968268628921, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.40189304684674, 388.7453717814829, 392.94105884117397, 400.03391808781805, 404.8380647740904, 5347.652402593833, 5457.534465614333, 5652.547796176306, 5979.521638268939, 6539.4877534501165, 6251.531135164858, 6298.29173455414, 6353.7140828662705, 6386.30606504219, 6409.8718025639455, 4735.5264473552625, 4755.500672429397, 4766.3088852116425, 4773.498782627827, 4793.736344501069, 4756.143894099078, 4805.303231031498, 4806.0, 4811.446683631392, 4824.927626085611, 3559.911002224952, 4612.331211974261, 4612.400387949562, 4707.24684050552, 4744.93239021221, 4701.0, 4706.576408123286, 4713.5993440557595, 4718.598919091847, 4744.525547445262, 3669.5779985301765, 3924.95988563031, 4161.10536234711, 4182.330827067685, 4185.321394307645, 5927.229460170193, 6055.849388616193, 6162.84592885177, 6189.907151392733, 6233.719482623293, 4784.091022705709, 5635.408282130358, 5639.15412688096, 5640.520555752766, 5644.463775941279, 6615.6030638161865, 6732.663366831688, 6732.899006514906, 6772.322767723188, 6878.931210687879, 4192.0, 4485.641148708128, 4735.100330937111, 4741.193997020515, 4741.383620129395, 5899.581171719575, 7143.678534465961, 7200.279972002811, 7217.963910180468, 7220.963895180543, 4434.401355816924, 4480.260756975128, 4487.88780280489, 4491.8203271869415, 4495.550444955544, 4176.394422808737, 4191.5539138997, 4567.977160114178, 4582.747948862844, 4586.174488592085, 5397.784088636489, 5412.377576578665, 5414.83755487336, 5451.672899626035, 5461.754221059982, 5978.282606087298, 6174.938250617526, 6428.614283143026, 6734.225564060098, 6780.694868730919, 1321.7311381074276, 1497.408523633159, 1530.043722673329, 1532.9156896370584, 1536.6696160325585, 982.0898352703922, 1011.8566020397052, 1013.9201750136082, 1019.230480986865, 1026.471367245869, 5473.343198816167, 6254.905391556459, 6392.328805475358, 6403.367141378919, 6408.327125651739, 6719.294474080249, 6917.89623155653, 7014.930595474348, 7052.153741551047, 7079.964600176966, 5276.709780962084, 5838.474537291664, 6449.355064493607, 6460.224773027267, 6478.870422591522, 3315.7181639560426, 3346.481295399217, 3372.966270337314, 3459.290845376692, 3614.2771445710964, 6925.688344024531, 7143.9642801786695, 7226.674799634029, 7275.636218189069, 7389.519681220703, 6388.936110638834, 7123.323284287984, 7230.602316872622, 7251.274872512707, 7274.381677557361, 5956.434138756811, 6353.777617783448, 7346.265373462717, 7414.5541619384785, 7420.109586849612, 7137.144342470891, 7143.285671433023, 7168.713251470092, 7176.452359412542, 7208.35124838767, 5725.53801865202, 6687.0, 6690.02994565795, 6938.548994315555, 7033.613151276729, 6065.484433823174, 6936.861262774817, 7195.892061618975, 7200.567965922061, 7397.593132377771, 4421.607193733964, 4425.911481770411, 4431.357453169337, 4436.622887054507, 4437.955620443754, 6029.87940241184, 6863.691133899087, 7018.175274428583, 7113.679884405317, 7206.783796486114, 6016.067509536029, 6225.190725205694, 6839.863202736017, 6846.87746798494, 6852.109225800614, 5654.6324488908085, 6188.257409110935, 6456.773213089498, 6762.830929226809, 6784.151981002375, 5652.208690783212, 6610.4050635443045, 6676.599404035774, 6709.295523970108, 6726.260111387645, 5427.918581221206, 5574.9442505574425, 5683.914741278802, 6507.6746162692625, 6882.659895635307, 6528.738850445929, 6548.705308261235, 6585.6377899216, 6629.46964242869, 6678.699458524476], "value": 4899.615915089497, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.704186492862, 826.4297634632107, 827.8799574061761, 851.3529717414767, 864.9264812490937, 13234.007449441295, 16566.585835354123, 16661.416850410224, 18673.439796806084, 19376.612467750638, 17992.550186245317, 18390.540236494093, 18542.629147417043, 18555.721664175013, 18801.717974230396, 997.5710444508858, 1002.268344108801, 1019.9286049976505, 1025.0774303127207, 1027.1885210683588, 839.3369238301734, 1022.1822541966434, 1029.6807989523272, 1032.6437379104198, 1033.4729288063068, 1063.9361638301689, 1085.0885256384674, 1087.798757229911, 1088.2871719024051, 1090.9908334790327, 1061.3737894642159, 1073.0557109743454, 1080.9783804323908, 1083.6044843632096, 1084.8698156221226, 777.4829738224074, 786.5909726942016, 795.7930937956115, 797.2704974947916, 799.3245707377315, 16934.49196524106, 17277.222524986406, 17443.476695699148, 17462.0, 17478.213480393413, 15247.085174889542, 15278.541643750705, 15645.530634080997, 15727.842721572752, 15758.212089395485, 13842.100263482942, 13892.93053534736, 14083.577492675236, 14228.786568201485, 14235.28823558818, 3287.7041066304146, 3352.379809735207, 3356.5300857879915, 3358.0261724099983, 3360.327934413103, 128699.35650321782, 129286.3535682325, 129570.0, 131150.0, 132237.33881330627, 10995.34027958325, 13126.278054707083, 13126.803097953538, 13161.341932903317, 13201.669958251121, 20540.0, 24888.0, 25696.743032569808, 25701.0, 25924.96300147973, 7158.928410715826, 7664.65509052094, 7704.807379815549, 7758.262965018314, 7765.873948277468, 3935.9803200984384, 3977.940330895039, 4015.1768887378016, 4044.312466880637, 4054.4526488924207, 4037.4347591337237, 4069.2878746219885, 4162.250794856895, 4193.412922190896, 4267.829286828554, 778.3461892010738, 907.3557773980548, 915.7527467583715, 919.1497864475333, 921.3090182363228, 632.5983000794529, 653.4870126950416, 657.1719633262124, 657.3557913245047, 676.0400231671039, 10895.727606809734, 11098.833517497244, 11559.133065020158, 11626.941865290619, 12165.75668486643, 604.9745681070543, 608.5364697901554, 611.5780111722897, 631.2614241337554, 640.2029473305671, 4655.674102812771, 4917.827876024394, 5185.792568297301, 5314.12316967696, 5354.3574771027725, 100031.49984250033, 103199.0, 103653.48173259085, 106260.0, 107016.46491767491, 92792.0, 92927.0, 93466.0653393471, 94067.52966235262, 95429.0, 83394.74907876387, 83521.16478835134, 84168.0, 84548.7317690235, 85038.14961850426, 10188.592456301665, 10518.316309439862, 11803.586874459354, 11887.76224475523, 13709.862901370858, 31123.533147003265, 34495.655043449246, 38146.0, 40011.399829002, 42135.0, 75471.24528754642, 76552.23447765673, 77128.90146534533, 77959.10204489867, 79016.0, 12814.55149069778, 12936.417861196456, 13602.455901763818, 15887.52337429879, 15907.965982211119, 16648.584870286162, 18330.450086497425, 18404.17181226821, 18579.349722759638, 18636.97496637698, 16462.0, 17116.914415427844, 17219.22513486921, 17444.30222791125, 17504.649907002044, 15435.536933892, 16800.831991679926, 17067.40264090751, 17091.65816683684, 17316.913415432842, 16665.583360415603, 16834.494965151065, 16930.068846213082, 17132.657346853244, 17150.970941743537, 10918.508667110158, 11150.81702119167, 11753.764924701629, 11778.705532361417, 12140.514379425078, 12718.936405317914, 12893.548725794552, 13009.414576344276, 13147.53983610569, 13503.72992540124], "value": 8380.952210483092, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1531.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 117.0, 124.0, 171.0, 167.0, 113.0, 116.0, 129.0, 117.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 108.0, 101.0, 184.0, 146.0, 144.0, 148.0, 144.0, 140.0, 110.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 197.0, 195.0, 213.0, 211.0, 244.0, 213.0, 195.0, 200.0, 196.0], "value": 206.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 56.0, 55.0, 86.0, 63.0, 51.0, 55.0, 70.0, 93.0, 86.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 71.0, 83.0, 71.0, 68.0, 72.0, 85.0, 69.0, 69.0, 93.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 80.0, 136.0, 138.0, 90.0, 89.0, 103.0, 88.0, 89.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 96.0, 96.0, 96.0, 96.0, 97.0, 136.0, 97.0, 97.0, 97.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 101.0, 91.0, 92.0, 92.0, 91.0, 87.0, 91.0, 95.0, 93.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 49.0, 50.0, 50.0, 51.0, 53.0, 50.0, 49.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 65.0, 91.0, 96.0, 57.0, 83.0, 82.0, 83.0, 49.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 84.0, 82.0, 83.0, 82.0, 80.0, 85.0, 81.0, 84.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 155.0, 157.0, 156.0, 154.0, 147.0, 153.0, 159.0, 184.0, 153.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 186.0, 219.0, 206.0, 183.0, 186.0, 187.0, 179.0, 184.0], "value": 190.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 66.0, 69.0, 67.0, 69.0, 68.0, 68.0, 70.0], "value": 68.3, "unit": "score"}]}]} 18:48:03 INFO - Return code: 0 18:48:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:48:03 INFO - # TBPL SUCCESS # 18:48:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:48:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:48:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:48:03 INFO - 2017-05-10 18:48:03.375 screenresolution[1661:10774] starting screenresolution argv=screenresolution get 18:48:03 INFO - 2017-05-10 18:48:03.378 screenresolution[1661:10774] Display 0: 1600x1200x32@60 18:48:03 INFO - 2017-05-10 18:48:03.384 screenresolution[1662:10777] starting screenresolution argv=screenresolution list 18:48:03 INFO - Available Modes on Display 0 18:48:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:48:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:48:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:48:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:48:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:48:03 INFO - Intel Iris: 18:48:03 INFO - Chipset Model: Intel Iris 18:48:03 INFO - Type: GPU 18:48:03 INFO - Bus: Built-In 18:48:03 INFO - VRAM (Dynamic, Max): 1536 MB 18:48:03 INFO - Vendor: Intel (0x8086) 18:48:03 INFO - Device ID: 0x0a2e 18:48:03 INFO - Revision ID: 0x0009 18:48:03 INFO - Displays: 18:48:03 INFO - Display: 18:48:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:48:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:48:03 INFO - Display Serial Number: 1600x1200 60 18:48:03 INFO - Main Display: Yes 18:48:03 INFO - Mirror: Off 18:48:03 INFO - Online: Yes 18:48:03 INFO - Rotation: Supported 18:48:03 INFO - Return code: 0 18:48:03 INFO - Running post-action listener: _resource_record_post_action 18:48:03 INFO - [mozharness: 2017-05-11 01:48:03.621815Z] Finished run-tests step (success) 18:48:03 INFO - Running post-run listener: _resource_record_post_run 18:48:03 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 48720896; Write bytes: 531101184; Read time: 537; Write time: 1794 18:48:03 INFO - TinderboxPrint: CPU usage
53.9% 18:48:03 INFO - TinderboxPrint: I/O read bytes / time
48,720,896 / 537 18:48:03 INFO - TinderboxPrint: I/O write bytes / time
531,101,184 / 1,794 18:48:03 INFO - TinderboxPrint: CPU idle
2,204.8 (46.1%) 18:48:03 INFO - TinderboxPrint: CPU system
960.9 (20.1%) 18:48:03 INFO - TinderboxPrint: CPU user
1,619.9 (33.9%) 18:48:03 INFO - TinderboxPrint: Swap in / out
253,947,904 / 0 18:48:03 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205261312; Write bytes: 208578560; Read time: 15958; Write time: 424 18:48:03 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 47688704; Write bytes: 312634880; Read time: 520; Write time: 1343 18:48:04 INFO - Running post-run listener: _upload_blobber_files 18:48:04 INFO - Blob upload gear active. 18:48:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:48:04 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 18:48:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:48:04 INFO - (blobuploader) - INFO - Open directory for files ... 18:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:48:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:05 INFO - (blobuploader) - INFO - Done attempting. 18:48:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:48:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:06 INFO - (blobuploader) - INFO - Done attempting. 18:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:48:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:07 INFO - (blobuploader) - INFO - Done attempting. 18:48:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:48:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:48:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:10 INFO - (blobuploader) - INFO - Done attempting. 18:48:10 INFO - (blobuploader) - INFO - Iteration through files over. 18:48:10 INFO - Return code: 0 18:48:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:48:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:48:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2cabae909809fdc253806204a4fec5993ae53eff24a22f3e25406c2fdf28a8b1b287851bbf711fb9ba523d5f0ddaa2d4f05584b1ea8946237f10274762148de", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78358173b2e6abb62d469658c379a92c35ddda37eadeaf6efcbfb120d82ececf5bda3dd6fe8e94e2822189c36a92fb7717cdb557f63241dd6a9bde756a3ddd07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdbd9fda9f3836f1d3d19b3af9bb44e7308cb76f74f57362cbb8d900bae967545a0b78d5e4ac16197a9e1696483c9082c55cc7926e93e70c1369fc0845df88b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e2a4f700f4ca394df010b25f08978fd369bbaa8a299a27f8a717c6dd0e8771a83fb50d29a25b9fe0daac48646fc042c1d7fe47b688801d3db79e8ce77f2a360"} 18:48:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:48:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:48:10 INFO - Contents: 18:48:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2cabae909809fdc253806204a4fec5993ae53eff24a22f3e25406c2fdf28a8b1b287851bbf711fb9ba523d5f0ddaa2d4f05584b1ea8946237f10274762148de", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78358173b2e6abb62d469658c379a92c35ddda37eadeaf6efcbfb120d82ececf5bda3dd6fe8e94e2822189c36a92fb7717cdb557f63241dd6a9bde756a3ddd07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdbd9fda9f3836f1d3d19b3af9bb44e7308cb76f74f57362cbb8d900bae967545a0b78d5e4ac16197a9e1696483c9082c55cc7926e93e70c1369fc0845df88b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e2a4f700f4ca394df010b25f08978fd369bbaa8a299a27f8a717c6dd0e8771a83fb50d29a25b9fe0daac48646fc042c1d7fe47b688801d3db79e8ce77f2a360"} 18:48:10 INFO - Running post-run listener: copy_logs_to_upload_dir 18:48:10 INFO - Copying logs to upload dir... 18:48:10 INFO - mkdir: /builds/slave/test/build/upload/logs 18:48:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.906117 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-10 18:48:10.224297) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 18:48:10.227392) ========= 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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2cabae909809fdc253806204a4fec5993ae53eff24a22f3e25406c2fdf28a8b1b287851bbf711fb9ba523d5f0ddaa2d4f05584b1ea8946237f10274762148de", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78358173b2e6abb62d469658c379a92c35ddda37eadeaf6efcbfb120d82ececf5bda3dd6fe8e94e2822189c36a92fb7717cdb557f63241dd6a9bde756a3ddd07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdbd9fda9f3836f1d3d19b3af9bb44e7308cb76f74f57362cbb8d900bae967545a0b78d5e4ac16197a9e1696483c9082c55cc7926e93e70c1369fc0845df88b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e2a4f700f4ca394df010b25f08978fd369bbaa8a299a27f8a717c6dd0e8771a83fb50d29a25b9fe0daac48646fc042c1d7fe47b688801d3db79e8ce77f2a360"} build_url:https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/Ys3BKH0eQmWa1KnDy7DyhA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2cabae909809fdc253806204a4fec5993ae53eff24a22f3e25406c2fdf28a8b1b287851bbf711fb9ba523d5f0ddaa2d4f05584b1ea8946237f10274762148de", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78358173b2e6abb62d469658c379a92c35ddda37eadeaf6efcbfb120d82ececf5bda3dd6fe8e94e2822189c36a92fb7717cdb557f63241dd6a9bde756a3ddd07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdbd9fda9f3836f1d3d19b3af9bb44e7308cb76f74f57362cbb8d900bae967545a0b78d5e4ac16197a9e1696483c9082c55cc7926e93e70c1369fc0845df88b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e2a4f700f4ca394df010b25f08978fd369bbaa8a299a27f8a717c6dd0e8771a83fb50d29a25b9fe0daac48646fc042c1d7fe47b688801d3db79e8ce77f2a360"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 18:48:10.260725) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 18:48:10.261054) ========= 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.pnIA8PwjRW/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.zXVjzW1J1v/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.005092 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 18:48:10.309799) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 18:48:10.310120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 18:48:10.310436) ========= ========= Total master_lag: 0.17 =========