builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0379 starttime: 1490891430.93 results: success (0) buildid: 20170330080007 builduid: 0a6504e97a594bd5a9c1a0920dc5cb87 revision: 3364cc17988c013c36f2a8123315db2855393011 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:30.928196) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:30.928605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:30.928887) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:30.955113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:30.955434) ========= 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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-30 09:30:30-- 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.7M=0.001s 2017-03-30 09:30:31 (17.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091544 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:31.071188) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:31.071503) ========= 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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026475 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:31.120737) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-30 09:30:31.121285) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3364cc17988c013c36f2a8123315db2855393011 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3364cc17988c013c36f2a8123315db2855393011 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-30 09:30:31,225 truncating revision to first 12 chars 2017-03-30 09:30:31,226 Setting DEBUG logging. 2017-03-30 09:30:31,226 attempt 1/10 2017-03-30 09:30:31,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3364cc17988c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 09:30:33,144 unpacking tar archive at: mozilla-central-3364cc17988c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.208655 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-30 09:30:33.354787) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:33.355112) ========= script_repo_revision: 3364cc17988c013c36f2a8123315db2855393011 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:33.355516) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:33.355837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 09:30:33.375364) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-30 09:30:33.375710) ========= /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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:30:33 INFO - MultiFileLogger online at 20170330 09:30:33 in /builds/slave/test 09:30:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:30:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:33 INFO - {'append_to_log': False, 09:30:33 INFO - 'base_work_dir': '/builds/slave/test', 09:30:33 INFO - 'blob_upload_branch': 'Firefox', 09:30:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:33 INFO - 'branch': 'Firefox', 09:30:33 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:33 INFO - 'config_files': ('talos/mac_config.py',), 09:30:33 INFO - 'default_actions': ('clobber', 09:30:33 INFO - 'read-buildbot-config', 09:30:33 INFO - 'download-and-extract', 09:30:33 INFO - 'populate-webroot', 09:30:33 INFO - 'create-virtualenv', 09:30:33 INFO - 'install', 09:30:33 INFO - 'run-tests'), 09:30:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:33 INFO - 'download_minidump_stackwalk': True, 09:30:33 INFO - 'download_symbols': 'ondemand', 09:30:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:33 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:33 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:33 INFO - 'gecko_profile': False, 09:30:33 INFO - 'gecko_profile_interval': 0, 09:30:33 INFO - 'installer_path': 'installer.exe', 09:30:33 INFO - 'log_level': 'info', 09:30:33 INFO - 'log_name': 'talos', 09:30:33 INFO - 'log_to_console': True, 09:30:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:30:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:30:33 INFO - 'opt_config_files': (), 09:30:33 INFO - 'pip_index': False, 09:30:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:33 INFO - 'cmd': ('bash', 09:30:33 INFO - '-c', 09:30:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:33 INFO - 'enabled': True, 09:30:33 INFO - 'halt_on_failure': False, 09:30:33 INFO - 'name': 'check_screen_resolution'},), 09:30:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:33 INFO - 'cmd': ('bash', 09:30:33 INFO - '-c', 09:30:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:33 INFO - 'enabled': True, 09:30:33 INFO - 'halt_on_failure': False, 09:30:33 INFO - 'name': 'check_screen_resolution'},), 09:30:33 INFO - 'run_cmd_checks_enabled': True, 09:30:33 INFO - 'suite': 'dromaeojs-e10s', 09:30:33 INFO - 'system_bits': '32', 09:30:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:33 INFO - 'title': 't-yosemite-r7-0379', 09:30:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:33 INFO - 'use_talos_json': True, 09:30:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:33 INFO - 'work_dir': 'build'} 09:30:33 INFO - [mozharness: 2017-03-30 16:30:33.573447Z] Running clobber step. 09:30:33 INFO - Running pre-action listener: _resource_record_pre_action 09:30:33 INFO - Running main action method: clobber 09:30:33 INFO - rmtree: /builds/slave/test/build 09:30:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:35 INFO - Running post-action listener: _resource_record_post_action 09:30:35 INFO - [mozharness: 2017-03-30 16:30:35.990896Z] Finished clobber step (success) 09:30:35 INFO - [mozharness: 2017-03-30 16:30:35.991064Z] Running read-buildbot-config step. 09:30:35 INFO - Running pre-action listener: _resource_record_pre_action 09:30:35 INFO - Running main action method: read_buildbot_config 09:30:35 INFO - Using buildbot properties: 09:30:35 INFO - { 09:30:35 INFO - "project": "", 09:30:35 INFO - "product": "firefox", 09:30:35 INFO - "script_repo_revision": "production", 09:30:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:30:35 INFO - "repository": "", 09:30:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:30:35 INFO - "buildid": "20170330080007", 09:30:35 INFO - "pgo_build": "False", 09:30:35 INFO - "basedir": "/builds/slave/test", 09:30:35 INFO - "buildnumber": 182, 09:30:35 INFO - "slavename": "t-yosemite-r7-0379", 09:30:35 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:30:35 INFO - "platform": "yosemite_r7", 09:30:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:30:35 INFO - "branch": "mozilla-central", 09:30:35 INFO - "repo_path": "mozilla-central", 09:30:35 INFO - "revision": "3364cc17988c013c36f2a8123315db2855393011", 09:30:35 INFO - "stage_platform": "macosx64", 09:30:35 INFO - "builduid": "0a6504e97a594bd5a9c1a0920dc5cb87", 09:30:35 INFO - "slavebuilddir": "test" 09:30:35 INFO - } 09:30:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:30:35 INFO - Running post-action listener: _resource_record_post_action 09:30:35 INFO - [mozharness: 2017-03-30 16:30:35.994483Z] Finished read-buildbot-config step (success) 09:30:35 INFO - [mozharness: 2017-03-30 16:30:35.994581Z] Running download-and-extract step. 09:30:35 INFO - Running pre-action listener: _resource_record_pre_action 09:30:35 INFO - Running main action method: download_and_extract 09:30:35 INFO - mkdir: /builds/slave/test/build/tests 09:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:35 INFO - trying https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:30:35 INFO - Downloading https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:30:36 INFO - Downloaded 1325 bytes. 09:30:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:30:36 INFO - Using the following test package requirements: 09:30:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:30:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:30:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:30:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:30:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'jsshell-mac.zip'], 09:30:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:30:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:30:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:30:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:30:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:30:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:30:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:30:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:30:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:30:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:36 INFO - Fetch https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:30:38 INFO - Expected file size: 52511534 09:30:38 INFO - Obtained file size: 52511534 09:30:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:30:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:30:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:54 INFO - Fetch https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:30:56 INFO - Expected file size: 52511534 09:30:56 INFO - Obtained file size: 52511534 09:31:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:31:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:31:12 INFO - Fetch https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:31:13 INFO - Expected file size: 14404152 09:31:13 INFO - Obtained file size: 14404152 09:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:13 INFO - trying https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:31:13 INFO - Downloading https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:31:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:31:15 INFO - Downloaded 75533800 bytes. 09:31:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:31:15 INFO - mkdir: /builds/slave/test/properties 09:31:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:31:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:31:15 INFO - Contents: 09:31:15 INFO - build_url:https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:31:16 INFO - Running post-action listener: _resource_record_post_action 09:31:16 INFO - Running post-action listener: set_extra_try_arguments 09:31:16 INFO - [mozharness: 2017-03-30 16:31:16.430735Z] Finished download-and-extract step (success) 09:31:16 INFO - [mozharness: 2017-03-30 16:31:16.430840Z] Running populate-webroot step. 09:31:16 INFO - Running pre-action listener: _resource_record_pre_action 09:31:16 INFO - Running main action method: populate_webroot 09:31:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:31:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:31:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:31:16 INFO - '--tppagecycles', 09:31:16 INFO - '7'], 09:31:16 INFO - 'tests': ['tsvgm']}}, 09:31:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:31:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:31:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:31:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:31:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:31:16 INFO - 'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:31:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:31:16 INFO - 'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['damp', 'tps']}, 09:31:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:31:16 INFO - 'tests': ['damp', 'tps']}, 09:31:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['dromaeo_dom']}, 09:31:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:31:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['basic_compositor_video']}, 09:31:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:31:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['a11yr', 09:31:16 INFO - 'ts_paint', 09:31:16 INFO - 'tpaint', 09:31:16 INFO - 'sessionrestore', 09:31:16 INFO - 'sessionrestore_no_auto_restore', 09:31:16 INFO - 'tabpaint']}, 09:31:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:31:16 INFO - 'ts_paint', 09:31:16 INFO - 'tpaint', 09:31:16 INFO - 'sessionrestore', 09:31:16 INFO - 'sessionrestore_no_auto_restore', 09:31:16 INFO - 'tabpaint']}, 09:31:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:31:16 INFO - 'ts_paint', 09:31:16 INFO - 'tpaint', 09:31:16 INFO - 'sessionrestore', 09:31:16 INFO - 'sessionrestore_no_auto_restore', 09:31:16 INFO - 'tabpaint']}, 09:31:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:31:16 INFO - 'ts_paint', 09:31:16 INFO - 'tpaint', 09:31:16 INFO - 'sessionrestore', 09:31:16 INFO - 'sessionrestore_no_auto_restore', 09:31:16 INFO - 'tabpaint']}, 09:31:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['a11yr', 09:31:16 INFO - 'ts_paint', 09:31:16 INFO - 'tpaint', 09:31:16 INFO - 'sessionrestore', 09:31:16 INFO - 'sessionrestore_no_auto_restore', 09:31:16 INFO - 'tabpaint']}, 09:31:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['a11yr', 09:31:16 INFO - 'ts_paint', 09:31:16 INFO - 'tpaint', 09:31:16 INFO - 'sessionrestore', 09:31:16 INFO - 'sessionrestore_no_auto_restore', 09:31:16 INFO - 'tabpaint']}, 09:31:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['tsvgx', 09:31:16 INFO - 'tsvgr_opacity', 09:31:16 INFO - 'tart', 09:31:16 INFO - 'tscrollx', 09:31:16 INFO - 'cart', 09:31:16 INFO - 'tsvg_static']}, 09:31:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:31:16 INFO - 'tsvgr_opacity', 09:31:16 INFO - 'tart', 09:31:16 INFO - 'tscrollx', 09:31:16 INFO - 'cart', 09:31:16 INFO - 'tsvg_static']}, 09:31:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:31:16 INFO - 'talos_options': ['--disable-e10s'], 09:31:16 INFO - 'tests': ['tp5o']}, 09:31:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:31:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:31:16 INFO - 'talos_options': ['--disable-e10s', 09:31:16 INFO - '--xperf_path', 09:31:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:16 INFO - 'tests': ['tp5n']}, 09:31:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:31:16 INFO - 'talos_options': ['--xperf_path', 09:31:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:16 INFO - 'tests': ['tp5n']}}, 09:31:16 INFO - 'talos.zip': {'path': '', 09:31:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:31:16 INFO - Running post-action listener: _resource_record_post_action 09:31:16 INFO - [mozharness: 2017-03-30 16:31:16.449580Z] Finished populate-webroot step (success) 09:31:16 INFO - [mozharness: 2017-03-30 16:31:16.449712Z] Running create-virtualenv step. 09:31:16 INFO - Running pre-action listener: _resource_record_pre_action 09:31:16 INFO - Running main action method: create_virtualenv 09:31:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:31:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:31:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:31:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:31:16 INFO - Using real prefix '/tools/python27' 09:31:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:31:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:31:20 INFO - Installing pip.................done. 09:31:20 INFO - Return code: 0 09:31:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:31:20 INFO - Reading from file tmpfile_stdout 09:31:20 INFO - Output received: 09:31:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:31:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:20 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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:20 INFO - 'HOME': '/Users/cltbld', 09:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:20 INFO - 'LOGNAME': 'cltbld', 09:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:20 INFO - 'MOZ_NO_REMOTE': '1', 09:31:20 INFO - 'NO_EM_RESTART': '1', 09:31:20 INFO - 'PAGER': '/bin/cat', 09:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:20 INFO - 'PWD': '/builds/slave/test', 09:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:20 INFO - 'SHELL': '/bin/bash', 09:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:20 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:20 INFO - 'USER': 'cltbld', 09:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:20 INFO - 'XPC_FLAGS': '0x0', 09:31:20 INFO - 'XPC_SERVICE_NAME': '0', 09:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:31:20 INFO - Cleaning up... 09:31:20 INFO - Return code: 0 09:31:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:20 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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:20 INFO - 'HOME': '/Users/cltbld', 09:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:20 INFO - 'LOGNAME': 'cltbld', 09:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:20 INFO - 'MOZ_NO_REMOTE': '1', 09:31:20 INFO - 'NO_EM_RESTART': '1', 09:31:20 INFO - 'PAGER': '/bin/cat', 09:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:20 INFO - 'PWD': '/builds/slave/test', 09:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:20 INFO - 'SHELL': '/bin/bash', 09:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:20 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:20 INFO - 'USER': 'cltbld', 09:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:20 INFO - 'XPC_FLAGS': '0x0', 09:31:20 INFO - 'XPC_SERVICE_NAME': '0', 09:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:21 INFO - Downloading/unpacking psutil>=3.1.1 09:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:31:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:24 INFO - Installing collected packages: psutil 09:31:24 INFO - Running setup.py install for psutil 09:31:24 INFO - building 'psutil._psutil_osx' extension 09:31:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:31:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:31:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:31:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:31:25 INFO - building 'psutil._psutil_posix' extension 09:31:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:31:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:31:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:31:25 INFO - ^ 09:31:25 INFO - 1 warning generated. 09:31:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:31:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:25 INFO - Successfully installed psutil 09:31:25 INFO - Cleaning up... 09:31:25 INFO - Return code: 0 09:31:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:26 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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:26 INFO - 'HOME': '/Users/cltbld', 09:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:26 INFO - 'LOGNAME': 'cltbld', 09:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:26 INFO - 'MOZ_NO_REMOTE': '1', 09:31:26 INFO - 'NO_EM_RESTART': '1', 09:31:26 INFO - 'PAGER': '/bin/cat', 09:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:26 INFO - 'PWD': '/builds/slave/test', 09:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:26 INFO - 'SHELL': '/bin/bash', 09:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:26 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:26 INFO - 'USER': 'cltbld', 09:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:26 INFO - 'XPC_FLAGS': '0x0', 09:31:26 INFO - 'XPC_SERVICE_NAME': '0', 09:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:31:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:31:29 INFO - Installing collected packages: mozsystemmonitor 09:31:29 INFO - Running setup.py install for mozsystemmonitor 09:31:29 INFO - Successfully installed mozsystemmonitor 09:31:29 INFO - Cleaning up... 09:31:30 INFO - Return code: 0 09:31:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:30 INFO - 'HOME': '/Users/cltbld', 09:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:30 INFO - 'LOGNAME': 'cltbld', 09:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:30 INFO - 'MOZ_NO_REMOTE': '1', 09:31:30 INFO - 'NO_EM_RESTART': '1', 09:31:30 INFO - 'PAGER': '/bin/cat', 09:31:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:30 INFO - 'PWD': '/builds/slave/test', 09:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:30 INFO - 'SHELL': '/bin/bash', 09:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:30 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:30 INFO - 'USER': 'cltbld', 09:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:30 INFO - 'XPC_FLAGS': '0x0', 09:31:30 INFO - 'XPC_SERVICE_NAME': '0', 09:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:30 INFO - Downloading/unpacking jsonschema==2.5.1 09:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:33 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:33 INFO - warning: no files found matching '*.txt' 09:31:33 INFO - no previously-included directories found matching 'build' 09:31:33 INFO - no previously-included directories found matching 'dist' 09:31:33 INFO - no previously-included directories found matching '.git*' 09:31:33 INFO - Installing collected packages: jsonschema, functools32 09:31:33 INFO - Running setup.py install for functools32 09:31:33 INFO - warning: no files found matching '*.txt' 09:31:33 INFO - no previously-included directories found matching 'build' 09:31:33 INFO - no previously-included directories found matching 'dist' 09:31:33 INFO - no previously-included directories found matching '.git*' 09:31:33 INFO - Successfully installed jsonschema functools32 09:31:33 INFO - Cleaning up... 09:31:33 INFO - Return code: 0 09:31:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:31:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:33 INFO - 'HOME': '/Users/cltbld', 09:31:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:33 INFO - 'LOGNAME': 'cltbld', 09:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:33 INFO - 'MOZ_NO_REMOTE': '1', 09:31:33 INFO - 'NO_EM_RESTART': '1', 09:31:33 INFO - 'PAGER': '/bin/cat', 09:31:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:33 INFO - 'PWD': '/builds/slave/test', 09:31:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:33 INFO - 'SHELL': '/bin/bash', 09:31:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:33 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:33 INFO - 'USER': 'cltbld', 09:31:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:33 INFO - 'XPC_FLAGS': '0x0', 09:31:33 INFO - 'XPC_SERVICE_NAME': '0', 09:31:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:31:33 INFO - Cleaning up... 09:31:33 INFO - Return code: 0 09:31:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:31:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:33 INFO - 'HOME': '/Users/cltbld', 09:31:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:33 INFO - 'LOGNAME': 'cltbld', 09:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:33 INFO - 'MOZ_NO_REMOTE': '1', 09:31:33 INFO - 'NO_EM_RESTART': '1', 09:31:33 INFO - 'PAGER': '/bin/cat', 09:31:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:33 INFO - 'PWD': '/builds/slave/test', 09:31:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:33 INFO - 'SHELL': '/bin/bash', 09:31:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:33 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:33 INFO - 'USER': 'cltbld', 09:31:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:33 INFO - 'XPC_FLAGS': '0x0', 09:31:33 INFO - 'XPC_SERVICE_NAME': '0', 09:31:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:34 INFO - Downloading/unpacking blobuploader==1.2.4 09:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:36 INFO - Downloading blobuploader-1.2.4.tar.gz 09:31:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:31:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:37 INFO - Downloading docopt-0.6.1.tar.gz 09:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:38 INFO - Running setup.py install for blobuploader 09:31:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:38 INFO - Running setup.py install for requests 09:31:38 INFO - Running setup.py install for docopt 09:31:38 INFO - Successfully installed blobuploader requests docopt 09:31:38 INFO - Cleaning up... 09:31:38 INFO - Return code: 0 09:31:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:38 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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:38 INFO - 'HOME': '/Users/cltbld', 09:31:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:38 INFO - 'LOGNAME': 'cltbld', 09:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:38 INFO - 'MOZ_NO_REMOTE': '1', 09:31:38 INFO - 'NO_EM_RESTART': '1', 09:31:38 INFO - 'PAGER': '/bin/cat', 09:31:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:38 INFO - 'PWD': '/builds/slave/test', 09:31:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:38 INFO - 'SHELL': '/bin/bash', 09:31:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:38 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:38 INFO - 'USER': 'cltbld', 09:31:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:38 INFO - 'XPC_FLAGS': '0x0', 09:31:38 INFO - 'XPC_SERVICE_NAME': '0', 09:31:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-WfAZDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-RJPC4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-dWSPUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-MFUKAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vyFP9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-V7_b6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Oh7ANz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-_8k2em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-5k4yg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-UFAOkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-396Gwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-5CF31Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-GCs8FK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-UVvBzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-8c1kEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-hGMOgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-e8OBTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:40 INFO - Running setup.py install for manifestparser 09:31:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:41 INFO - Running setup.py install for mozcrash 09:31:41 INFO - Running setup.py install for mozdebug 09:31:41 INFO - Running setup.py install for mozdevice 09:31:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:41 INFO - Running setup.py install for mozfile 09:31:41 INFO - Running setup.py install for mozhttpd 09:31:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:41 INFO - Running setup.py install for mozinfo 09:31:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:41 INFO - Running setup.py install for mozInstall 09:31:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Running setup.py install for mozleak 09:31:42 INFO - Running setup.py install for mozlog 09:31:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Running setup.py install for moznetwork 09:31:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Running setup.py install for mozprocess 09:31:42 INFO - Running setup.py install for mozprofile 09:31:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:42 INFO - Running setup.py install for mozrunner 09:31:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:43 INFO - Running setup.py install for mozscreenshot 09:31:43 INFO - Running setup.py install for moztest 09:31:43 INFO - Running setup.py install for mozversion 09:31:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:31:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:31:43 INFO - Cleaning up... 09:31:43 INFO - Return code: 0 09:31:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:43 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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:43 INFO - 'HOME': '/Users/cltbld', 09:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:43 INFO - 'LOGNAME': 'cltbld', 09:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:43 INFO - 'MOZ_NO_REMOTE': '1', 09:31:43 INFO - 'NO_EM_RESTART': '1', 09:31:43 INFO - 'PAGER': '/bin/cat', 09:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:43 INFO - 'PWD': '/builds/slave/test', 09:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:43 INFO - 'SHELL': '/bin/bash', 09:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:43 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:43 INFO - 'USER': 'cltbld', 09:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:43 INFO - 'XPC_FLAGS': '0x0', 09:31:43 INFO - 'XPC_SERVICE_NAME': '0', 09:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-yL2YCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-e6l21p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vu_thl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-0v3p_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-e25VdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-bqqm_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-TgklZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-QKtXAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-mdhI1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-3DJIhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-rhZQ6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-dzKw6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:45 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-mvRfX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:45 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-qcObbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:45 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-X5A4BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:45 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-i6LaQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:45 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-34QoqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31:48 INFO - Downloading blessings-1.6.tar.gz 09:31:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:31:48 INFO - Installing collected packages: blessings 09:31:48 INFO - Running setup.py install for blessings 09:31:48 INFO - Successfully installed blessings 09:31:48 INFO - Cleaning up... 09:31:48 INFO - Return code: 0 09:31:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:31:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:48 INFO - Reading from file tmpfile_stdout 09:31:48 INFO - Current package versions: 09:31:48 INFO - blessings == 1.6 09:31:48 INFO - blobuploader == 1.2.4 09:31:48 INFO - docopt == 0.6.1 09:31:48 INFO - functools32 == 3.2.3-2 09:31:48 INFO - jsonschema == 2.5.1 09:31:48 INFO - manifestparser == 1.1 09:31:48 INFO - mozInstall == 1.12 09:31:48 INFO - mozcrash == 1.0 09:31:48 INFO - mozdebug == 0.1 09:31:48 INFO - mozdevice == 0.49 09:31:48 INFO - mozfile == 1.2 09:31:48 INFO - mozhttpd == 0.7 09:31:48 INFO - mozinfo == 0.9 09:31:48 INFO - mozleak == 0.1 09:31:48 INFO - mozlog == 3.4 09:31:48 INFO - moznetwork == 0.27 09:31:48 INFO - mozprocess == 0.25 09:31:48 INFO - mozprofile == 0.28 09:31:48 INFO - mozrunner == 6.13 09:31:48 INFO - mozscreenshot == 0.1 09:31:48 INFO - mozsystemmonitor == 0.3 09:31:48 INFO - moztest == 0.8 09:31:48 INFO - mozversion == 1.4 09:31:48 INFO - psutil == 3.1.1 09:31:48 INFO - requests == 1.2.3 09:31:48 INFO - wsgiref == 0.1.2 09:31:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:48 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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:48 INFO - 'HOME': '/Users/cltbld', 09:31:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:48 INFO - 'LOGNAME': 'cltbld', 09:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:48 INFO - 'MOZ_NO_REMOTE': '1', 09:31:48 INFO - 'NO_EM_RESTART': '1', 09:31:48 INFO - 'PAGER': '/bin/cat', 09:31:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:48 INFO - 'PWD': '/builds/slave/test', 09:31:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:48 INFO - 'SHELL': '/bin/bash', 09:31:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:48 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:48 INFO - 'USER': 'cltbld', 09:31:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:48 INFO - 'XPC_FLAGS': '0x0', 09:31:48 INFO - 'XPC_SERVICE_NAME': '0', 09:31:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:49 INFO - Cleaning up... 09:31:49 INFO - Return code: 0 09:31:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:49 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 0x10ee30b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee33768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb259d7f4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, '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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:49 INFO - 'HOME': '/Users/cltbld', 09:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:49 INFO - 'LOGNAME': 'cltbld', 09:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:49 INFO - 'MOZ_NO_REMOTE': '1', 09:31:49 INFO - 'NO_EM_RESTART': '1', 09:31:49 INFO - 'PAGER': '/bin/cat', 09:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:49 INFO - 'PWD': '/builds/slave/test', 09:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:49 INFO - 'SHELL': '/bin/bash', 09:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:31:49 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:49 INFO - 'USER': 'cltbld', 09:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:49 INFO - 'XPC_FLAGS': '0x0', 09:31:49 INFO - 'XPC_SERVICE_NAME': '0', 09:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:31:49 INFO - Cleaning up... 09:31:49 INFO - Return code: 0 09:31:49 INFO - Running post-action listener: _resource_record_post_action 09:31:49 INFO - Running post-action listener: _start_resource_monitoring 09:31:49 INFO - Starting resource monitoring. 09:31:49 INFO - [mozharness: 2017-03-30 16:31:49.505301Z] Finished create-virtualenv step (success) 09:31:49 INFO - [mozharness: 2017-03-30 16:31:49.506296Z] Running install step. 09:31:49 INFO - Running pre-action listener: _resource_record_pre_action 09:31:49 INFO - Running main action method: install 09:31:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:49 INFO - Reading from file tmpfile_stdout 09:31:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:31:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:31:49 INFO - Reading from file tmpfile_stdout 09:31:49 INFO - Output received: 09:31:49 INFO - Usage: mozinstall [options] installer 09:31:49 INFO - Options: 09:31:49 INFO - -h, --help show this help message and exit 09:31:49 INFO - -d DEST, --destination=DEST 09:31:49 INFO - Directory to install application into. [default: 09:31:49 INFO - "/builds/slave/test"] 09:31:49 INFO - --app=APP Application being installed. [default: firefox] 09:31:49 INFO - mkdir: /builds/slave/test/build/application 09:31:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:32:10 INFO - Reading from file tmpfile_stdout 09:32:10 INFO - Output received: 09:32:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:32:10 INFO - Running post-action listener: _resource_record_post_action 09:32:10 INFO - [mozharness: 2017-03-30 16:32:10.405620Z] Finished install step (success) 09:32:10 INFO - [mozharness: 2017-03-30 16:32:10.405758Z] Running run-tests step. 09:32:10 INFO - Running pre-action listener: _resource_record_pre_action 09:32:10 INFO - Running main action method: run_tests 09:32:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:10 INFO - 2017-03-30 09:32:10.419 screenresolution[1626:7264] starting screenresolution argv=screenresolution get 09:32:10 INFO - 2017-03-30 09:32:10.422 screenresolution[1626:7264] Display 0: 1600x1200x32@60 09:32:10 INFO - 2017-03-30 09:32:10.427 screenresolution[1627:7266] starting screenresolution argv=screenresolution list 09:32:10 INFO - Available Modes on Display 0 09:32:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:10 INFO - Intel Iris: 09:32:10 INFO - Chipset Model: Intel Iris 09:32:10 INFO - Type: GPU 09:32:10 INFO - Bus: Built-In 09:32:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:10 INFO - Vendor: Intel (0x8086) 09:32:10 INFO - Device ID: 0x0a2e 09:32:10 INFO - Revision ID: 0x0009 09:32:10 INFO - Displays: 09:32:10 INFO - Display: 09:32:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:10 INFO - Display Serial Number: 1600x1200 60 09:32:10 INFO - Main Display: Yes 09:32:10 INFO - Mirror: Off 09:32:10 INFO - Online: Yes 09:32:10 INFO - Rotation: Supported 09:32:10 INFO - Return code: 0 09:32:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:32:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:32:10 INFO - Python 2.7.3 09:32:10 INFO - Return code: 0 09:32:10 INFO - grabbing minidump binary from tooltool 09:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:10 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 0x10ec0ed30>, '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 0x10eb35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec03be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:32:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:32:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:32:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:32:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:32:10 INFO - Return code: 0 09:32:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:32:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:32:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:32:10 INFO - ENV: RUST_BACKTRACE is now 1 09:32:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:32:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:32:10 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/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:32:10 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/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0379 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D3yl1iGMqI/Render', 09:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:10 INFO - 'HOME': '/Users/cltbld', 09:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:10 INFO - 'LOGNAME': 'cltbld', 09:32:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:32:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:10 INFO - 'MOZ_NO_REMOTE': '1', 09:32:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:32:10 INFO - 'NO_EM_RESTART': '1', 09:32:10 INFO - 'PAGER': '/bin/cat', 09:32:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:10 INFO - 'PWD': '/builds/slave/test', 09:32:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:10 INFO - 'RUST_BACKTRACE': '1', 09:32:10 INFO - 'SHELL': '/bin/bash', 09:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYx6QBCWCx/Listeners', 09:32:10 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:10 INFO - 'USER': 'cltbld', 09:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:10 INFO - 'XPC_FLAGS': '0x0', 09:32:10 INFO - 'XPC_SERVICE_NAME': '0', 09:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:10 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/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:32:11 INFO - mozversion application_buildid: 20170330080007 09:32:11 INFO - mozversion application_changeset: 3364cc17988c013c36f2a8123315db2855393011 09:32:11 INFO - mozversion application_display_name: Nightly 09:32:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:32:11 INFO - mozversion application_name: Firefox 09:32:11 INFO - mozversion application_remotingname: firefox 09:32:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:32:11 INFO - mozversion application_vendor: Mozilla 09:32:11 INFO - mozversion application_version: 55.0a1 09:32:11 INFO - mozversion platform_buildid: 20170330080007 09:32:11 INFO - mozversion platform_changeset: 3364cc17988c013c36f2a8123315db2855393011 09:32:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:32:11 INFO - mozversion platform_version: 55.0a1 09:32:11 INFO - using testdate: 1490891531 09:32:11 INFO - actual date: 1490891531 09:32:11 INFO - starting webserver on 'localhost:49236' 09:32:11 INFO - SUITE-START | Running 2 tests 09:32:11 INFO - TEST-START | dromaeo_css 09:32:11 INFO - operating with platform_type : mac_ 09:32:11 INFO - Initialising browser for dromaeo_css test... 09:32:11 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpkSj39t/profile http://localhost:49236/getInfo.html) 09:32:14 INFO - PID 1637 | 2017-03-30 09:32:14.011 plugin-container[1639:7442] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 09:32:14 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:14 INFO - PID 1637 | 2017-03-30 09:32:14.014 plugin-container[1639:7442] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x921b, name = 'com.apple.CFPasteboardClient' 09:32:14 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:14 INFO - PID 1637 | 2017-03-30 09:32:14.014 plugin-container[1639:7442] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:32:14 INFO - PID 1637 | __metrics Screen width/height:1600/1200 09:32:14 INFO - PID 1637 | colorDepth:24 09:32:14 INFO - PID 1637 | Browser inner width/height: 1024/689 09:32:14 INFO - PID 1637 | __metrics 09:32:14 ERROR - PID 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 09:32:14 ERROR - PID 1637 | 1490891534177 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpkSj39t/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpkSj39t/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpkSj39t/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpkSj39t/profile/extensions/talos-powers@TalosPowersService.js:55:9 09:32:14 INFO - TEST-INFO | 1637: exit 0 09:32:14 INFO - Browser initialized. 09:32:14 INFO - Running cycle 1/1 for dromaeo_css test... 09:32:14 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpkSj39t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:17 INFO - PID 1640 | 2017-03-30 09:32:17.240 plugin-container[1641:7567] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 09:32:17 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:17 INFO - PID 1640 | 2017-03-30 09:32:17.241 plugin-container[1641:7567] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 09:32:17 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:17 INFO - PID 1640 | 2017-03-30 09:32:17.241 plugin-container[1641:7567] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:32:26 INFO - PID 1640 | RSS: Main: 229240832 09:32:26 INFO - PID 1640 | 09:35:30 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 09:35:32 INFO - PID 1640 | RSS: Main: 253673472 09:35:32 INFO - PID 1640 | 09:37:15 INFO - PID 1640 | ************************* 09:37:15 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 09:37:15 INFO - PID 1640 | 09:37:15 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:37:15 INFO - PID 1640 | Full stack: 09:37:15 INFO - PID 1640 | ************************* 09:37:15 INFO - PID 1640 | ************************* 09:37:15 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 09:37:15 INFO - PID 1640 | 09:37:15 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:37:15 INFO - PID 1640 | Full stack: 09:37:15 INFO - PID 1640 | ************************* 09:37:15 INFO - PID 1640 | ************************* 09:37:15 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 09:37:15 INFO - PID 1640 | 09:37:15 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:37:15 INFO - PID 1640 | Full stack: 09:37:15 INFO - PID 1640 | ************************* 09:37:15 INFO - PID 1640 | ************************* 09:37:15 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 09:37:15 INFO - PID 1640 | 09:37:15 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:37:15 INFO - PID 1640 | Full stack: 09:37:15 INFO - PID 1640 | ************************* 09:38:35 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 09:38:35 INFO - PID 1640 | RSS: Main: 260284416 09:38:35 INFO - PID 1640 | 09:41:37 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 09:41:38 INFO - PID 1640 | RSS: Main: 272265216 09:41:38 INFO - PID 1640 | 09:44:40 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 09:44:40 INFO - PID 1640 | RSS: Main: 264675328 09:44:40 INFO - PID 1640 | 09:47:43 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 09:47:44 INFO - PID 1640 | RSS: Main: 275365888 09:47:44 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 09:50:47 INFO - PID 1640 | RSS: Main: 269205504 09:50:47 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | __start_tp_report 09:50:47 INFO - PID 1640 | _x_x_mozilla_page_load 09:50:47 INFO - PID 1640 | _x_x_mozilla_page_load_details 09:50:47 INFO - PID 1640 | |i|pagename|runs| 09:50:47 INFO - PID 1640 | |0;dojo.html;2753.5181343264935;3017.2155239637696;3671.284099600578;3677.4483827425865;3681.337359275329;6113.050656958994;6268.153799237096;6271.937280627192;6293.370662933704;6293.622382657044;6187.845303867405;6194.070889366586;6197.566170368076;6233.625982441045;6326.588771729846;5825.44658257467;5860.09168578871;5884.735186916578;5904.409559044104;5907.940920590782;5876.882462350772;5929.169916211734;5942.138389933457;5953.761849526014;5967.761289548434;4704.270838020112;5237.13587258102;5812.9709351452975;5888.911666325009;5918.05311150216;4780.972091000417;5136.152534831748;5618.4662457066515;5751.05107657236;5777.046787280094;5654.858628534278;5664.178694089375;5664.575156863251;5710.857228569277;5711.571632127607;5804.303483581955;6155.661438620874;6161.7535298588;6277.811665650038;6282.308946015934;6089.269287685506;6387.93612063878;6617.834554136137;6687.832804179886;6797.932020679779;6841.931580684229;6867.759628412981;6881.621510816903;6888.724451021953;6890.517663763539;6109.677964500999;6295.276043254993;6321.083442900754;6347.270063942613;6362.363763623694;6136.21274067861;6866.519343645897;6949.166100068024;6959.652017399109;6993.755218567428;6362.236531616236;6386.8722625547225;6484.773032943919;6553.082568440422;6709.060731497594;6792.3886850182735;6861.073755043105;6905.240423553405;6913.688884000232;6920.5847649141215;6452.161219041588;6793.45652347811;6805.863882722318;6807.591544507345;6816.625085620339;5656.349519805192;5954.37479064692;6441.710123044474;6616.702248398834;6662.067310576524;5407.351117865803;5414.377346605191;5426.538744206787;5432.91850622241;5450.073487507133;4682.297655351656;4783.545563171493;4792.0895029944195;4823.155947709137;4836.371271734652;6267.1539342188225;6329.3354197809485;6397.936020639827;6429.292777794439;6443.935560644333;6900.827479313058;6922.203946546111;6970.372666460043;6972.372486476242;6975.232724400312;6404.807855764335;6455.806325810233;6464.385883341075;6481.708323125471;6485.221773387224;5008.724520151354;5865.002949498596;6030.306514750772;6065.059915713072;6107.053406721965;7050.471214658818;7073.858522829618;7081.079459670416;7097.858042839011;7113.288671133052;6855.5886646801355;6868.759593414206;6875.243723190345;6879.140107486564;6936.34104760047;6772.187337519529;6884.931150688429;6989.895151572832;7027.648617568999;7066.964665176642;6752.3922846944015;6787.117674702257;6793.490488213305;6799.93200067993;6819.829504262433;5669.829905102853;6584.374484423972;6823.215330236888;6889.689963951734;6890.345417185359;6300.401461861115;6406.615603063831;6424.325445828121;6516.869662606816;6593.3736295051895;6626.171728533934;6647.833804154741;6650.168728908887;6653.833654158685;6655.73377064912;5393.595480338912;6323.893318669123;6356.841078972878;6483;6499.5125365598715;6371.267304260069;6426.903596445964;6446.613203207822;6564.671766411565;6661.933380666132;5778.653280803165;6285.654289013965;6291.874162516816;6422.807315780534;6457.806265812033 09:50:47 INFO - PID 1640 | |1;ext.html;714.8070021094304;740.1451323721102;804.1717031457597;1268.8033354830004;1276.3299267884365;38009.23981520371;38944.610553894454;38995.0251243719;39039.633612823556;39176.60823391769;38615.806920965355;38948;38962;39006.80496597513;39013.21973560527;8020.0776910655295;8021.19788021199;8184.549849758261;8858.202761751441;8868.467891926504;8755.299576033934;8808.867866982;8875.866861997076;8896.199342059213;8901.688440904569;8803.779905502382;8809.295256379473;8877.822443551126;8883;8929.151730585583;8820.911790882074;8844.425112367675;8897.110288971116;8897.288216942628;8899.37704360695;8604.483730976099;8607.784805379915;8660;8673.089325620846;8683.478991260545;38528.844134676;38800.417993730116;38810.61189388098;38833.223335533134;38929.22141557153;2993.5133895406548;3007.65411977621;3008.7743419243643;3016.5173572228337;3030.469667808147;28045.43909121806;28181;28280.717192828015;28295.858520707472;29155.70844291551;2794.762445192161;2807.7192280771965;2820.252633052243;2828.1232817826335;2860.985695071532;277416;281416;311463.99999999773;334403;337185;48656.27015594769;48728.02543949172;48731.26903096456;48966.75516622394;49038.75480622574;52056.47943520592;52107.47892521102;52253.477465225624;52303.47696523062;52355.73822130865;14329.498467553585;14400.711985760227;14406.42374305037;14416.639584010483;14513.346899389553;11628.592999245155;11758;11771.23486973344;11791.764164716658;11798.351090690094;12092.334921579397;12720.72792720739;13772.380242889094;13815.723685526233;14011.439542418393;6058.57589968698;6059.697015149225;6064.545159113084;6115.6330620162935;6120.08198770188;951.3198063384713;976.5312649928075;983.6311383231289;985.192142443204;1027.6865556005341;19463.416097517096;21749;21775.128994840346;24656;24864.37839054038;1070.6038765656756;1071.6838532632892;1118.6588090632436;1120.9495572699248;1132.224426268009;11818;12594.81107783384;12640.557580484823;12668.36658167088;12702.42839072244;399527;399573;400985;401429;402459;56139;56363.15455268174;56378.43621563814;56529.717351413805;56631.150532741216;1433.8136042314432;1471.9264036798331;1504.601280660626;1510.9848901510845;1517.051842598376;24031.399215019763;25730.970761169345;29192.36651904824;29430.264243394085;29548.409031819672;51239;51320.48679513157;51459.48540514547;51719.74140129426;51734.482655172964;390492;391628;392256;392510;392983.2806687815;33361.49957750587;33461.832690837364;33729.662703372655;34769.478457822646;35022.47466288059;43283;44477.777611111735;44485.777571113234;44591.77704111459;44703;28751.568726469537;29238;29526.261843454085;30464.543031854984;30804.92182773592;25297.367565811;29131.126066218047;30287.182769033927;30588;30829.845850770602;35201.94394168179;35678;35789;36227.63772362243;36692.44961325636;27915;28799.712002879704;30364.84817575898;31028.844855775576;31156;20774.792252077892;22900.54198915979;24782.256532304058;24801;24977.37376550267 09:50:47 INFO - PID 1640 | |2;jquery.html;414.3929143804326;414.4549916859328;423.8241129931078;439.3980247061526;440.8633323669663;16412.01527908326;20329.49176270594;21487.57024859504;22453.101875924942;22669.433264168405;18804.717929231072;21874.01566929488;22446.214382496615;23152.536949261004;23269.30192094236;5974.522038236931;6252.187215661957;6262.2172228471445;6281.214848143982;6284.7486100555925;5855.882882342372;6116.969415152918;6140.662263575501;6170.598911070766;6192.876142477147;5867.178594996704;6122;6127.724252408652;6132.846678833021;6139.140520327158;5322.2814919985885;6023.488003519706;6131.846703832418;6160;6162;5379.139337705988;5423.430539793344;5496.065668836308;5499.50504454597;5507.586930980193;26943;27158.864205678845;27455.45089098227;28381.716182838114;28600.856995715098;1936.8547358947994;1954.3159894036974;1955.6479833629942;1975.9110840012233;2025.8075482829108;16296.755548666779;16914.56312619251;17097.48707538776;17132.571685707833;17193.398231061852;1609.871210303173;1729.3860679458862;1735.2885317019961;1736.9913150434168;1801.2434777393526;149025.25487372602;157289;158541.20729396393;170259;177083;12683;12865.292408917412;12871.356432178542;13005.089643725038;13162.670933226555;16242.837571624368;16302.429414970657;16506.669866602842;16539.586510337336;16555.08947007926;8892.688755893641;9191.908080919238;9206.26349892007;9338.673146439842;9349.392289501297;8919.840420745391;8942.86585701215;8943.821123577622;9092.408993415407;9108.49903255314;7875.7243496478495;7965.402594805297;7990.923494454561;8048.275655191061;8058.1538938411795;2139.668351405535;2144.3517256188593;2145.9592097832538;2154.418307057117;2313.9702832239827;890.5324704530044;901.4546199549178;905.8641203819479;936.8220038192721;941.4069136443997;19616;20347.796522034885;20672.79327206739;20928.895355523124;21104.261350852645;924.523870206844;942.0358552418148;968.6222373274429;974.055166488505;987.4865070163474;9713.562889669982;10272.640457583948;10739.570417183226;10800.405977671146;10866.728331791768;649651;669390;670277;692530;719015;133248;159193;159368;166267;167622.54597801095;1023.2070145637191;1139.2651739628059;1155.185594156118;1172.2439026827649;1194.4923407551755;16892.324307027582;18307.542311441786;18317.725234121233;18333.3583324583;18700.625987480445;63398.92808575211;63415.0487742675;65358;65970.34029659642;66074.33925660874;86081.70877436719;86606.7008994853;86899;86904.5654771722;89400;8604.440711353742;8789.38474306818;8827.24968377695;8902.59938302765;9084.954575227082;9751.756206094904;14071.155730656194;14412.783808243095;14484.986050976331;14556.417743290573;11451.541938322374;11564.94217528907;11613.245139066272;11762.294262344287;12027.75944481123;10767.6231331903;10949.069329107115;10971.780564388508;11258.605948791754;11347.886521134684;11469.885301146882;11487.310761354345;11650.70873228176;12191.085668574715;12269.263844169378;8039.477433966774;8716.607752651273;8741.300695944436;8974.339747146538;9123.680671176742;11621.966155922848;11730.530778768754;11877.58428455;11929.164958452826;12054.698632534257 09:50:47 INFO - PID 1640 | |3;mootools.html;1302.2029599153168;1337.6321511584313;1345.6703107738592;1355.3020194599783;1369.0348304445367;10778.514966826491;10803.351798892072;11045.944770276137;11255.831162532559;11271;10914.399708016055;11072.612458563952;11083.889161108387;11100;11110.555627768399;2418.927432177033;2469.493753780471;2476.3437689012426;2480.7271200167966;2488.850668959859;2396.089485995319;2420.2134306350417;2459.040974020125;2462.926112216627;2468.4075821802726;2407.4442675159253;2412.915547955813;2437.2079074300837;2451.570975079363;2455.238875948453;2366.6331718583647;2414.106780491221;2427.3082171581104;2428.5871401861637;2450.301664025762;2399.6160614301803;2405.4226985523524;2424.9936276445255;2433.2335314375914;2510.184190138212;11001.889981100167;11082.722931926686;11097.44512774358;11101.666949991513;11132.944335278353;5076.847694569169;6464.644444555547;6475.7733479328;6478.2873883872735;6486.383793539606;4031.23406552754;4063.471748672683;4080.489938757655;4086.4074709167003;4113;903;1025.8409946458312;1163.1857699610307;1170.8770579089244;1216.5681183179931;13840.446382144703;14467.131972081708;15142.091474511564;15304.617384565363;15338.156401397919;4607.378003969421;5322.0952438085615;5464.9453505464435;5497.945020549823;5521;11587.130965177477;11642;11672.591459299054;11683.415829208505;11727.237729547553;6240.375962403723;6335.081413195153;6341.270753863365;6350.68246587678;6373.107764912823;5770.394108618535;5891.41085891416;6009.669468179292;6020.729067191987;6066.636001839904;4206.137741762931;4305.31115021595;4340.82636694535;4352;4407.338899165086;1232.3653318540958;1263.317808383481;1272.049098717562;1276.9553065642845;1280.8014757712383;719.6186021408662;730.0472882887776;751.0124186694479;761.5392687424139;786.598834594361;5824.504917082011;5938.198343223609;6024.728887200086;6120.234970628671;6134.723937422827;543.5217009032026;562.8395907166438;576.9528306124371;580.0574067140896;580.4166812353558;3755.3052685253315;4105.58944105593;4359.912801744011;4466.821327146878;4580.656450766206;10913.345199288067;14215.786763198561;14235.64410889736;14239.93200509966;14273;13729.519466818614;13931.303434828218;13968.511102111377;14126.64683382903;14213.218272994878;10124;10441.425721585234;10453.163746900382;10455.790884182274;10491;5710.942890571041;6553;6642.43539299165;6771.085903403175;6783.05037294789;12163.087768417581;12181.878181218075;12393.442295096922;12747.553835615709;12941;12710.936445317715;12762.553310634083;12788.976881849243;12975.545855894998;12977.286249256382;9027.638894444148;9299.163075323253;9302.069793020644;9325.393849399767;9353.85969210476;10128.493575321058;10164.491775411348;10198.286119971532;10218.131458826083;10275.588976441157;8584;8717.346199034972;8725.65097396097;8806.251468625238;8845.4250473719;8441.746747597583;8459.661613535389;8463.53450560225;8592.183742544448;8602.870956935525;7814.374850012099;7835.608219589112;7875.094364147967;7918.564478953713;7931.643076061707;5345.78616855337;5664.886702266014;5814.186013958134;5906.26171728534;6017.247844019497;5893.469587737124;6006.369331220334;6023.427774361428;6052.152698622109;6085.878282434238 09:50:47 INFO - PID 1640 | |4;prototype.html;376.65536034528407;401.9758814471132;404.23599397139407;404.77130421311955;414.55228353378357;5221.5822734181265;6382.425581697652;6390.3609639036185;6404;6462;4854.635902307324;5054.21659642756;5118.5905127589795;5342.439043900383;6254.593451425654;4856.3686720726255;4865.14859899518;4877.292792545097;4899.559039686427;4901.705897646153;4860.246661767432;4903.117438861003;4906.484819093997;4919.557239848413;4952.182889823175;4186.434831297766;4519.367288579601;4556.2913907284765;4798.232282834747;4867.172580661296;4318.870433886973;4331.3719510670935;4848.757562121915;4852.8544143675745;4876;3369.6461871503384;4265.637420819234;4279.2083464559155;4286.464191976003;4313.525512193656;5308.628396012281;5452.700101494416;6049.879002419971;6069.878602427927;6171.59884607499;4663.836765713218;5560.4161563035705;5573.303337082864;5578.553715702734;5587.38538760736;5739.885202295931;5773.220615216934;6256.78101266458;6662.367075127855;6708.19501659804;3570.892873213808;3883.7475564088527;4301.784910754481;4371.125774845012;4437.689361744679;6770;7028.367446929801;7040;7119.50163488556;7131.251218622022;4217.388478670576;4762.14281429346;4795.448523419851;4902.950970490249;4917.311576379309;4163.292240319153;5019.171836646985;5025.522575355409;5030.673006254655;5035.546800787946;5396.163594642837;5555.611107222537;5613.978967571671;5635.716855190085;5644.943550564524;6698.698558564876;6719.42884854783;6751.291114433012;6780.69486873082;6820.067371030505;1629;1660.850523452895;1714.1943286655253;1731.4199743086208;1732.4023211991844;1170.4849866058908;1177.6113882418952;1187.2342339191173;1193.3555879824798;1208.130146294684;5753.194552762617;5924.6149000314845;6267.561270711098;6307.180066591313;6328.272248691367;5619.550435965195;6194.194754681863;6298.212723409574;6631.568948018364;6961.61711105894;5763.7406316715005;5969.582129250911;5988.401159884064;6206.782762603287;6351.777687780906;3163.7469002479747;3402.030421329909;3627.165751877083;3639.5086663300117;3667.0832291927018;6024.909626355608;6524.706388212542;6547.526806468506;6806.217285012188;7141.7864958579485;6824.863502729918;7144.571325720473;7161.856762864818;7179.353858152791;7192.31672991065;5326.334208223972;6372.9166041773005;6434.324395938546;6799.402140496979;7127.964360178166;6858.279880612464;6858.725650973905;6923.89614155778;6930.896036559355;6935.479839012195;5964.582479226413;6123.173371594778;6696.531242812956;6806.353396427331;6834.2482326943;7025.962470883386;7045.506814522934;7087;7116.3988102676485;7144.749933752294;4124.979375103106;4268.978655106829;4799.424069111729;4855.344528488608;4905.632077594266;5955.898158840629;6531.575447595891;6678.265390807104;6836.931630683828;6845.178578570603;5752.827415177551;6099.451049405575;6554.541182117206;6598.670066496752;6692.330766923269;6259.965511208784;6280.152179455898;6596.967015165085;6616.386527321777;6682.264950855305;6429.903551446639;6433.233709504958;6455.741770329134;6469.738401011758;6504.414602685781;5970.835687040985;6413.294537600954;6432.774852880127;6461.64460954652;6613.511959809004;5760.423957604206;6465.676716164267;6480.513961452815;6496.480281577557;6511.934880651323 09:50:47 INFO - PID 1640 | |5;yui.html;694.4203670224625;723.9565626062436;726.7753834788379;727.7707522130529;729.3982464466815;14809.925950370247;15529.456469023586;15548.922255388707;15563.922180389096;15576.688466230682;15043;15077.472288469906;15117.848821511781;15181.924090379533;15186.772198417006;909.3816204980627;938.7183844846519;943.9622415103388;955.9761005974872;985.7387792235028;979.5445118020119;990.6334656177224;992.6575331510618;993.4536005197149;994.796066806303;1025.7333093395735;1036.383351905615;1037.5382954585214;1037.891021442749;1039.428314427066;1032.9741756456149;1035.927485076045;1035.9533820978074;1037.0770014686934;1037.792441511697;760.6691089376139;765.4488768086968;765.6057130577757;766.6550052476352;767.0680123649737;16306.347746090261;16584.004959702335;16705.164741762863;16736.497905062868;16999.830001700073;14733;14753.77869331961;14785.67525952425;14908.403663853433;14924.850751492564;11476.196666233283;11661.416929153593;11765.529378824836;12081;12208.32854193019;2991.850407479639;3058.663547009827;3156.71589556941;3163.6519982801874;3163.6994485523837;110512;112193;114212.42893785561;114466;116098.41950790277;11103.944480277547;11359.943200284111;11565.537378504934;11630.302181868945;12019.399030048462;24568.140115095885;24878.37804054913;24878.37804054913;24942.87757050901;25678.74321256801;6252.218472690914;7705.691772329156;7780.260875216845;7840.41196910234;7873.133955264916;3483.512308276843;4045.9797701011894;4115.7324773890205;4130.607592278729;4132.173565286955;4112.033672087057;4184.916301673949;4186.706930514834;4194.517630472535;4227.492700875914;639.5171645407687;759.5518644000066;791.4262159934117;808.4462143431768;808.4946908182386;535.635767677975;551.7241379310345;568.8947544704243;588.2117961931033;598.0670154558815;8738.737837864874;9392.62429502826;10359.119474844572;10422.947885260677;10443.895561044292;494.868859752166;504.26532727417566;568.9956829682949;575.516566084491;602.668532307238;4149.688773342012;4587.495375508758;4719.003432820115;4803.231482962779;4896.657233993657;105234.47382763037;108950;110156;110854.78290434147;112494.31258531129;92164.53917730368;93158.53420732853;94670.0532994675;95629.52185239168;97548;81948;83317.75023374654;83592.58203708942;83659.5102599601;83908;9419.529023548657;9571.712848614552;9584.050046844572;9604.183644390025;9617.951910240685;40387;40416.79791601023;40778.59221407748;41418.58581414147;42289.365659515744;49036.01927961492;49850;50049;50548.935299957135;50562.747186263834;10540.52567634443;11498.425078746197;11621.593244236412;11827.467763950472;12407.31759753186;12406.751864962831;12422.689432763891;12555.246685198552;12582.496700131893;12659.683507912008;11602.18784685064;11856.22934509254;11992.460339284578;12248.448819803309;12255.754884902073;11766.461110005695;11863.406829658656;11874.940625296818;11926.403679815801;11972.461239244081;11622.360770157722;11671.533138674698;11725.355105469282;11832.17174797756;11847.289162650268;8659.263962563;8869.696278594556;8995.955020224857;9271.119243671841;9293.25653947669;9510.334276600848;9928.553215105194;9971.40171589707;10001.799964000533;10209.9489502552 09:50:47 INFO - PID 1640 | __end_tp_report 09:50:47 INFO - PID 1640 | __start_cc_report 09:50:47 INFO - PID 1640 | _x_x_mozilla_cycle_collect,435 09:50:47 INFO - PID 1640 | __end_cc_report 09:50:47 INFO - PID 1640 | __startTimestamp1490892647622__endTimestamp 09:50:47 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | ------- Summary: start ------- 09:50:47 INFO - PID 1640 | Number of tests: 6 09:50:47 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6222.93 Median:6387.94 stddev:760.97 (11.9%) stddev-sans-first:712.98 09:50:47 INFO - PID 1640 | Values: 2753.5 3017.2 3671.3 3677.4 3681.3 6113.1 6268.2 6271.9 6293.4 6293.6 6187.8 6194.1 6197.6 6233.6 6326.6 5825.4 5860.1 5884.7 5904.4 5907.9 5876.9 5929.2 5942.1 5953.8 5967.8 4704.3 5237.1 5813.0 5888.9 5918.1 4781.0 5136.2 5618.5 5751.1 5777.0 5654.9 5664.2 5664.6 5710.9 5711.6 5804.3 6155.7 6161.8 6277.8 6282.3 6089.3 6387.9 6617.8 6687.8 6797.9 6841.9 6867.8 6881.6 6888.7 6890.5 6109.7 6295.3 6321.1 6347.3 6362.4 6136.2 6866.5 6949.2 6959.7 6993.8 6362.2 6386.9 6484.8 6553.1 6709.1 6792.4 6861.1 6905.2 6913.7 6920.6 6452.2 6793.5 6805.9 6807.6 6816.6 5656.3 5954.4 6441.7 6616.7 6662.1 5407.4 5414.4 5426.5 5432.9 5450.1 4682.3 4783.5 4792.1 4823.2 4836.4 6267.2 6329.3 6397.9 6429.3 6443.9 6900.8 6922.2 6970.4 6972.4 6975.2 6404.8 6455.8 6464.4 6481.7 6485.2 5008.7 5865.0 6030.3 6065.1 6107.1 7050.5 7073.9 7081.1 7097.9 7113.3 6855.6 6868.8 6875.2 6879.1 6936.3 6772.2 6884.9 6989.9 7027.6 7067.0 6752.4 6787.1 6793.5 6799.9 6819.8 5669.8 6584.4 6823.2 6889.7 6890.3 6300.4 6406.6 6424.3 6516.9 6593.4 6626.2 6647.8 6650.2 6653.8 6655.7 5393.6 6323.9 6356.8 6483.0 6499.5 6371.3 6426.9 6446.6 6564.7 6661.9 5778.7 6285.7 6291.9 6422.8 6457.8 09:50:47 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:51218.60 Median:36008.32 stddev:97720.41 (271.4%) stddev-sans-first:97920.97 09:50:47 INFO - PID 1640 | Values: 714.8 740.1 804.2 1268.8 1276.3 38009.2 38944.6 38995.0 39039.6 39176.6 38615.8 38948.0 38962.0 39006.8 39013.2 8020.1 8021.2 8184.5 8858.2 8868.5 8755.3 8808.9 8875.9 8896.2 8901.7 8803.8 8809.3 8877.8 8883.0 8929.2 8820.9 8844.4 8897.1 8897.3 8899.4 8604.5 8607.8 8660.0 8673.1 8683.5 38528.8 38800.4 38810.6 38833.2 38929.2 2993.5 3007.7 3008.8 3016.5 3030.5 28045.4 28181.0 28280.7 28295.9 29155.7 2794.8 2807.7 2820.3 2828.1 2861.0 277416.0 281416.0 311464.0 334403.0 337185.0 48656.3 48728.0 48731.3 48966.8 49038.8 52056.5 52107.5 52253.5 52303.5 52355.7 14329.5 14400.7 14406.4 14416.6 14513.3 11628.6 11758.0 11771.2 11791.8 11798.4 12092.3 12720.7 13772.4 13815.7 14011.4 6058.6 6059.7 6064.5 6115.6 6120.1 951.3 976.5 983.6 985.2 1027.7 19463.4 21749.0 21775.1 24656.0 24864.4 1070.6 1071.7 1118.7 1120.9 1132.2 11818.0 12594.8 12640.6 12668.4 12702.4 399527.0 399573.0 400985.0 401429.0 402459.0 56139.0 56363.2 56378.4 56529.7 56631.2 1433.8 1471.9 1504.6 1511.0 1517.1 24031.4 25731.0 29192.4 29430.3 29548.4 51239.0 51320.5 51459.5 51719.7 51734.5 390492.0 391628.0 392256.0 392510.0 392983.3 33361.5 33461.8 33729.7 34769.5 35022.5 43283.0 44477.8 44485.8 44591.8 44703.0 28751.6 29238.0 29526.3 30464.5 30804.9 25297.4 29131.1 30287.2 30588.0 30829.8 35201.9 35678.0 35789.0 36227.6 36692.4 27915.0 28799.7 30364.8 31028.8 31156.0 20774.8 22900.5 24782.3 24801.0 24977.4 09:50:47 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:40560.38 Median:12733.25 stddev:114784.14 (901.5%) stddev-sans-first:115066.56 09:50:47 INFO - PID 1640 | Values: 414.4 414.5 423.8 439.4 440.9 16412.0 20329.5 21487.6 22453.1 22669.4 18804.7 21874.0 22446.2 23152.5 23269.3 5974.5 6252.2 6262.2 6281.2 6284.7 5855.9 6117.0 6140.7 6170.6 6192.9 5867.2 6122.0 6127.7 6132.8 6139.1 5322.3 6023.5 6131.8 6160.0 6162.0 5379.1 5423.4 5496.1 5499.5 5507.6 26943.0 27158.9 27455.5 28381.7 28600.9 1936.9 1954.3 1955.6 1975.9 2025.8 16296.8 16914.6 17097.5 17132.6 17193.4 1609.9 1729.4 1735.3 1737.0 1801.2 149025.3 157289.0 158541.2 170259.0 177083.0 12683.0 12865.3 12871.4 13005.1 13162.7 16242.8 16302.4 16506.7 16539.6 16555.1 8892.7 9191.9 9206.3 9338.7 9349.4 8919.8 8942.9 8943.8 9092.4 9108.5 7875.7 7965.4 7990.9 8048.3 8058.2 2139.7 2144.4 2146.0 2154.4 2314.0 890.5 901.5 905.9 936.8 941.4 19616.0 20347.8 20672.8 20928.9 21104.3 924.5 942.0 968.6 974.1 987.5 9713.6 10272.6 10739.6 10800.4 10866.7 649651.0 669390.0 670277.0 692530.0 719015.0 133248.0 159193.0 159368.0 166267.0 167622.5 1023.2 1139.3 1155.2 1172.2 1194.5 16892.3 18307.5 18317.7 18333.4 18700.6 63398.9 63415.0 65358.0 65970.3 66074.3 86081.7 86606.7 86899.0 86904.6 89400.0 8604.4 8789.4 8827.2 8902.6 9085.0 9751.8 14071.2 14412.8 14485.0 14556.4 11451.5 11564.9 11613.2 11762.3 12027.8 10767.6 10949.1 10971.8 11258.6 11347.9 11469.9 11487.3 11650.7 12191.1 12269.3 8039.5 8716.6 8741.3 8974.3 9123.7 11622.0 11730.5 11877.6 11929.2 12054.7 09:50:47 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6816.97 Median:2478.54 stddev:4227.75 (170.6%) stddev-sans-first:4219.30 09:50:47 INFO - PID 1640 | Values: 1302.2 1337.6 1345.7 1355.3 1369.0 10778.5 10803.4 11045.9 11255.8 11271.0 10914.4 11072.6 11083.9 11100.0 11110.6 2418.9 2469.5 2476.3 2480.7 2488.9 2396.1 2420.2 2459.0 2462.9 2468.4 2407.4 2412.9 2437.2 2451.6 2455.2 2366.6 2414.1 2427.3 2428.6 2450.3 2399.6 2405.4 2425.0 2433.2 2510.2 11001.9 11082.7 11097.4 11101.7 11132.9 5076.8 6464.6 6475.8 6478.3 6486.4 4031.2 4063.5 4080.5 4086.4 4113.0 903.0 1025.8 1163.2 1170.9 1216.6 13840.4 14467.1 15142.1 15304.6 15338.2 4607.4 5322.1 5464.9 5497.9 5521.0 11587.1 11642.0 11672.6 11683.4 11727.2 6240.4 6335.1 6341.3 6350.7 6373.1 5770.4 5891.4 6009.7 6020.7 6066.6 4206.1 4305.3 4340.8 4352.0 4407.3 1232.4 1263.3 1272.0 1277.0 1280.8 719.6 730.0 751.0 761.5 786.6 5824.5 5938.2 6024.7 6120.2 6134.7 543.5 562.8 577.0 580.1 580.4 3755.3 4105.6 4359.9 4466.8 4580.7 10913.3 14215.8 14235.6 14239.9 14273.0 13729.5 13931.3 13968.5 14126.6 14213.2 10124.0 10441.4 10453.2 10455.8 10491.0 5710.9 6553.0 6642.4 6771.1 6783.1 12163.1 12181.9 12393.4 12747.6 12941.0 12710.9 12762.6 12789.0 12975.5 12977.3 9027.6 9299.2 9302.1 9325.4 9353.9 10128.5 10164.5 10198.3 10218.1 10275.6 8584.0 8717.3 8725.7 8806.3 8845.4 8441.7 8459.7 8463.5 8592.2 8602.9 7814.4 7835.6 7875.1 7918.6 7931.6 5345.8 5664.9 5814.2 5906.3 6017.2 5893.5 6006.4 6023.4 6052.2 6085.9 09:50:47 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5392.57 Median:5940.26 stddev:1632.32 (27.5%) stddev-sans-first:1592.89 09:50:47 INFO - PID 1640 | Values: 376.7 402.0 404.2 404.8 414.6 5221.6 6382.4 6390.4 6404.0 6462.0 4854.6 5054.2 5118.6 5342.4 6254.6 4856.4 4865.1 4877.3 4899.6 4901.7 4860.2 4903.1 4906.5 4919.6 4952.2 4186.4 4519.4 4556.3 4798.2 4867.2 4318.9 4331.4 4848.8 4852.9 4876.0 3369.6 4265.6 4279.2 4286.5 4313.5 5308.6 5452.7 6049.9 6069.9 6171.6 4663.8 5560.4 5573.3 5578.6 5587.4 5739.9 5773.2 6256.8 6662.4 6708.2 3570.9 3883.7 4301.8 4371.1 4437.7 6770.0 7028.4 7040.0 7119.5 7131.3 4217.4 4762.1 4795.4 4903.0 4917.3 4163.3 5019.2 5025.5 5030.7 5035.5 5396.2 5555.6 5614.0 5635.7 5644.9 6698.7 6719.4 6751.3 6780.7 6820.1 1629.0 1660.9 1714.2 1731.4 1732.4 1170.5 1177.6 1187.2 1193.4 1208.1 5753.2 5924.6 6267.6 6307.2 6328.3 5619.6 6194.2 6298.2 6631.6 6961.6 5763.7 5969.6 5988.4 6206.8 6351.8 3163.7 3402.0 3627.2 3639.5 3667.1 6024.9 6524.7 6547.5 6806.2 7141.8 6824.9 7144.6 7161.9 7179.4 7192.3 5326.3 6372.9 6434.3 6799.4 7128.0 6858.3 6858.7 6923.9 6930.9 6935.5 5964.6 6123.2 6696.5 6806.4 6834.2 7026.0 7045.5 7087.0 7116.4 7144.7 4125.0 4269.0 4799.4 4855.3 4905.6 5955.9 6531.6 6678.3 6836.9 6845.2 5752.8 6099.5 6554.5 6598.7 6692.3 6260.0 6280.2 6597.0 6616.4 6682.3 6429.9 6433.2 6455.7 6469.7 6504.4 5970.8 6413.3 6432.8 6461.6 6613.5 5760.4 6465.7 6480.5 6496.5 6511.9 09:50:47 INFO - PID 1640 | 09:50:47 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:20312.58 Median:21935.26 stddev:30449.17 (138.8%) stddev-sans-first:30498.96 09:50:47 INFO - PID 1640 | Values: 694.4 724.0 726.8 727.8 729.4 14809.9 15529.5 15548.9 15563.9 15576.7 15043.0 15077.5 15117.8 15181.9 15186.8 909.4 938.7 944.0 956.0 985.7 979.5 990.6 992.7 993.5 994.8 1025.7 1036.4 1037.5 1037.9 1039.4 1033.0 1035.9 1036.0 1037.1 1037.8 760.7 765.4 765.6 766.7 767.1 16306.3 16584.0 16705.2 16736.5 16999.8 14733.0 14753.8 14785.7 14908.4 14924.9 11476.2 11661.4 11765.5 12081.0 12208.3 2991.9 3058.7 3156.7 3163.7 3163.7 110512.0 112193.0 114212.4 114466.0 116098.4 11103.9 11359.9 11565.5 11630.3 12019.4 24568.1 24878.4 24878.4 24942.9 25678.7 6252.2 7705.7 7780.3 7840.4 7873.1 3483.5 4046.0 4115.7 4130.6 4132.2 4112.0 4184.9 4186.7 4194.5 4227.5 639.5 759.6 791.4 808.4 808.5 535.6 551.7 568.9 588.2 598.1 8738.7 9392.6 10359.1 10422.9 10443.9 494.9 504.3 569.0 575.5 602.7 4149.7 4587.5 4719.0 4803.2 4896.7 105234.5 108950.0 110156.0 110854.8 112494.3 92164.5 93158.5 94670.1 95629.5 97548.0 81948.0 83317.8 83592.6 83659.5 83908.0 9419.5 9571.7 9584.1 9604.2 9618.0 40387.0 40416.8 40778.6 41418.6 42289.4 49036.0 49850.0 50049.0 50548.9 50562.7 10540.5 11498.4 11621.6 11827.5 12407.3 12406.8 12422.7 12555.2 12582.5 12659.7 11602.2 11856.2 11992.5 12248.4 12255.8 11766.5 11863.4 11874.9 11926.4 11972.5 11622.4 11671.5 11725.4 11832.2 11847.3 8659.3 8869.7 8996.0 9271.1 9293.3 9510.3 9928.6 9971.4 10001.8 10209.9 09:50:47 INFO - PID 1640 | -------- Summary: end -------- 09:50:47 INFO - PID 1640 | 09:50:48 INFO - TEST-INFO | 1640: exit 0 09:50:48 INFO - TEST-OK | dromaeo_css | took 1117330ms 09:50:48 INFO - TEST-START | kraken 09:50:48 INFO - operating with platform_type : mac_ 09:50:48 INFO - Initialising browser for kraken test... 09:50:48 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpBzXbg0/profile http://localhost:49236/getInfo.html) 09:50:51 INFO - PID 1652 | 2017-03-30 09:50:51.154 plugin-container[1653:10002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 09:50:51 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:50:51 INFO - PID 1652 | 2017-03-30 09:50:51.158 plugin-container[1653:10002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 09:50:51 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:50:51 INFO - PID 1652 | 2017-03-30 09:50:51.158 plugin-container[1653:10002] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:50:51 INFO - PID 1652 | __metrics Screen width/height:1600/1200 09:50:51 INFO - PID 1652 | colorDepth:24 09:50:51 INFO - PID 1652 | Browser inner width/height: 1024/689 09:50:51 INFO - PID 1652 | __metrics 09:50:51 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 09:50:51 ERROR - PID 1652 | 1490892651309 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpBzXbg0/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpBzXbg0/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpBzXbg0/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpBzXbg0/profile/extensions/talos-powers@TalosPowersService.js:55:9 09:50:52 INFO - TEST-INFO | 1652: exit 0 09:50:52 INFO - Browser initialized. 09:50:52 INFO - Running cycle 1/1 for kraken test... 09:50:52 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpBzXbg0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:54 INFO - PID 1654 | 2017-03-30 09:50:54.461 plugin-container[1655:10123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 09:50:54 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:50:54 INFO - PID 1654 | 2017-03-30 09:50:54.461 plugin-container[1655:10123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 09:50:54 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:50:54 INFO - PID 1654 | 2017-03-30 09:50:54.461 plugin-container[1655:10123] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:51:04 INFO - PID 1654 | RSS: Main: 230682624 09:51:04 INFO - PID 1654 | 09:51:07 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 09:51:08 INFO - PID 1654 | RSS: Main: 245112832 09:51:08 INFO - PID 1654 | 09:51:11 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 09:51:11 INFO - PID 1654 | RSS: Main: 253530112 09:51:11 INFO - PID 1654 | 09:51:15 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 09:51:16 INFO - PID 1654 | RSS: Main: 254746624 09:51:16 INFO - PID 1654 | 09:51:18 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 09:51:18 INFO - PID 1654 | RSS: Main: 257327104 09:51:18 INFO - PID 1654 | 09:51:20 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:51:21 INFO - PID 1654 | RSS: Main: 257581056 09:51:21 INFO - PID 1654 | 09:51:24 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 09:51:25 INFO - PID 1654 | RSS: Main: 263024640 09:51:25 INFO - PID 1654 | 09:51:29 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 09:51:29 INFO - PID 1654 | RSS: Main: 261214208 09:51:29 INFO - PID 1654 | 09:51:33 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 09:51:33 INFO - PID 1654 | RSS: Main: 261980160 09:51:33 INFO - PID 1654 | 09:51:35 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:51:36 INFO - PID 1654 | RSS: Main: 261939200 09:51:36 INFO - PID 1654 | 09:51:37 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:51:38 INFO - PID 1654 | RSS: Main: 261844992 09:51:38 INFO - PID 1654 | 09:51:40 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:51:41 INFO - PID 1654 | RSS: Main: 261931008 09:51:41 INFO - PID 1654 | 09:51:44 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:51:45 INFO - PID 1654 | RSS: Main: 262361088 09:51:45 INFO - PID 1654 | 09:51:48 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:49 INFO - PID 1654 | RSS: Main: 260472832 09:51:49 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 09:51:51 INFO - PID 1654 | RSS: Main: 263745536 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | __start_tp_report 09:51:51 INFO - PID 1654 | _x_x_mozilla_page_load 09:51:51 INFO - PID 1654 | _x_x_mozilla_page_load_details 09:51:51 INFO - PID 1654 | |i|pagename|runs| 09:51:51 INFO - PID 1654 | |0;ai-astar;111;123;125;124;122;121;124;185;183;123 09:51:51 INFO - PID 1654 | |1;audio-beat-detection;154;120;112;159;116;132;159;152;171;148 09:51:51 INFO - PID 1654 | |2;audio-dft;221;223;223;224;241;300;207;263;266;248 09:51:51 INFO - PID 1654 | |3;audio-fft;85;64;60;88;81;66;63;61;83;87 09:51:51 INFO - PID 1654 | |4;audio-oscillator;92;78;126;110;112;73;96;68;71;86 09:51:51 INFO - PID 1654 | |5;imaging-gaussian-blur;106;122;141;92;93;90;141;105;91;93 09:51:51 INFO - PID 1654 | |6;imaging-darkroom;103;164;161;129;149;139;101;101;112;109 09:51:51 INFO - PID 1654 | |7;imaging-desaturate;103;103;115;102;102;99;151;166;103;100 09:51:51 INFO - PID 1654 | |8;json-parse-financial;67;67;67;70;76;67;68;67;68;70 09:51:51 INFO - PID 1654 | |9;json-stringify-tinderbox;62;67;70;72;66;85;53;62;74;91 09:51:51 INFO - PID 1654 | |10;stanford-crypto-aes;88;88;87;88;96;109;89;90;88;90 09:51:51 INFO - PID 1654 | |11;stanford-crypto-ccm;169;137;168;164;165;179;171;158;195;149 09:51:51 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;210;199;194;200;225;255;249;200;196;189 09:51:51 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;72;73;73;101;99;101;95;72;72;72 09:51:51 INFO - PID 1654 | __end_tp_report 09:51:51 INFO - PID 1654 | __start_cc_report 09:51:51 INFO - PID 1654 | _x_x_mozilla_cycle_collect,1018 09:51:51 INFO - PID 1654 | __end_cc_report 09:51:51 INFO - PID 1654 | __startTimestamp1490892711577__endTimestamp 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | ------- Summary: start ------- 09:51:51 INFO - PID 1654 | Number of tests: 14 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:134.10 Median:124.00 stddev:26.60 (21.4%) stddev-sans-first:26.87 09:51:51 INFO - PID 1654 | Values: 111.0 123.0 125.0 124.0 122.0 121.0 124.0 185.0 183.0 123.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:153.00 stddev:20.70 (13.5%) stddev-sans-first:21.52 09:51:51 INFO - PID 1654 | Values: 154.0 120.0 112.0 159.0 116.0 132.0 159.0 152.0 171.0 148.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:241.60 Median:244.50 stddev:28.14 (11.5%) stddev-sans-first:28.85 09:51:51 INFO - PID 1654 | Values: 221.0 223.0 223.0 224.0 241.0 300.0 207.0 263.0 266.0 248.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:73.80 Median:82.00 stddev:11.86 (14.5%) stddev-sans-first:11.86 09:51:51 INFO - PID 1654 | Values: 85.0 64.0 60.0 88.0 81.0 66.0 63.0 61.0 83.0 87.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:75.50 stddev:19.72 (26.1%) stddev-sans-first:20.91 09:51:51 INFO - PID 1654 | Values: 92.0 78.0 126.0 110.0 112.0 73.0 96.0 68.0 71.0 86.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:107.40 Median:90.50 stddev:20.28 (22.4%) stddev-sans-first:21.51 09:51:51 INFO - PID 1654 | Values: 106.0 122.0 141.0 92.0 93.0 90.0 141.0 105.0 91.0 93.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:126.80 Median:134.00 stddev:25.02 (18.7%) stddev-sans-first:25.01 09:51:51 INFO - PID 1654 | Values: 103.0 164.0 161.0 129.0 149.0 139.0 101.0 101.0 112.0 109.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:114.40 Median:109.00 stddev:23.91 (21.9%) stddev-sans-first:25.00 09:51:51 INFO - PID 1654 | Values: 103.0 103.0 115.0 102.0 102.0 99.0 151.0 166.0 103.0 100.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:68.00 stddev:2.83 (4.2%) stddev-sans-first:2.93 09:51:51 INFO - PID 1654 | Values: 67.0 67.0 67.0 70.0 76.0 67.0 68.0 67.0 68.0 70.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.00 stddev:11.19 (15.8%) stddev-sans-first:11.47 09:51:51 INFO - PID 1654 | Values: 62.0 67.0 70.0 72.0 66.0 85.0 53.0 62.0 74.0 91.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:88.50 stddev:6.72 (7.6%) stddev-sans-first:7.02 09:51:51 INFO - PID 1654 | Values: 88.0 88.0 87.0 88.0 96.0 109.0 89.0 90.0 88.0 90.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:165.50 Median:168.50 stddev:15.79 (9.4%) stddev-sans-first:16.70 09:51:51 INFO - PID 1654 | Values: 169.0 137.0 168.0 164.0 165.0 179.0 171.0 158.0 195.0 149.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:211.70 Median:205.00 stddev:23.47 (11.4%) stddev-sans-first:24.88 09:51:51 INFO - PID 1654 | Values: 210.0 199.0 194.0 200.0 225.0 255.0 249.0 200.0 196.0 189.0 09:51:51 INFO - PID 1654 | 09:51:51 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.00 Median:72.50 stddev:13.87 (19.1%) stddev-sans-first:14.13 09:51:51 INFO - PID 1654 | Values: 72.0 73.0 73.0 101.0 99.0 101.0 95.0 72.0 72.0 72.0 09:51:51 INFO - PID 1654 | -------- Summary: end -------- 09:51:51 INFO - PID 1654 | 09:51:52 INFO - TEST-INFO | 1654: exit 0 09:51:52 INFO - TEST-OK | kraken | took 64142ms 09:51:52 INFO - SUITE-END | took 1181s 09:51:52 INFO - Completed test suite (00:19:41) 09:51:52 INFO - javascript benchmark 09:51:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7740.370630952985, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2753.5181343264935, 3017.2155239637696, 3671.284099600578, 3677.4483827425865, 3681.337359275329, 6113.050656958994, 6268.153799237096, 6271.937280627192, 6293.370662933704, 6293.622382657044, 6187.845303867405, 6194.070889366586, 6197.566170368076, 6233.625982441045, 6326.588771729846, 5825.44658257467, 5860.09168578871, 5884.735186916578, 5904.409559044104, 5907.940920590782, 5876.882462350772, 5929.169916211734, 5942.138389933457, 5953.761849526014, 5967.761289548434, 4704.270838020112, 5237.13587258102, 5812.9709351452975, 5888.911666325009, 5918.05311150216, 4780.972091000417, 5136.152534831748, 5618.4662457066515, 5751.05107657236, 5777.046787280094, 5654.858628534278, 5664.178694089375, 5664.575156863251, 5710.857228569277, 5711.571632127607, 5804.303483581955, 6155.661438620874, 6161.7535298588, 6277.811665650038, 6282.308946015934, 6089.269287685506, 6387.93612063878, 6617.834554136137, 6687.832804179886, 6797.932020679779, 6841.931580684229, 6867.759628412981, 6881.621510816903, 6888.724451021953, 6890.517663763539, 6109.677964500999, 6295.276043254993, 6321.083442900754, 6347.270063942613, 6362.363763623694, 6136.21274067861, 6866.519343645897, 6949.166100068024, 6959.652017399109, 6993.755218567428, 6362.236531616236, 6386.8722625547225, 6484.773032943919, 6553.082568440422, 6709.060731497594, 6792.3886850182735, 6861.073755043105, 6905.240423553405, 6913.688884000232, 6920.5847649141215, 6452.161219041588, 6793.45652347811, 6805.863882722318, 6807.591544507345, 6816.625085620339, 5656.349519805192, 5954.37479064692, 6441.710123044474, 6616.702248398834, 6662.067310576524, 5407.351117865803, 5414.377346605191, 5426.538744206787, 5432.91850622241, 5450.073487507133, 4682.297655351656, 4783.545563171493, 4792.0895029944195, 4823.155947709137, 4836.371271734652, 6267.1539342188225, 6329.3354197809485, 6397.936020639827, 6429.292777794439, 6443.935560644333, 6900.827479313058, 6922.203946546111, 6970.372666460043, 6972.372486476242, 6975.232724400312, 6404.807855764335, 6455.806325810233, 6464.385883341075, 6481.708323125471, 6485.221773387224, 5008.724520151354, 5865.002949498596, 6030.306514750772, 6065.059915713072, 6107.053406721965, 7050.471214658818, 7073.858522829618, 7081.079459670416, 7097.858042839011, 7113.288671133052, 6855.5886646801355, 6868.759593414206, 6875.243723190345, 6879.140107486564, 6936.34104760047, 6772.187337519529, 6884.931150688429, 6989.895151572832, 7027.648617568999, 7066.964665176642, 6752.3922846944015, 6787.117674702257, 6793.490488213305, 6799.93200067993, 6819.829504262433, 5669.829905102853, 6584.374484423972, 6823.215330236888, 6889.689963951734, 6890.345417185359, 6300.401461861115, 6406.615603063831, 6424.325445828121, 6516.869662606816, 6593.3736295051895, 6626.171728533934, 6647.833804154741, 6650.168728908887, 6653.833654158685, 6655.73377064912, 5393.595480338912, 6323.893318669123, 6356.841078972878, 6483.0, 6499.5125365598715, 6371.267304260069, 6426.903596445964, 6446.613203207822, 6564.671766411565, 6661.933380666132, 5778.653280803165, 6285.654289013965, 6291.874162516816, 6422.807315780534, 6457.806265812033], "value": 6170.611696140732, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.8070021094304, 740.1451323721102, 804.1717031457597, 1268.8033354830004, 1276.3299267884365, 38009.23981520371, 38944.610553894454, 38995.0251243719, 39039.633612823556, 39176.60823391769, 38615.806920965355, 38948.0, 38962.0, 39006.80496597513, 39013.21973560527, 8020.0776910655295, 8021.19788021199, 8184.549849758261, 8858.202761751441, 8868.467891926504, 8755.299576033934, 8808.867866982, 8875.866861997076, 8896.199342059213, 8901.688440904569, 8803.779905502382, 8809.295256379473, 8877.822443551126, 8883.0, 8929.151730585583, 8820.911790882074, 8844.425112367675, 8897.110288971116, 8897.288216942628, 8899.37704360695, 8604.483730976099, 8607.784805379915, 8660.0, 8673.089325620846, 8683.478991260545, 38528.844134676, 38800.417993730116, 38810.61189388098, 38833.223335533134, 38929.22141557153, 2993.5133895406548, 3007.65411977621, 3008.7743419243643, 3016.5173572228337, 3030.469667808147, 28045.43909121806, 28181.0, 28280.717192828015, 28295.858520707472, 29155.70844291551, 2794.762445192161, 2807.7192280771965, 2820.252633052243, 2828.1232817826335, 2860.985695071532, 277416.0, 281416.0, 311463.99999999773, 334403.0, 337185.0, 48656.27015594769, 48728.02543949172, 48731.26903096456, 48966.75516622394, 49038.75480622574, 52056.47943520592, 52107.47892521102, 52253.477465225624, 52303.47696523062, 52355.73822130865, 14329.498467553585, 14400.711985760227, 14406.42374305037, 14416.639584010483, 14513.346899389553, 11628.592999245155, 11758.0, 11771.23486973344, 11791.764164716658, 11798.351090690094, 12092.334921579397, 12720.72792720739, 13772.380242889094, 13815.723685526233, 14011.439542418393, 6058.57589968698, 6059.697015149225, 6064.545159113084, 6115.6330620162935, 6120.08198770188, 951.3198063384713, 976.5312649928075, 983.6311383231289, 985.192142443204, 1027.6865556005341, 19463.416097517096, 21749.0, 21775.128994840346, 24656.0, 24864.37839054038, 1070.6038765656756, 1071.6838532632892, 1118.6588090632436, 1120.9495572699248, 1132.224426268009, 11818.0, 12594.81107783384, 12640.557580484823, 12668.36658167088, 12702.42839072244, 399527.0, 399573.0, 400985.0, 401429.0, 402459.0, 56139.0, 56363.15455268174, 56378.43621563814, 56529.717351413805, 56631.150532741216, 1433.8136042314432, 1471.9264036798331, 1504.601280660626, 1510.9848901510845, 1517.051842598376, 24031.399215019763, 25730.970761169345, 29192.36651904824, 29430.264243394085, 29548.409031819672, 51239.0, 51320.48679513157, 51459.48540514547, 51719.74140129426, 51734.482655172964, 390492.0, 391628.0, 392256.0, 392510.0, 392983.2806687815, 33361.49957750587, 33461.832690837364, 33729.662703372655, 34769.478457822646, 35022.47466288059, 43283.0, 44477.777611111735, 44485.777571113234, 44591.77704111459, 44703.0, 28751.568726469537, 29238.0, 29526.261843454085, 30464.543031854984, 30804.92182773592, 25297.367565811, 29131.126066218047, 30287.182769033927, 30588.0, 30829.845850770602, 35201.94394168179, 35678.0, 35789.0, 36227.63772362243, 36692.44961325636, 27915.0, 28799.712002879704, 30364.84817575898, 31028.844855775576, 31156.0, 20774.792252077892, 22900.54198915979, 24782.256532304058, 24801.0, 24977.37376550267], "value": 17952.707931635403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.3929143804326, 414.4549916859328, 423.8241129931078, 439.3980247061526, 440.8633323669663, 16412.01527908326, 20329.49176270594, 21487.57024859504, 22453.101875924942, 22669.433264168405, 18804.717929231072, 21874.01566929488, 22446.214382496615, 23152.536949261004, 23269.30192094236, 5974.522038236931, 6252.187215661957, 6262.2172228471445, 6281.214848143982, 6284.7486100555925, 5855.882882342372, 6116.969415152918, 6140.662263575501, 6170.598911070766, 6192.876142477147, 5867.178594996704, 6122.0, 6127.724252408652, 6132.846678833021, 6139.140520327158, 5322.2814919985885, 6023.488003519706, 6131.846703832418, 6160.0, 6162.0, 5379.139337705988, 5423.430539793344, 5496.065668836308, 5499.50504454597, 5507.586930980193, 26943.0, 27158.864205678845, 27455.45089098227, 28381.716182838114, 28600.856995715098, 1936.8547358947994, 1954.3159894036974, 1955.6479833629942, 1975.9110840012233, 2025.8075482829108, 16296.755548666779, 16914.56312619251, 17097.48707538776, 17132.571685707833, 17193.398231061852, 1609.871210303173, 1729.3860679458862, 1735.2885317019961, 1736.9913150434168, 1801.2434777393526, 149025.25487372602, 157289.0, 158541.20729396393, 170259.0, 177083.0, 12683.0, 12865.292408917412, 12871.356432178542, 13005.089643725038, 13162.670933226555, 16242.837571624368, 16302.429414970657, 16506.669866602842, 16539.586510337336, 16555.08947007926, 8892.688755893641, 9191.908080919238, 9206.26349892007, 9338.673146439842, 9349.392289501297, 8919.840420745391, 8942.86585701215, 8943.821123577622, 9092.408993415407, 9108.49903255314, 7875.7243496478495, 7965.402594805297, 7990.923494454561, 8048.275655191061, 8058.1538938411795, 2139.668351405535, 2144.3517256188593, 2145.9592097832538, 2154.418307057117, 2313.9702832239827, 890.5324704530044, 901.4546199549178, 905.8641203819479, 936.8220038192721, 941.4069136443997, 19616.0, 20347.796522034885, 20672.79327206739, 20928.895355523124, 21104.261350852645, 924.523870206844, 942.0358552418148, 968.6222373274429, 974.055166488505, 987.4865070163474, 9713.562889669982, 10272.640457583948, 10739.570417183226, 10800.405977671146, 10866.728331791768, 649651.0, 669390.0, 670277.0, 692530.0, 719015.0, 133248.0, 159193.0, 159368.0, 166267.0, 167622.54597801095, 1023.2070145637191, 1139.2651739628059, 1155.185594156118, 1172.2439026827649, 1194.4923407551755, 16892.324307027582, 18307.542311441786, 18317.725234121233, 18333.3583324583, 18700.625987480445, 63398.92808575211, 63415.0487742675, 65358.0, 65970.34029659642, 66074.33925660874, 86081.70877436719, 86606.7008994853, 86899.0, 86904.5654771722, 89400.0, 8604.440711353742, 8789.38474306818, 8827.24968377695, 8902.59938302765, 9084.954575227082, 9751.756206094904, 14071.155730656194, 14412.783808243095, 14484.986050976331, 14556.417743290573, 11451.541938322374, 11564.94217528907, 11613.245139066272, 11762.294262344287, 12027.75944481123, 10767.6231331903, 10949.069329107115, 10971.780564388508, 11258.605948791754, 11347.886521134684, 11469.885301146882, 11487.310761354345, 11650.70873228176, 12191.085668574715, 12269.263844169378, 8039.477433966774, 8716.607752651273, 8741.300695944436, 8974.339747146538, 9123.680671176742, 11621.966155922848, 11730.530778768754, 11877.58428455, 11929.164958452826, 12054.698632534257], "value": 10461.288194402712, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.2029599153168, 1337.6321511584313, 1345.6703107738592, 1355.3020194599783, 1369.0348304445367, 10778.514966826491, 10803.351798892072, 11045.944770276137, 11255.831162532559, 11271.0, 10914.399708016055, 11072.612458563952, 11083.889161108387, 11100.0, 11110.555627768399, 2418.927432177033, 2469.493753780471, 2476.3437689012426, 2480.7271200167966, 2488.850668959859, 2396.089485995319, 2420.2134306350417, 2459.040974020125, 2462.926112216627, 2468.4075821802726, 2407.4442675159253, 2412.915547955813, 2437.2079074300837, 2451.570975079363, 2455.238875948453, 2366.6331718583647, 2414.106780491221, 2427.3082171581104, 2428.5871401861637, 2450.301664025762, 2399.6160614301803, 2405.4226985523524, 2424.9936276445255, 2433.2335314375914, 2510.184190138212, 11001.889981100167, 11082.722931926686, 11097.44512774358, 11101.666949991513, 11132.944335278353, 5076.847694569169, 6464.644444555547, 6475.7733479328, 6478.2873883872735, 6486.383793539606, 4031.23406552754, 4063.471748672683, 4080.489938757655, 4086.4074709167003, 4113.0, 903.0, 1025.8409946458312, 1163.1857699610307, 1170.8770579089244, 1216.5681183179931, 13840.446382144703, 14467.131972081708, 15142.091474511564, 15304.617384565363, 15338.156401397919, 4607.378003969421, 5322.0952438085615, 5464.9453505464435, 5497.945020549823, 5521.0, 11587.130965177477, 11642.0, 11672.591459299054, 11683.415829208505, 11727.237729547553, 6240.375962403723, 6335.081413195153, 6341.270753863365, 6350.68246587678, 6373.107764912823, 5770.394108618535, 5891.41085891416, 6009.669468179292, 6020.729067191987, 6066.636001839904, 4206.137741762931, 4305.31115021595, 4340.82636694535, 4352.0, 4407.338899165086, 1232.3653318540958, 1263.317808383481, 1272.049098717562, 1276.9553065642845, 1280.8014757712383, 719.6186021408662, 730.0472882887776, 751.0124186694479, 761.5392687424139, 786.598834594361, 5824.504917082011, 5938.198343223609, 6024.728887200086, 6120.234970628671, 6134.723937422827, 543.5217009032026, 562.8395907166438, 576.9528306124371, 580.0574067140896, 580.4166812353558, 3755.3052685253315, 4105.58944105593, 4359.912801744011, 4466.821327146878, 4580.656450766206, 10913.345199288067, 14215.786763198561, 14235.64410889736, 14239.93200509966, 14273.0, 13729.519466818614, 13931.303434828218, 13968.511102111377, 14126.64683382903, 14213.218272994878, 10124.0, 10441.425721585234, 10453.163746900382, 10455.790884182274, 10491.0, 5710.942890571041, 6553.0, 6642.43539299165, 6771.085903403175, 6783.05037294789, 12163.087768417581, 12181.878181218075, 12393.442295096922, 12747.553835615709, 12941.0, 12710.936445317715, 12762.553310634083, 12788.976881849243, 12975.545855894998, 12977.286249256382, 9027.638894444148, 9299.163075323253, 9302.069793020644, 9325.393849399767, 9353.85969210476, 10128.493575321058, 10164.491775411348, 10198.286119971532, 10218.131458826083, 10275.588976441157, 8584.0, 8717.346199034972, 8725.65097396097, 8806.251468625238, 8845.4250473719, 8441.746747597583, 8459.661613535389, 8463.53450560225, 8592.183742544448, 8602.870956935525, 7814.374850012099, 7835.608219589112, 7875.094364147967, 7918.564478953713, 7931.643076061707, 5345.78616855337, 5664.886702266014, 5814.186013958134, 5906.26171728534, 6017.247844019497, 5893.469587737124, 6006.369331220334, 6023.427774361428, 6052.152698622109, 6085.878282434238], "value": 5123.363986787161, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.65536034528407, 401.9758814471132, 404.23599397139407, 404.77130421311955, 414.55228353378357, 5221.5822734181265, 6382.425581697652, 6390.3609639036185, 6404.0, 6462.0, 4854.635902307324, 5054.21659642756, 5118.5905127589795, 5342.439043900383, 6254.593451425654, 4856.3686720726255, 4865.14859899518, 4877.292792545097, 4899.559039686427, 4901.705897646153, 4860.246661767432, 4903.117438861003, 4906.484819093997, 4919.557239848413, 4952.182889823175, 4186.434831297766, 4519.367288579601, 4556.2913907284765, 4798.232282834747, 4867.172580661296, 4318.870433886973, 4331.3719510670935, 4848.757562121915, 4852.8544143675745, 4876.0, 3369.6461871503384, 4265.637420819234, 4279.2083464559155, 4286.464191976003, 4313.525512193656, 5308.628396012281, 5452.700101494416, 6049.879002419971, 6069.878602427927, 6171.59884607499, 4663.836765713218, 5560.4161563035705, 5573.303337082864, 5578.553715702734, 5587.38538760736, 5739.885202295931, 5773.220615216934, 6256.78101266458, 6662.367075127855, 6708.19501659804, 3570.892873213808, 3883.7475564088527, 4301.784910754481, 4371.125774845012, 4437.689361744679, 6770.0, 7028.367446929801, 7040.0, 7119.50163488556, 7131.251218622022, 4217.388478670576, 4762.14281429346, 4795.448523419851, 4902.950970490249, 4917.311576379309, 4163.292240319153, 5019.171836646985, 5025.522575355409, 5030.673006254655, 5035.546800787946, 5396.163594642837, 5555.611107222537, 5613.978967571671, 5635.716855190085, 5644.943550564524, 6698.698558564876, 6719.42884854783, 6751.291114433012, 6780.69486873082, 6820.067371030505, 1629.0, 1660.850523452895, 1714.1943286655253, 1731.4199743086208, 1732.4023211991844, 1170.4849866058908, 1177.6113882418952, 1187.2342339191173, 1193.3555879824798, 1208.130146294684, 5753.194552762617, 5924.6149000314845, 6267.561270711098, 6307.180066591313, 6328.272248691367, 5619.550435965195, 6194.194754681863, 6298.212723409574, 6631.568948018364, 6961.61711105894, 5763.7406316715005, 5969.582129250911, 5988.401159884064, 6206.782762603287, 6351.777687780906, 3163.7469002479747, 3402.030421329909, 3627.165751877083, 3639.5086663300117, 3667.0832291927018, 6024.909626355608, 6524.706388212542, 6547.526806468506, 6806.217285012188, 7141.7864958579485, 6824.863502729918, 7144.571325720473, 7161.856762864818, 7179.353858152791, 7192.31672991065, 5326.334208223972, 6372.9166041773005, 6434.324395938546, 6799.402140496979, 7127.964360178166, 6858.279880612464, 6858.725650973905, 6923.89614155778, 6930.896036559355, 6935.479839012195, 5964.582479226413, 6123.173371594778, 6696.531242812956, 6806.353396427331, 6834.2482326943, 7025.962470883386, 7045.506814522934, 7087.0, 7116.3988102676485, 7144.749933752294, 4124.979375103106, 4268.978655106829, 4799.424069111729, 4855.344528488608, 4905.632077594266, 5955.898158840629, 6531.575447595891, 6678.265390807104, 6836.931630683828, 6845.178578570603, 5752.827415177551, 6099.451049405575, 6554.541182117206, 6598.670066496752, 6692.330766923269, 6259.965511208784, 6280.152179455898, 6596.967015165085, 6616.386527321777, 6682.264950855305, 6429.903551446639, 6433.233709504958, 6455.741770329134, 6469.738401011758, 6504.414602685781, 5970.835687040985, 6413.294537600954, 6432.774852880127, 6461.64460954652, 6613.511959809004, 5760.423957604206, 6465.676716164267, 6480.513961452815, 6496.480281577557, 6511.934880651323], "value": 4896.498589467185, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.4203670224625, 723.9565626062436, 726.7753834788379, 727.7707522130529, 729.3982464466815, 14809.925950370247, 15529.456469023586, 15548.922255388707, 15563.922180389096, 15576.688466230682, 15043.0, 15077.472288469906, 15117.848821511781, 15181.924090379533, 15186.772198417006, 909.3816204980627, 938.7183844846519, 943.9622415103388, 955.9761005974872, 985.7387792235028, 979.5445118020119, 990.6334656177224, 992.6575331510618, 993.4536005197149, 994.796066806303, 1025.7333093395735, 1036.383351905615, 1037.5382954585214, 1037.891021442749, 1039.428314427066, 1032.9741756456149, 1035.927485076045, 1035.9533820978074, 1037.0770014686934, 1037.792441511697, 760.6691089376139, 765.4488768086968, 765.6057130577757, 766.6550052476352, 767.0680123649737, 16306.347746090261, 16584.004959702335, 16705.164741762863, 16736.497905062868, 16999.830001700073, 14733.0, 14753.77869331961, 14785.67525952425, 14908.403663853433, 14924.850751492564, 11476.196666233283, 11661.416929153593, 11765.529378824836, 12081.0, 12208.32854193019, 2991.850407479639, 3058.663547009827, 3156.71589556941, 3163.6519982801874, 3163.6994485523837, 110512.0, 112193.0, 114212.42893785561, 114466.0, 116098.41950790277, 11103.944480277547, 11359.943200284111, 11565.537378504934, 11630.302181868945, 12019.399030048462, 24568.140115095885, 24878.37804054913, 24878.37804054913, 24942.87757050901, 25678.74321256801, 6252.218472690914, 7705.691772329156, 7780.260875216845, 7840.41196910234, 7873.133955264916, 3483.512308276843, 4045.9797701011894, 4115.7324773890205, 4130.607592278729, 4132.173565286955, 4112.033672087057, 4184.916301673949, 4186.706930514834, 4194.517630472535, 4227.492700875914, 639.5171645407687, 759.5518644000066, 791.4262159934117, 808.4462143431768, 808.4946908182386, 535.635767677975, 551.7241379310345, 568.8947544704243, 588.2117961931033, 598.0670154558815, 8738.737837864874, 9392.62429502826, 10359.119474844572, 10422.947885260677, 10443.895561044292, 494.868859752166, 504.26532727417566, 568.9956829682949, 575.516566084491, 602.668532307238, 4149.688773342012, 4587.495375508758, 4719.003432820115, 4803.231482962779, 4896.657233993657, 105234.47382763037, 108950.0, 110156.0, 110854.78290434147, 112494.31258531129, 92164.53917730368, 93158.53420732853, 94670.0532994675, 95629.52185239168, 97548.0, 81948.0, 83317.75023374654, 83592.58203708942, 83659.5102599601, 83908.0, 9419.529023548657, 9571.712848614552, 9584.050046844572, 9604.183644390025, 9617.951910240685, 40387.0, 40416.79791601023, 40778.59221407748, 41418.58581414147, 42289.365659515744, 49036.01927961492, 49850.0, 50049.0, 50548.935299957135, 50562.747186263834, 10540.52567634443, 11498.425078746197, 11621.593244236412, 11827.467763950472, 12407.31759753186, 12406.751864962831, 12422.689432763891, 12555.246685198552, 12582.496700131893, 12659.683507912008, 11602.18784685064, 11856.22934509254, 11992.460339284578, 12248.448819803309, 12255.754884902073, 11766.461110005695, 11863.406829658656, 11874.940625296818, 11926.403679815801, 11972.461239244081, 11622.360770157722, 11671.533138674698, 11725.355105469282, 11832.17174797756, 11847.289162650268, 8659.263962563, 8869.696278594556, 8995.955020224857, 9271.119243671841, 9293.25653947669, 9510.334276600848, 9928.553215105194, 9971.40171589707, 10001.799964000533, 10209.9489502552], "value": 7396.984974983705, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1722.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 123.0, 125.0, 124.0, 122.0, 121.0, 124.0, 185.0, 183.0, 123.0], "value": 134.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 112.0, 159.0, 116.0, 132.0, 159.0, 152.0, 171.0, 148.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 223.0, 223.0, 224.0, 241.0, 300.0, 207.0, 263.0, 266.0, 248.0], "value": 241.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 64.0, 60.0, 88.0, 81.0, 66.0, 63.0, 61.0, 83.0, 87.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 78.0, 126.0, 110.0, 112.0, 73.0, 96.0, 68.0, 71.0, 86.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 122.0, 141.0, 92.0, 93.0, 90.0, 141.0, 105.0, 91.0, 93.0], "value": 107.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 164.0, 161.0, 129.0, 149.0, 139.0, 101.0, 101.0, 112.0, 109.0], "value": 126.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 103.0, 115.0, 102.0, 102.0, 99.0, 151.0, 166.0, 103.0, 100.0], "value": 114.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 70.0, 76.0, 67.0, 68.0, 67.0, 68.0, 70.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 70.0, 72.0, 66.0, 85.0, 53.0, 62.0, 74.0, 91.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 88.0, 96.0, 109.0, 89.0, 90.0, 88.0, 90.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 137.0, 168.0, 164.0, 165.0, 179.0, 171.0, 158.0, 195.0, 149.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 199.0, 194.0, 200.0, 225.0, 255.0, 249.0, 200.0, 196.0, 189.0], "value": 211.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 73.0, 101.0, 99.0, 101.0, 95.0, 72.0, 72.0, 72.0], "value": 83.0, "unit": "score"}]}]} 09:51:52 INFO - Return code: 0 09:51:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:51:52 ERROR - # TBPL SUCCESS # 09:51:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:51:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:51:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:51:52 INFO - 2017-03-30 09:51:52.902 screenresolution[1659:10510] starting screenresolution argv=screenresolution get 09:51:52 INFO - 2017-03-30 09:51:52.905 screenresolution[1659:10510] Display 0: 1600x1200x32@60 09:51:52 INFO - 2017-03-30 09:51:52.911 screenresolution[1660:10513] starting screenresolution argv=screenresolution list 09:51:52 INFO - Available Modes on Display 0 09:51:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:51:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:51:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:51:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:51:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:51:53 INFO - Intel Iris: 09:51:53 INFO - Chipset Model: Intel Iris 09:51:53 INFO - Type: GPU 09:51:53 INFO - Bus: Built-In 09:51:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:51:53 INFO - Vendor: Intel (0x8086) 09:51:53 INFO - Device ID: 0x0a2e 09:51:53 INFO - Revision ID: 0x0009 09:51:53 INFO - Displays: 09:51:53 INFO - Display: 09:51:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:51:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:51:53 INFO - Display Serial Number: 1600x1200 60 09:51:53 INFO - Main Display: Yes 09:51:53 INFO - Mirror: Off 09:51:53 INFO - Online: Yes 09:51:53 INFO - Rotation: Supported 09:51:53 INFO - Return code: 0 09:51:53 INFO - Running post-action listener: _resource_record_post_action 09:51:53 INFO - [mozharness: 2017-03-30 16:51:53.148092Z] Finished run-tests step (success) 09:51:53 INFO - Running post-run listener: _resource_record_post_run 09:51:53 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 37542400; Write bytes: 518069248; Read time: 239; Write time: 1621 09:51:53 INFO - TinderboxPrint: CPU usage
54.3% 09:51:53 INFO - TinderboxPrint: I/O read bytes / time
37,542,400 / 239 09:51:53 INFO - TinderboxPrint: I/O write bytes / time
518,069,248 / 1,621 09:51:53 INFO - TinderboxPrint: CPU idle
2,200.1 (45.7%) 09:51:53 INFO - TinderboxPrint: CPU system
968.7 (20.1%) 09:51:53 INFO - TinderboxPrint: CPU user
1,644.9 (34.2%) 09:51:53 INFO - TinderboxPrint: Swap in / out
249,552,896 / 0 09:51:53 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203131392; Write bytes: 205485568; Read time: 15709; Write time: 452 09:51:53 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 36575744; Write bytes: 302618112; Read time: 226; Write time: 1133 09:51:53 INFO - Running post-run listener: _upload_blobber_files 09:51:53 INFO - Blob upload gear active. 09:51:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:51:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:51:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:51:54 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:51:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:54 INFO - (blobuploader) - INFO - Done attempting. 09:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:51:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:55 INFO - (blobuploader) - INFO - Done attempting. 09:51:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:51:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:51:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:56 INFO - (blobuploader) - INFO - Done attempting. 09:51:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:51:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:51:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:59 INFO - (blobuploader) - INFO - Done attempting. 09:51:59 INFO - (blobuploader) - INFO - Iteration through files over. 09:51:59 INFO - Return code: 0 09:51:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:51:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:51:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c2158e5239a566fbb109b81217bb56e3f8963da30de6d95216d792511ea75582410680a107f996743daf75e87bed3972797ef91f4019095d2b3708f8398717", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eab594e77f4abd627268fdab0e08f0fa792ea52bf5fefda59bb3c33353d49b54e32f9f49defc29cb7526160010ea7001959af32554f7a687f9df1e8706bcd4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2486d340c294e5a7b973f9119e2025e106d3b25a12eda048d49269553a74b463e3d1c568838bfba46d6f6d8dd1b809a4e7ce7d623bddaba205f92d98a9e3e7c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256a87e4c1baade202c312385d532e1f3fb0098c3dbecc55e9280aa6048e6c67e855b173e10096df194c785d1c2507afa677770fa745bfbd85611c1022802a19"} 09:51:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:51:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:51:59 INFO - Contents: 09:51:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c2158e5239a566fbb109b81217bb56e3f8963da30de6d95216d792511ea75582410680a107f996743daf75e87bed3972797ef91f4019095d2b3708f8398717", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eab594e77f4abd627268fdab0e08f0fa792ea52bf5fefda59bb3c33353d49b54e32f9f49defc29cb7526160010ea7001959af32554f7a687f9df1e8706bcd4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2486d340c294e5a7b973f9119e2025e106d3b25a12eda048d49269553a74b463e3d1c568838bfba46d6f6d8dd1b809a4e7ce7d623bddaba205f92d98a9e3e7c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256a87e4c1baade202c312385d532e1f3fb0098c3dbecc55e9280aa6048e6c67e855b173e10096df194c785d1c2507afa677770fa745bfbd85611c1022802a19"} 09:51:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:51:59 INFO - Copying logs to upload dir... 09:51:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:51:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.890639 ========= master_lag: 0.60 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-30 09:51:59.868226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 09:51:59.872609) ========= 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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c2158e5239a566fbb109b81217bb56e3f8963da30de6d95216d792511ea75582410680a107f996743daf75e87bed3972797ef91f4019095d2b3708f8398717", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eab594e77f4abd627268fdab0e08f0fa792ea52bf5fefda59bb3c33353d49b54e32f9f49defc29cb7526160010ea7001959af32554f7a687f9df1e8706bcd4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2486d340c294e5a7b973f9119e2025e106d3b25a12eda048d49269553a74b463e3d1c568838bfba46d6f6d8dd1b809a4e7ce7d623bddaba205f92d98a9e3e7c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256a87e4c1baade202c312385d532e1f3fb0098c3dbecc55e9280aa6048e6c67e855b173e10096df194c785d1c2507afa677770fa745bfbd85611c1022802a19"} build_url:https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/YybSgs36S0m8eMhFkLXQfQ/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/b0c2158e5239a566fbb109b81217bb56e3f8963da30de6d95216d792511ea75582410680a107f996743daf75e87bed3972797ef91f4019095d2b3708f8398717", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eab594e77f4abd627268fdab0e08f0fa792ea52bf5fefda59bb3c33353d49b54e32f9f49defc29cb7526160010ea7001959af32554f7a687f9df1e8706bcd4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2486d340c294e5a7b973f9119e2025e106d3b25a12eda048d49269553a74b463e3d1c568838bfba46d6f6d8dd1b809a4e7ce7d623bddaba205f92d98a9e3e7c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256a87e4c1baade202c312385d532e1f3fb0098c3dbecc55e9280aa6048e6c67e855b173e10096df194c785d1c2507afa677770fa745bfbd85611c1022802a19"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 09:51:59.917867) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:51:59.918275) ========= 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.D3yl1iGMqI/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.ZYx6QBCWCx/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.004356 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 09:51:59.966725) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 09:51:59.967097) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 09:51:59.967454) ========= ========= Total master_lag: 0.75 =========