builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0068 starttime: 1490748734.76 results: success (0) buildid: 20170328152823 builduid: 8e1dd61cf8834aa79307c0c038355146 revision: bbab5665154904a6f4cd39b83bd9140dc5888d81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.764537) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.764987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.765272) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.791841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.792292) ========= 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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-28 17:52:14-- 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% 29.7M=0s 2017-03-28 17:52:14 (29.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123392 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.939708) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.940018) ========= 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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.031281 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.993797) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:14.994228) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev bbab5665154904a6f4cd39b83bd9140dc5888d81 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev bbab5665154904a6f4cd39b83bd9140dc5888d81 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.M0mYwFjlGY/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.3HIzsP17Ic/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-28 17:52:15,023 truncating revision to first 12 chars 2017-03-28 17:52:15,023 Setting DEBUG logging. 2017-03-28 17:52:15,023 attempt 1/10 2017-03-28 17:52:15,023 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/bbab56651549?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 17:52:15,704 unpacking tar archive at: pine-bbab56651549/testing/mozharness/ program finished with exit code 0 elapsedTime=0.974701 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:15.992491) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:15.992947) ========= script_repo_revision: bbab5665154904a6f4cd39b83bd9140dc5888d81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:15.993431) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:15.993759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 17:52:16.013999) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-28 17:52:16.014444) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.M0mYwFjlGY/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.3HIzsP17Ic/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:52:16 INFO - MultiFileLogger online at 20170328 17:52:16 in /builds/slave/test 17:52:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 17:52:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:52:16 INFO - {'append_to_log': False, 17:52:16 INFO - 'base_work_dir': '/builds/slave/test', 17:52:16 INFO - 'blob_upload_branch': 'Pine', 17:52:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:52:16 INFO - 'branch': 'Pine', 17:52:16 INFO - 'buildbot_json_path': 'buildprops.json', 17:52:16 INFO - 'config_files': ('talos/mac_config.py',), 17:52:16 INFO - 'default_actions': ('clobber', 17:52:16 INFO - 'read-buildbot-config', 17:52:16 INFO - 'download-and-extract', 17:52:16 INFO - 'populate-webroot', 17:52:16 INFO - 'create-virtualenv', 17:52:16 INFO - 'install', 17:52:16 INFO - 'run-tests'), 17:52:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:52:16 INFO - 'download_minidump_stackwalk': True, 17:52:16 INFO - 'download_symbols': 'ondemand', 17:52:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:52:16 INFO - 'tooltool.py': '/tools/tooltool.py', 17:52:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:52:16 INFO - '/tools/misc-python/virtualenv.py')}, 17:52:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:52:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:52:16 INFO - 'gecko_profile': False, 17:52:16 INFO - 'gecko_profile_interval': 0, 17:52:16 INFO - 'installer_path': 'installer.exe', 17:52:16 INFO - 'log_level': 'info', 17:52:16 INFO - 'log_name': 'talos', 17:52:16 INFO - 'log_to_console': True, 17:52:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:52:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:52:16 INFO - 'opt_config_files': (), 17:52:16 INFO - 'pip_index': False, 17:52:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:52:16 INFO - 'cmd': ('bash', 17:52:16 INFO - '-c', 17:52:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:52:16 INFO - 'enabled': True, 17:52:16 INFO - 'halt_on_failure': False, 17:52:16 INFO - 'name': 'check_screen_resolution'},), 17:52:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:52:16 INFO - 'cmd': ('bash', 17:52:16 INFO - '-c', 17:52:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:52:16 INFO - 'enabled': True, 17:52:16 INFO - 'halt_on_failure': False, 17:52:16 INFO - 'name': 'check_screen_resolution'},), 17:52:16 INFO - 'run_cmd_checks_enabled': True, 17:52:16 INFO - 'suite': 'dromaeojs-e10s', 17:52:16 INFO - 'system_bits': '32', 17:52:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:52:16 INFO - 'title': 't-yosemite-r7-0068', 17:52:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:52:16 INFO - 'use_talos_json': True, 17:52:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:52:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:52:16 INFO - 'work_dir': 'build'} 17:52:16 INFO - [mozharness: 2017-03-29 00:52:16.157490Z] Running clobber step. 17:52:16 INFO - Running pre-action listener: _resource_record_pre_action 17:52:16 INFO - Running main action method: clobber 17:52:16 INFO - rmtree: /builds/slave/test/build 17:52:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:52:18 INFO - Running post-action listener: _resource_record_post_action 17:52:18 INFO - [mozharness: 2017-03-29 00:52:18.696016Z] Finished clobber step (success) 17:52:18 INFO - [mozharness: 2017-03-29 00:52:18.696165Z] Running read-buildbot-config step. 17:52:18 INFO - Running pre-action listener: _resource_record_pre_action 17:52:18 INFO - Running main action method: read_buildbot_config 17:52:18 INFO - Using buildbot properties: 17:52:18 INFO - { 17:52:18 INFO - "project": "", 17:52:18 INFO - "product": "firefox", 17:52:18 INFO - "script_repo_revision": "default", 17:52:18 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 17:52:18 INFO - "repository": "", 17:52:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 17:52:18 INFO - "buildid": "20170328152823", 17:52:18 INFO - "pgo_build": "False", 17:52:18 INFO - "basedir": "/builds/slave/test", 17:52:18 INFO - "buildnumber": 0, 17:52:18 INFO - "slavename": "t-yosemite-r7-0068", 17:52:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:52:18 INFO - "platform": "yosemite_r7", 17:52:18 INFO - "scheduler": "tests-pine-macosx64-talos", 17:52:18 INFO - "branch": "pine", 17:52:18 INFO - "repo_path": "projects/pine", 17:52:18 INFO - "revision": "bbab5665154904a6f4cd39b83bd9140dc5888d81", 17:52:18 INFO - "stage_platform": "macosx64", 17:52:18 INFO - "builduid": "8e1dd61cf8834aa79307c0c038355146", 17:52:18 INFO - "slavebuilddir": "test" 17:52:18 INFO - } 17:52:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 17:52:18 INFO - Running post-action listener: _resource_record_post_action 17:52:18 INFO - [mozharness: 2017-03-29 00:52:18.699495Z] Finished read-buildbot-config step (success) 17:52:18 INFO - [mozharness: 2017-03-29 00:52:18.699627Z] Running download-and-extract step. 17:52:18 INFO - Running pre-action listener: _resource_record_pre_action 17:52:18 INFO - Running main action method: download_and_extract 17:52:18 INFO - mkdir: /builds/slave/test/build/tests 17:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:18 INFO - trying https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:52:18 INFO - Downloading https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/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 17:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/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 17:52:19 INFO - Downloaded 1325 bytes. 17:52:19 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:52:19 INFO - Using the following test package requirements: 17:52:19 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:52:19 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:52:19 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:52:19 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:52:19 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'jsshell-mac.zip'], 17:52:19 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:52:19 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:52:19 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:52:19 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:52:19 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:52:19 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:52:19 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:52:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:52:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:52:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:52:19 INFO - Fetch https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:52:21 INFO - Expected file size: 52507122 17:52:21 INFO - Obtained file size: 52507122 17:52:37 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 17:52:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:52:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:52:37 INFO - Fetch https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:52:39 INFO - Expected file size: 52507122 17:52:39 INFO - Obtained file size: 52507122 17:52:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:52:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:52:55 INFO - Fetch https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:52:56 INFO - Expected file size: 14404171 17:52:56 INFO - Obtained file size: 14404171 17:52:56 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')]} 17:52:56 INFO - trying https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:52:56 INFO - Downloading https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:52:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/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 17:52:58 INFO - Downloaded 75643280 bytes. 17:52:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:52:58 INFO - mkdir: /builds/slave/test/properties 17:52:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:52:58 INFO - Writing to file /builds/slave/test/properties/build_url 17:52:58 INFO - Contents: 17:52:58 INFO - build_url:https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:52:59 INFO - Running post-action listener: _resource_record_post_action 17:52:59 INFO - Running post-action listener: set_extra_try_arguments 17:52:59 INFO - [mozharness: 2017-03-29 00:52:59.546244Z] Finished download-and-extract step (success) 17:52:59 INFO - [mozharness: 2017-03-29 00:52:59.546392Z] Running populate-webroot step. 17:52:59 INFO - Running pre-action listener: _resource_record_pre_action 17:52:59 INFO - Running main action method: populate_webroot 17:52:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:52:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:52:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:52:59 INFO - '--tppagecycles', 17:52:59 INFO - '7'], 17:52:59 INFO - 'tests': ['tsvgm']}}, 17:52:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:52:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:52:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:52:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:52:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:52:59 INFO - 'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:52:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:52:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:52:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:52:59 INFO - 'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['damp', 'tps']}, 17:52:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:52:59 INFO - 'tests': ['damp', 'tps']}, 17:52:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['dromaeo_dom']}, 17:52:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:52:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['basic_compositor_video']}, 17:52:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:52:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['a11yr', 17:52:59 INFO - 'ts_paint', 17:52:59 INFO - 'tpaint', 17:52:59 INFO - 'sessionrestore', 17:52:59 INFO - 'sessionrestore_no_auto_restore', 17:52:59 INFO - 'tabpaint']}, 17:52:59 INFO - 'other-e10s': {'tests': ['a11yr', 17:52:59 INFO - 'ts_paint', 17:52:59 INFO - 'tpaint', 17:52:59 INFO - 'sessionrestore', 17:52:59 INFO - 'sessionrestore_no_auto_restore', 17:52:59 INFO - 'tabpaint']}, 17:52:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:52:59 INFO - 'ts_paint', 17:52:59 INFO - 'tpaint', 17:52:59 INFO - 'sessionrestore', 17:52:59 INFO - 'sessionrestore_no_auto_restore', 17:52:59 INFO - 'tabpaint']}, 17:52:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:52:59 INFO - 'ts_paint', 17:52:59 INFO - 'tpaint', 17:52:59 INFO - 'sessionrestore', 17:52:59 INFO - 'sessionrestore_no_auto_restore', 17:52:59 INFO - 'tabpaint']}, 17:52:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['a11yr', 17:52:59 INFO - 'ts_paint', 17:52:59 INFO - 'tpaint', 17:52:59 INFO - 'sessionrestore', 17:52:59 INFO - 'sessionrestore_no_auto_restore', 17:52:59 INFO - 'tabpaint']}, 17:52:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['a11yr', 17:52:59 INFO - 'ts_paint', 17:52:59 INFO - 'tpaint', 17:52:59 INFO - 'sessionrestore', 17:52:59 INFO - 'sessionrestore_no_auto_restore', 17:52:59 INFO - 'tabpaint']}, 17:52:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['tsvgx', 17:52:59 INFO - 'tsvgr_opacity', 17:52:59 INFO - 'tart', 17:52:59 INFO - 'tscrollx', 17:52:59 INFO - 'cart', 17:52:59 INFO - 'tsvg_static']}, 17:52:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:52:59 INFO - 'tsvgr_opacity', 17:52:59 INFO - 'tart', 17:52:59 INFO - 'tscrollx', 17:52:59 INFO - 'cart', 17:52:59 INFO - 'tsvg_static']}, 17:52:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:52:59 INFO - 'talos_options': ['--disable-e10s'], 17:52:59 INFO - 'tests': ['tp5o']}, 17:52:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:52:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:52:59 INFO - 'talos_options': ['--disable-e10s', 17:52:59 INFO - '--xperf_path', 17:52:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:52:59 INFO - 'tests': ['tp5n']}, 17:52:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:52:59 INFO - 'talos_options': ['--xperf_path', 17:52:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:52:59 INFO - 'tests': ['tp5n']}}, 17:52:59 INFO - 'talos.zip': {'path': '', 17:52:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:52:59 INFO - Running post-action listener: _resource_record_post_action 17:52:59 INFO - [mozharness: 2017-03-29 00:52:59.564335Z] Finished populate-webroot step (success) 17:52:59 INFO - [mozharness: 2017-03-29 00:52:59.564443Z] Running create-virtualenv step. 17:52:59 INFO - Running pre-action listener: _resource_record_pre_action 17:52:59 INFO - Running main action method: create_virtualenv 17:52:59 INFO - Creating virtualenv /builds/slave/test/build/venv 17:52:59 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 17:52:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:52:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:52:59 INFO - Using real prefix '/tools/python27' 17:52:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:53:00 INFO - Installing distribute.............................................................................................................................................................................................done. 17:53:03 INFO - Installing pip.................done. 17:53:03 INFO - Return code: 0 17:53:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:53:03 INFO - Reading from file tmpfile_stdout 17:53:03 INFO - Output received: 17:53:03 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) 17:53:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:53:03 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')]} 17:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:03 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')]} 17:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:03 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:03 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 17:53:03 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 17:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:03 INFO - 'HOME': '/Users/cltbld', 17:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:03 INFO - 'LOGNAME': 'cltbld', 17:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:03 INFO - 'MOZ_NO_REMOTE': '1', 17:53:03 INFO - 'NO_EM_RESTART': '1', 17:53:03 INFO - 'PAGER': '/bin/cat', 17:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:03 INFO - 'PWD': '/builds/slave/test', 17:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:03 INFO - 'SHELL': '/bin/bash', 17:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:03 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:03 INFO - 'USER': 'cltbld', 17:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:03 INFO - 'XPC_FLAGS': '0x0', 17:53:03 INFO - 'XPC_SERVICE_NAME': '0', 17:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:03 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 17:53:03 INFO - Cleaning up... 17:53:03 INFO - Return code: 0 17:53:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:53:03 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')]} 17:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:03 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')]} 17:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:03 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:03 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 17:53:03 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 17:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:03 INFO - 'HOME': '/Users/cltbld', 17:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:03 INFO - 'LOGNAME': 'cltbld', 17:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:03 INFO - 'MOZ_NO_REMOTE': '1', 17:53:03 INFO - 'NO_EM_RESTART': '1', 17:53:03 INFO - 'PAGER': '/bin/cat', 17:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:03 INFO - 'PWD': '/builds/slave/test', 17:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:03 INFO - 'SHELL': '/bin/bash', 17:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:03 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:03 INFO - 'USER': 'cltbld', 17:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:03 INFO - 'XPC_FLAGS': '0x0', 17:53:03 INFO - 'XPC_SERVICE_NAME': '0', 17:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:04 INFO - Downloading/unpacking psutil>=3.1.1 17:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:53:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:53:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:53:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:53:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:53:07 INFO - Installing collected packages: psutil 17:53:07 INFO - Running setup.py install for psutil 17:53:07 INFO - building 'psutil._psutil_osx' extension 17:53:07 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 17:53:08 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 17:53:08 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 17:53:08 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 17:53:08 INFO - building 'psutil._psutil_posix' extension 17:53:08 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 17:53:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:53:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:53:08 INFO - ^ 17:53:08 INFO - 1 warning generated. 17:53:08 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 17:53:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:53:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:53:08 INFO - Successfully installed psutil 17:53:08 INFO - Cleaning up... 17:53:08 INFO - Return code: 0 17:53:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:09 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')]} 17:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:09 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:09 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 17:53:09 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 17:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:09 INFO - 'HOME': '/Users/cltbld', 17:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:09 INFO - 'LOGNAME': 'cltbld', 17:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:09 INFO - 'MOZ_NO_REMOTE': '1', 17:53:09 INFO - 'NO_EM_RESTART': '1', 17:53:09 INFO - 'PAGER': '/bin/cat', 17:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:09 INFO - 'PWD': '/builds/slave/test', 17:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:09 INFO - 'SHELL': '/bin/bash', 17:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:09 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:09 INFO - 'USER': 'cltbld', 17:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:09 INFO - 'XPC_FLAGS': '0x0', 17:53:09 INFO - 'XPC_SERVICE_NAME': '0', 17:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:53:09 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 17:53:09 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 17:53:09 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 17:53:09 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 17:53:09 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 17:53:09 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 17:53:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:53:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:53:12 INFO - Installing collected packages: mozsystemmonitor 17:53:12 INFO - Running setup.py install for mozsystemmonitor 17:53:12 INFO - Successfully installed mozsystemmonitor 17:53:12 INFO - Cleaning up... 17:53:13 INFO - Return code: 0 17:53:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:53: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')]} 17:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53: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')]} 17:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:13 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:13 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 17:53:13 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 17:53:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:13 INFO - 'HOME': '/Users/cltbld', 17:53:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:13 INFO - 'LOGNAME': 'cltbld', 17:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:13 INFO - 'MOZ_NO_REMOTE': '1', 17:53:13 INFO - 'NO_EM_RESTART': '1', 17:53:13 INFO - 'PAGER': '/bin/cat', 17:53:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:13 INFO - 'PWD': '/builds/slave/test', 17:53:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:13 INFO - 'SHELL': '/bin/bash', 17:53:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:13 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:13 INFO - 'USER': 'cltbld', 17:53:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:13 INFO - 'XPC_FLAGS': '0x0', 17:53:13 INFO - 'XPC_SERVICE_NAME': '0', 17:53:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:13 INFO - Downloading/unpacking jsonschema==2.5.1 17:53:13 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 17:53:13 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 17:53:13 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 17:53:13 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 17:53:13 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 17:53:13 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 17:53:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:53:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:53:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:53:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:53:16 INFO - Downloading functools32-3.2.3-2.tar.gz 17:53:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:53:16 INFO - warning: no files found matching '*.txt' 17:53:16 INFO - no previously-included directories found matching 'build' 17:53:16 INFO - no previously-included directories found matching 'dist' 17:53:16 INFO - no previously-included directories found matching '.git*' 17:53:16 INFO - Installing collected packages: jsonschema, functools32 17:53:16 INFO - Running setup.py install for functools32 17:53:16 INFO - warning: no files found matching '*.txt' 17:53:16 INFO - no previously-included directories found matching 'build' 17:53:16 INFO - no previously-included directories found matching 'dist' 17:53:16 INFO - no previously-included directories found matching '.git*' 17:53:16 INFO - Successfully installed jsonschema functools32 17:53:16 INFO - Cleaning up... 17:53:16 INFO - Return code: 0 17:53:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:53:16 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')]} 17:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:16 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')]} 17:53:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:16 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:16 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 17:53:16 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 17:53:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:16 INFO - 'HOME': '/Users/cltbld', 17:53:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:16 INFO - 'LOGNAME': 'cltbld', 17:53:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:16 INFO - 'MOZ_NO_REMOTE': '1', 17:53:16 INFO - 'NO_EM_RESTART': '1', 17:53:16 INFO - 'PAGER': '/bin/cat', 17:53:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:16 INFO - 'PWD': '/builds/slave/test', 17:53:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:16 INFO - 'SHELL': '/bin/bash', 17:53:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:16 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:16 INFO - 'USER': 'cltbld', 17:53:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:16 INFO - 'XPC_FLAGS': '0x0', 17:53:16 INFO - 'XPC_SERVICE_NAME': '0', 17:53:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:53:16 INFO - Cleaning up... 17:53:17 INFO - Return code: 0 17:53:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:53:17 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')]} 17:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:17 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')]} 17:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:17 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:17 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 17:53:17 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 17:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:17 INFO - 'HOME': '/Users/cltbld', 17:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:17 INFO - 'LOGNAME': 'cltbld', 17:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:17 INFO - 'MOZ_NO_REMOTE': '1', 17:53:17 INFO - 'NO_EM_RESTART': '1', 17:53:17 INFO - 'PAGER': '/bin/cat', 17:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:17 INFO - 'PWD': '/builds/slave/test', 17:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:17 INFO - 'SHELL': '/bin/bash', 17:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:17 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:17 INFO - 'USER': 'cltbld', 17:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:17 INFO - 'XPC_FLAGS': '0x0', 17:53:17 INFO - 'XPC_SERVICE_NAME': '0', 17:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:17 INFO - Downloading/unpacking blobuploader==1.2.4 17:53:17 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 17:53:17 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 17:53:17 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 17:53:17 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 17:53:17 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 17:53:17 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 17:53:19 INFO - Downloading blobuploader-1.2.4.tar.gz 17:53:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:53:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:53:19 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 17:53:19 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 17:53:19 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 17:53:19 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 17:53:19 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 17:53:19 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 17:53:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:53:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:53:20 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 17:53:20 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 17:53:20 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 17:53:20 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 17:53:20 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 17:53:20 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 17:53:21 INFO - Downloading docopt-0.6.1.tar.gz 17:53:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:53:21 INFO - Installing collected packages: blobuploader, requests, docopt 17:53:21 INFO - Running setup.py install for blobuploader 17:53:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:53:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:53:21 INFO - Running setup.py install for requests 17:53:21 INFO - Running setup.py install for docopt 17:53:22 INFO - Successfully installed blobuploader requests docopt 17:53:22 INFO - Cleaning up... 17:53:22 INFO - Return code: 0 17:53:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:53:22 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')]} 17:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:22 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')]} 17:53:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:22 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:22 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 17:53:22 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 17:53:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:22 INFO - 'HOME': '/Users/cltbld', 17:53:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:22 INFO - 'LOGNAME': 'cltbld', 17:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:22 INFO - 'MOZ_NO_REMOTE': '1', 17:53:22 INFO - 'NO_EM_RESTART': '1', 17:53:22 INFO - 'PAGER': '/bin/cat', 17:53:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:22 INFO - 'PWD': '/builds/slave/test', 17:53:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:22 INFO - 'SHELL': '/bin/bash', 17:53:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:22 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:22 INFO - 'USER': 'cltbld', 17:53:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:22 INFO - 'XPC_FLAGS': '0x0', 17:53:22 INFO - 'XPC_SERVICE_NAME': '0', 17:53:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:53:22 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-VHCcnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:53:22 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-l59_Ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:53:22 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-qmMC0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:53:22 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-x9yfaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:53:22 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-y6tjHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:53:22 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-wSuXEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_yBosd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-c3U7Fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_i_xsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-vgNrar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-9WmDgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-q7i2q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ujazLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-aWbRoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-NUrkwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:53:23 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-eYgMVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:53:24 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-2fq3i9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:53:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:53:24 INFO - Running setup.py install for manifestparser 17:53:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:53:24 INFO - Running setup.py install for mozcrash 17:53:24 INFO - Running setup.py install for mozdebug 17:53:24 INFO - Running setup.py install for mozdevice 17:53:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:53:24 INFO - Running setup.py install for mozfile 17:53:24 INFO - Running setup.py install for mozhttpd 17:53:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:53:25 INFO - Running setup.py install for mozinfo 17:53:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:53:25 INFO - Running setup.py install for mozInstall 17:53:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:53:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:53:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:53:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:53:25 INFO - Running setup.py install for mozleak 17:53:25 INFO - Running setup.py install for mozlog 17:53:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:53:25 INFO - Running setup.py install for moznetwork 17:53:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:53:25 INFO - Running setup.py install for mozprocess 17:53:25 INFO - Running setup.py install for mozprofile 17:53:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:53:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:53:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:53:26 INFO - Running setup.py install for mozrunner 17:53:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:53:26 INFO - Running setup.py install for mozscreenshot 17:53:26 INFO - Running setup.py install for moztest 17:53:26 INFO - Running setup.py install for mozversion 17:53:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:53:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:53:26 INFO - Cleaning up... 17:53:26 INFO - Return code: 0 17:53:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:53: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')]} 17:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53: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')]} 17:53:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:26 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:26 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 17:53:26 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 17:53:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:26 INFO - 'HOME': '/Users/cltbld', 17:53:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:26 INFO - 'LOGNAME': 'cltbld', 17:53:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:26 INFO - 'MOZ_NO_REMOTE': '1', 17:53:26 INFO - 'NO_EM_RESTART': '1', 17:53:26 INFO - 'PAGER': '/bin/cat', 17:53:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:26 INFO - 'PWD': '/builds/slave/test', 17:53:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:26 INFO - 'SHELL': '/bin/bash', 17:53:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:26 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:26 INFO - 'USER': 'cltbld', 17:53:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:26 INFO - 'XPC_FLAGS': '0x0', 17:53:26 INFO - 'XPC_SERVICE_NAME': '0', 17:53:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-jrbXzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-GXPHWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-R5soZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-98QySj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-lmpTRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-FNeGc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-BUtqSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-e1gsEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-SLm4cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:53:27 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)) 17:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:53:27 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-eblPvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:53:28 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)) 17:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:53:28 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-mpKwUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:53:28 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)) 17:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:53:28 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-y1dk8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:53:28 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)) 17:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:53:28 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-d2n4Nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:53:28 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)) 17:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:53:28 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-clPjo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:53:28 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)) 17:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:53:28 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-BMZ8Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:53:28 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)) 17:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:53:28 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-4IyKEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:53:28 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)) 17:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:53:28 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-5fblFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:53:28 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)) 17:53:28 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)) 17:53:28 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)) 17:53:28 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)) 17:53:28 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)) 17:53:28 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)) 17:53:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:53:28 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 17:53:28 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 17:53:28 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 17:53:28 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 17:53:28 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 17:53:28 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 17:53:31 INFO - Downloading blessings-1.6.tar.gz 17:53:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:53:31 INFO - Installing collected packages: blessings 17:53:31 INFO - Running setup.py install for blessings 17:53:31 INFO - Successfully installed blessings 17:53:31 INFO - Cleaning up... 17:53:31 INFO - Return code: 0 17:53:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:53:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:53:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:53:32 INFO - Reading from file tmpfile_stdout 17:53:32 INFO - Current package versions: 17:53:32 INFO - blessings == 1.6 17:53:32 INFO - blobuploader == 1.2.4 17:53:32 INFO - docopt == 0.6.1 17:53:32 INFO - functools32 == 3.2.3-2 17:53:32 INFO - jsonschema == 2.5.1 17:53:32 INFO - manifestparser == 1.1 17:53:32 INFO - mozInstall == 1.12 17:53:32 INFO - mozcrash == 1.0 17:53:32 INFO - mozdebug == 0.1 17:53:32 INFO - mozdevice == 0.49 17:53:32 INFO - mozfile == 1.2 17:53:32 INFO - mozhttpd == 0.7 17:53:32 INFO - mozinfo == 0.9 17:53:32 INFO - mozleak == 0.1 17:53:32 INFO - mozlog == 3.4 17:53:32 INFO - moznetwork == 0.27 17:53:32 INFO - mozprocess == 0.25 17:53:32 INFO - mozprofile == 0.28 17:53:32 INFO - mozrunner == 6.13 17:53:32 INFO - mozscreenshot == 0.1 17:53:32 INFO - mozsystemmonitor == 0.3 17:53:32 INFO - moztest == 0.8 17:53:32 INFO - mozversion == 1.4 17:53:32 INFO - psutil == 3.1.1 17:53:32 INFO - requests == 1.2.3 17:53:32 INFO - wsgiref == 0.1.2 17:53:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:32 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 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:32 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 17:53:32 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 17:53:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:32 INFO - 'HOME': '/Users/cltbld', 17:53:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:32 INFO - 'LOGNAME': 'cltbld', 17:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:32 INFO - 'MOZ_NO_REMOTE': '1', 17:53:32 INFO - 'NO_EM_RESTART': '1', 17:53:32 INFO - 'PAGER': '/bin/cat', 17:53:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:32 INFO - 'PWD': '/builds/slave/test', 17:53:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:32 INFO - 'SHELL': '/bin/bash', 17:53:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:32 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:32 INFO - 'USER': 'cltbld', 17:53:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:32 INFO - 'XPC_FLAGS': '0x0', 17:53:32 INFO - 'XPC_SERVICE_NAME': '0', 17:53:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 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)) 17:53:32 INFO - Cleaning up... 17:53:32 INFO - Return code: 0 17:53:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e900b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb829d56a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, '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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:53:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:53:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:32 INFO - 'HOME': '/Users/cltbld', 17:53:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:32 INFO - 'LOGNAME': 'cltbld', 17:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:32 INFO - 'MOZ_NO_REMOTE': '1', 17:53:32 INFO - 'NO_EM_RESTART': '1', 17:53:32 INFO - 'PAGER': '/bin/cat', 17:53:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:32 INFO - 'PWD': '/builds/slave/test', 17:53:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:32 INFO - 'SHELL': '/bin/bash', 17:53:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:32 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:32 INFO - 'USER': 'cltbld', 17:53:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:32 INFO - 'XPC_FLAGS': '0x0', 17:53:32 INFO - 'XPC_SERVICE_NAME': '0', 17:53:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:53:32 INFO - Cleaning up... 17:53:32 INFO - Return code: 0 17:53:32 INFO - Running post-action listener: _resource_record_post_action 17:53:32 INFO - Running post-action listener: _start_resource_monitoring 17:53:32 INFO - Starting resource monitoring. 17:53:32 INFO - [mozharness: 2017-03-29 00:53:32.755236Z] Finished create-virtualenv step (success) 17:53:32 INFO - [mozharness: 2017-03-29 00:53:32.756216Z] Running install step. 17:53:32 INFO - Running pre-action listener: _resource_record_pre_action 17:53:32 INFO - Running main action method: install 17:53:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:53:33 INFO - Reading from file tmpfile_stdout 17:53:33 INFO - Detecting whether we're running mozinstall >=1.0... 17:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:53:33 INFO - Reading from file tmpfile_stdout 17:53:33 INFO - Output received: 17:53:33 INFO - Usage: mozinstall [options] installer 17:53:33 INFO - Options: 17:53:33 INFO - -h, --help show this help message and exit 17:53:33 INFO - -d DEST, --destination=DEST 17:53:33 INFO - Directory to install application into. [default: 17:53:33 INFO - "/builds/slave/test"] 17:53:33 INFO - --app=APP Application being installed. [default: firefox] 17:53:33 INFO - mkdir: /builds/slave/test/build/application 17:53:33 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'] 17:53:33 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 17:53:53 INFO - Reading from file tmpfile_stdout 17:53:53 INFO - Output received: 17:53:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:53:53 INFO - Running post-action listener: _resource_record_post_action 17:53:53 INFO - [mozharness: 2017-03-29 00:53:53.679458Z] Finished install step (success) 17:53:53 INFO - [mozharness: 2017-03-29 00:53:53.679578Z] Running run-tests step. 17:53:53 INFO - Running pre-action listener: _resource_record_pre_action 17:53:53 INFO - Running main action method: run_tests 17:53:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:53:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:53:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:53:53 INFO - 2017-03-28 17:53:53.693 screenresolution[1623:7285] starting screenresolution argv=screenresolution get 17:53:53 INFO - 2017-03-28 17:53:53.696 screenresolution[1623:7285] Display 0: 1600x1200x32@60 17:53:53 INFO - 2017-03-28 17:53:53.701 screenresolution[1624:7288] starting screenresolution argv=screenresolution list 17:53:53 INFO - Available Modes on Display 0 17:53:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:53:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:53:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:53:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:53:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:53:53 INFO - Intel Iris: 17:53:53 INFO - Chipset Model: Intel Iris 17:53:53 INFO - Type: GPU 17:53:53 INFO - Bus: Built-In 17:53:53 INFO - VRAM (Dynamic, Max): 1536 MB 17:53:53 INFO - Vendor: Intel (0x8086) 17:53:53 INFO - Device ID: 0x0a2e 17:53:53 INFO - Revision ID: 0x0009 17:53:53 INFO - Displays: 17:53:53 INFO - Display: 17:53:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:53:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:53:53 INFO - Display Serial Number: 1600x1200 60 17:53:53 INFO - Main Display: Yes 17:53:53 INFO - Mirror: Off 17:53:53 INFO - Online: Yes 17:53:53 INFO - Rotation: Supported 17:53:53 INFO - Return code: 0 17:53:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:53:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:53:53 INFO - Python 2.7.3 17:53:53 INFO - Return code: 0 17:53:53 INFO - grabbing minidump binary from tooltool 17:53:53 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')]} 17:53:53 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 0x10e6ded30>, '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 0x10e605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6d3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:53:53 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 17:53:53 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 17:53:53 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 17:53:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:53:54 INFO - Return code: 0 17:53:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:53:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:53:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:53:54 INFO - ENV: RUST_BACKTRACE is now 1 17:53:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:53:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:53:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 17:53:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 17:53:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M0mYwFjlGY/Render', 17:53:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:54 INFO - 'HOME': '/Users/cltbld', 17:53:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:54 INFO - 'LOGNAME': 'cltbld', 17:53:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:53:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:54 INFO - 'MOZ_NO_REMOTE': '1', 17:53:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:53:54 INFO - 'NO_EM_RESTART': '1', 17:53:54 INFO - 'PAGER': '/bin/cat', 17:53:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:54 INFO - 'PWD': '/builds/slave/test', 17:53:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:53:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:54 INFO - 'RUST_BACKTRACE': '1', 17:53:54 INFO - 'SHELL': '/bin/bash', 17:53:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HIzsP17Ic/Listeners', 17:53:54 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:53:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:54 INFO - 'USER': 'cltbld', 17:53:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:54 INFO - 'XPC_FLAGS': '0x0', 17:53:54 INFO - 'XPC_SERVICE_NAME': '0', 17:53:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 17:53:54 INFO - mozversion application_buildid: 20170328152823 17:53:54 INFO - mozversion application_changeset: bbab5665154904a6f4cd39b83bd9140dc5888d81 17:53:54 INFO - mozversion application_display_name: Nightly 17:53:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:53:54 INFO - mozversion application_name: Firefox 17:53:54 INFO - mozversion application_remotingname: firefox 17:53:54 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 17:53:54 INFO - mozversion application_vendor: Mozilla 17:53:54 INFO - mozversion application_version: 55.0a1 17:53:54 INFO - mozversion platform_buildid: 20170328152823 17:53:54 INFO - mozversion platform_changeset: bbab5665154904a6f4cd39b83bd9140dc5888d81 17:53:54 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 17:53:54 INFO - mozversion platform_version: 55.0a1 17:53:54 INFO - using testdate: 1490748834 17:53:54 INFO - actual date: 1490748834 17:53:54 INFO - starting webserver on 'localhost:49236' 17:53:54 INFO - SUITE-START | Running 2 tests 17:53:54 INFO - TEST-START | dromaeo_css 17:53:54 INFO - operating with platform_type : mac_ 17:53:54 INFO - Initialising browser for dromaeo_css test... 17:53:54 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpzsqCC0/profile http://localhost:49236/getInfo.html) 17:53:57 INFO - PID 1634 | 2017-03-28 17:53:57.476 plugin-container[1636:7469] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 17:53:57 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:53:57 INFO - PID 1634 | 2017-03-28 17:53:57.479 plugin-container[1636:7469] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 17:53:57 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:53:57 INFO - PID 1634 | 2017-03-28 17:53:57.479 plugin-container[1636:7469] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:53:57 INFO - PID 1634 | __metrics Screen width/height:1600/1200 17:53:57 INFO - PID 1634 | colorDepth:24 17:53:57 INFO - PID 1634 | Browser inner width/height: 1024/689 17:53:57 INFO - PID 1634 | __metrics 17:53:57 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 17:53:57 ERROR - PID 1634 | 1490748837643 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/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpzsqCC0/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpzsqCC0/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpzsqCC0/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpzsqCC0/profile/extensions/talos-powers@TalosPowersService.js:55:9 17:53:58 INFO - TEST-INFO | 1634: exit 0 17:53:58 INFO - Browser initialized. 17:53:58 INFO - Running cycle 1/1 for dromaeo_css test... 17:53:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpzsqCC0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:54:00 INFO - PID 1637 | 2017-03-28 17:54:00.909 plugin-container[1638:7604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 17:54:00 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:54:00 INFO - PID 1637 | 2017-03-28 17:54:00.910 plugin-container[1638:7604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 17:54:00 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:54:00 INFO - PID 1637 | 2017-03-28 17:54:00.910 plugin-container[1638:7604] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:54:10 INFO - PID 1637 | RSS: Main: 229724160 17:54:10 INFO - PID 1637 | 17:57:15 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 17:57:16 INFO - PID 1637 | RSS: Main: 253886464 17:57:16 INFO - PID 1637 | 17:58:58 INFO - PID 1637 | ************************* 17:58:58 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:58:58 INFO - PID 1637 | 17:58:58 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:58:58 INFO - PID 1637 | Full stack: 17:58:58 INFO - PID 1637 | ************************* 17:58:58 INFO - PID 1637 | ************************* 17:58:58 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:58:58 INFO - PID 1637 | 17:58:58 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:58:58 INFO - PID 1637 | Full stack: 17:58:58 INFO - PID 1637 | ************************* 17:58:58 INFO - PID 1637 | ************************* 17:58:58 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:58:58 INFO - PID 1637 | 17:58:58 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:58:58 INFO - PID 1637 | Full stack: 17:58:58 INFO - PID 1637 | ************************* 17:58:58 INFO - PID 1637 | ************************* 17:58:58 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:58:58 INFO - PID 1637 | 17:58:58 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:58:58 INFO - PID 1637 | Full stack: 17:58:58 INFO - PID 1637 | ************************* 18:00:18 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 18:00:19 INFO - PID 1637 | RSS: Main: 261840896 18:00:19 INFO - PID 1637 | 18:03:21 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 18:03:22 INFO - PID 1637 | RSS: Main: 272936960 18:03:22 INFO - PID 1637 | 18:06:24 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 18:06:24 INFO - PID 1637 | RSS: Main: 266072064 18:06:24 INFO - PID 1637 | 18:09:27 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 18:09:28 INFO - PID 1637 | RSS: Main: 280358912 18:09:28 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 18:12:31 INFO - PID 1637 | RSS: Main: 274153472 18:12:31 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | __start_tp_report 18:12:31 INFO - PID 1637 | _x_x_mozilla_page_load 18:12:31 INFO - PID 1637 | _x_x_mozilla_page_load_details 18:12:31 INFO - PID 1637 | |i|pagename|runs| 18:12:31 INFO - PID 1637 | |0;dojo.html;3217.356528694261;3534.0281422608764;3570.1967057412085;3586.92826143477;3590.605033446322;5420.972895135524;6052.818415447538;6125.845966769335;6260.46786023188;6321.43107120359;6116.366205733227;6146.862830376373;6246.781362652308;6286.497080233582;6290.0250461178475;4148.543660197376;5111.744412779365;5646.971765141178;5666.773329066832;5783.074708046714;5584.944150558483;5663.7168141592965;5761.193432919395;5817.360090390053;5847.093700476433;5473.480019398151;5533.114701647757;5551.472610102034;5679.602427830033;5717.342505611867;5602.299712535933;5610.775568977236;5655.123455864347;5678.716064196774;5690.5447564194765;5040.7731652075745;5550.028744969654;5588.832335029955;5616.32604087508;5616.66300022;6081.270247570275;6103.481204097613;6154.44609985099;6169.105479705418;6319.115323854664;6410.839729006765;6608.570442921195;6672.332766723337;6684.2981486944145;6694.196696396415;6772.390484856337;6772.69522871472;6792.320767923217;6803;6814.080099186596;6324.810255692337;6392.073149393358;6393.776217832354;6426.807195784134;6433.871322573569;6551.246606640202;6612.537122401482;6631;6674.098996635392;6696.598204107671;5740.823131258082;6070;6304.936950630527;6521.673916304166;6590.242122155918;6450;6467.579607325509;6591;6662.600243985376;6770.255271920085;6657.234418041987;6657.73369065232;6659.833504162338;6669;6697.296783837724;6357.11000459936;6415.26224484181;6607.933920660828;6683.331666833278;6745.0556922030955;5332.520073193354;5334.466553344712;5383.67697938125;5383.757730902119;5403.756830942617;4703.764811759398;4725.64557658177;4736.005438857878;4746.667733258639;4758.000819827875;6340.746370145235;6375.10748495211;6391.392817682313;6402.935970640234;6404.263509696351;6480.157579514633;6550.868982620321;6739.932600674028;6741.831454213684;6768.052472653832;6342.904856427157;6375.6174629522375;6380.042993551005;6396.42432181106;6411.967940160363;5150.175971844487;5731.111677689882;5882.470577647947;5953.136795164677;5966.492848107872;6658.3674550917585;6796.388325050769;6912.723491060502;6936.132983377078;7004.4046256068805;5968.373320801254;6139.017757159012;6786.423154031962;6827.556208846409;6853.074834897225;6973.860522789414;7018.052562904147;7018.438524918097;7029.789106326818;7052.9647351762915;6362.936370636235;6398.5201109916015;6574.80275591733;6628.370304821035;6670.799876003728;6659.966700166468;6670.332966703291;6677.499187561049;6739.359760822714;6746.156730408699;6234.781782637586;6467.029945508211;6476.967615162082;6481.935180648133;6488.513361497812;5763.84723055399;6125.235451818428;6301.495880329654;6456.838579035561;6481.902771458337;5769.624974376652;6347.5556711029785;6414.743410263537;6429.38920802523;6439.162908821823;6541.2477565078725;6548.770793022221;6552.410283074546;6555.80332590023;6584.440322572635;6168.136460895568;6214.819184354958;6331.96834015827;6386.16979792624;6391.520635952415 18:12:31 INFO - PID 1637 | |1;ext.html;726.3608024938054;802.0936341933615;828.6892415344244;1213.8968187704045;1268.0426278159987;37286.62713372865;38485.61514384859;38523.614763852354;38530;38541;38039.61960380402;38110.856674299815;38218.426723599165;38229.61770382295;38379;7890.4476686631815;8080.39397045221;8330.458520196198;8547.914520854774;8555.48667079974;7633.969414129105;8501.872471912926;8550.52972086535;8635.740927772145;8641.135886411372;8326.209010144026;8574.78563035926;8664.826703465927;8664.8700269496;8671.783205419884;8591.742247732547;8665.740027799176;8712;8726.650933962634;8738.825223495494;8460.36547258952;8515.787105322355;8526.403151779377;8535.829283414298;8537.57312134397;37943.4308485373;38150;38154;38158;38358.424623630664;3013.4123845850067;3034.263934066369;3039.4680930837017;3046.8781248750024;3057.327387974642;23695.644565331535;27938.860305698545;28093.71906280952;28151.71848281532;28494.002709905053;2769.695333513312;2822.068717323278;2843;2853.4007858349773;2856.685764565896;221434;237663;269313;350559;350695;46681.066378672236;46746;46912;46916.53083469121;47014.29478557824;48835.51164488381;48896.51103488991;48947.755261223465;48984.755076225105;49257.56681898647;13758;14026.438942442188;14107.85892141086;14276.214808185441;14299.428022879176;11587.826182607268;11595.652130436101;11621.24461909973;11640.767184656259;11649;9724.756881077888;13616.910647148205;13747.862521374858;13833.446662133401;13926.860731392759;5937.732802023833;5970.014947533695;5995.025743521792;6003.249593800775;6021;948.3977674176947;953.9618415263451;966.7099870039101;994.6419289055934;1005.6228914157191;27617.309567260978;28470.430591388056;29103.56344654832;29429.96995105161;30114.69885301119;1039.0856046679025;1040.1990467340106;1066.8666416697913;1072.7103682005816;1078.2506158219962;11770.646880593597;12356.38218089092;12433.813492797615;12434.440450179764;12457.252564846138;457366;461271;461502;462558;462601;45350.77324613356;52618.47381526212;53019.734901325246;53380;53486.465135348924;1061.6496556136456;1267.7908145155945;1269.707967167538;1269.8730126987227;1434.6126545832776;20458.590828183056;22765.658515121955;22966.08135674621;23203.303900883002;23830.642540362256;31207;38425.655102071294;38494.42258366071;38803.22393552057;39633;450442;465533;466924;468660;471577;31919;32217.51673724849;32537;33620.66379336175;33704.83147584246;41833.58166418419;42887.78556107199;43377.56622433735;43871.12257754891;43972;28539;29247;29441.96953106631;30341.089767307018;30504.03447551804;28163.71836281611;29414.705852941195;30269.394612108073;30352.54471182978;30592.694073058985;35217.82391088028;35401.822990885914;35805.82097089498;36105.7362992294;36190.63809361873;26372.945082196497;28907.855460722563;29670.40659186761;30090.69909300879;30691.925782597504;21231.726096434264;21831.563368732855;23615.40961475977;23620.52758944846;24142.637860431758 18:12:31 INFO - PID 1637 | |2;jquery.html;393.5375933278397;405.47693475416696;406.66247014977574;408.8752930356241;422.442376063596;21002;21573.801955256982;21920.45198870029;22159.667604985938;22853.657195142045;18021.4593562193;22820.8858955705;22831.657525137136;23084;23579.764202357972;6060.2424696912885;6328.335524769902;6329.082283068952;6332.746690132389;6338.683065846712;6054.60645058072;6149;6194.349593292707;6222.81331560054;6263.530235232353;6219.160413344209;6219.222597175353;6226.533010024244;6233.0338797486465;6235.968820155893;5053.722045287508;6114.602550834204;6159.23009623797;6167.383261673842;6188;5396.192275587683;5399.217113518518;5417.918731219035;5430.429804870471;5484.204790305424;26799.330016749544;26908.789104490148;26962.730372696216;27248.59127113095;27333.76998035093;1979.4556496963307;2000.9699854502194;2005.81947624713;2007.0968064370945;2032.542677897476;16068;16794.580135496588;17006.404775832787;17366.39217627377;17394.391196308068;1555.237933412631;1643.9506814795575;1663.808662003873;1673.3306677329044;1674.148244732202;145328;153482;154726;155361.44638553582;156262;12483.50065997368;12665.936670316589;12681.93659031699;12736.299503527394;12814.743705126031;15928.76106858398;16095.758563621555;16128.193590320438;16129.758053629204;16202.108884011492;7803.099180739141;8892.777680557909;8991.100889911088;9102;9103.817923641622;8444.240018398374;8558.78603034929;8641.697540586048;8743.081976392514;8790.120987901159;6428;6569.605823650596;8017.919820801834;8023.277904988462;8052.516848989079;2127.8936053197276;2140.978590214109;2142.32516757221;2144.7855214478427;2673.46530693862;889.5196593839254;903.2683526343551;906.7098528470958;933.4725879877848;950.2587981374539;20264.696029559567;20915.8954205228;20942.89528552378;20949;20982.790172098084;935.7473482159779;964.2719746591279;969.4038166527534;972.644984580623;972.9318947673595;10170.08469237772;10409.895901040893;10785.46072696362;10871.83692244617;11143.997040266415;407943;578122.5003999424;579974;588678;702681;104990;114628;114865.42567287278;146876;149731.2513437426;1070.5236169904474;1129.7966366054025;1139.3448766959032;1201.6575276046285;1208.6555331730415;20208.686435381653;20876.58246835085;21095.15619375208;21360.087179948387;21386.930653466952;48891.75554122207;60921;61424;64980.35019649932;66445.33554664391;81898.59050704708;82804.17195828205;82990;84379.5781021091;90217;6437.742490300336;7133.357997780225;7676.347510461674;8708.521031343336;8921.687740929035;13732.970027247797;14080.507182248572;14146.151230926178;14510.782338265146;14617.222727681707;11348.694900086595;11674.941625292158;11802.704932376759;11838.704032399259;12118.394080296126;9771;11615.5934542294;11645.41772911334;11796.646100616996;11872.347020913932;12045.819312710491;12175.512979480722;12441.44013519412;12499.500019999097;12582.874171258536;8507.617157228064;8579.442336248125;8774.736757897273;8989.617751296042;9039.231665308522;11436.027937625395;11776.76446471083;12118.03055755555;12149.757004859677;12162.695932601755 18:12:31 INFO - PID 1637 | |3;mootools.html;1386.6949271160345;1421;1431.899767016308;1437.820986790832;1470.7867359232912;10671.1996600255;10874.891251087487;11099.16756243282;11136.443177841105;11163.99524042836;10813.729656758585;10980.890191098086;10992.835107473375;11011.119110471163;11013;2424.6726691896542;2470.72821989581;2479.913203037894;2487.825852190347;2490.86300253486;2387.1167667962877;2438.5244877248942;2457.8156638252112;2467.629855521668;2469.900894102125;2427.9150229741963;2440.9633855492184;2450.0567281596536;2484.428581426273;2485.763852434016;2428.0287884846116;2429.2226487524;2447.21689059501;2447.865367404792;2475.4553998120386;2077.792220777925;2334.7781960713705;2437.439388940536;2447.7919376852988;2463.3718401807423;11074.833877491845;11125.666230013032;11180.832287515694;11186.216964812467;11194.776104477865;6274.1843560336865;6490.837729056764;6496.058071579641;6497.610143391412;6516.609003459807;4051.0682543015273;4104.527979282361;4110.753354798691;4124.649404800595;4153.875383738493;1099.8295264234057;1174.2602160638744;1183.4970137691446;1219.9390030498528;1227.3679055286534;13866.93066534671;13985.021048526607;14938.70122597542;15250.161241131733;15377.615559610987;4696.412948381452;5101.821436249787;5227.059129356752;5409.864753381197;5440.939016891668;9463.479508626953;11441.084713222923;11455.59905403307;11547.76904461906;11573.594924177612;6249.6562689052535;6294.8111556653375;6318.62088274705;6325.3674632537295;6349;5195.584353251731;5911.379305172981;5938.762449501972;5999.340072592011;6058.091286307089;4247.851325203603;4323.610875021263;4348.456298014223;4362.280223763107;4362.890927726832;1022.5551884930079;1045.72288343589;1203.6870413692327;1237.6287113865803;1237.8762123787549;754.8943147959291;757.3221966340095;779.8479296537121;785.2383188307333;797.401948538596;5092.567131793765;5928.347881733006;5975;6064.787732429344;6152.3540028297275;565.0422533805242;583.9708014599338;594.5243804956104;595.8808238352347;596.6062398816847;3925.175713100281;4212.136512015029;4221.303484925014;4301.07526881722;4335.891602709958;13587.660308492366;14173.645658858612;14178.291085445686;14215.289235538181;14215.50737172582;13297;13767;14134.010619256758;14138.575842724735;14144.71710565783;8849.4247873888;10061.69814905554;10345.017223363768;10356.741081473023;10371.585136594602;5671.206031155559;5673.06394444912;5814.418558144152;6725.495587830835;6729.803343412036;10941.726456838644;11859.34773587461;12101;12140.878591213976;12148.817767733315;12196.756064878475;12341.691457713629;12345.382730863601;12361.752764944471;12566;8869.06874778139;9141.085891411072;9201.30990175753;9208.12522810332;9230.123138301851;10090.09189172978;10246.53890574911;10314.916933722152;10337.74155646115;10339.017793309624;8568.742937711879;8617.439866408664;8708.869366959627;8879.978802437803;8905.866412003696;8306;8414.410991230556;8425.325973922194;8476.110008449024;8624.611892464729;7713.035870516185;7731.265529774662;7741.651625676745;7817.022872140982;7852.097008844056;5827.912581311199;5890.234269545103;5983.102534619842;6033.487153591818;6063.727132279147;5606.467385598362;5682.659040457585;5692.658440493584;5755.7409916553015;5804.46181761825 18:12:31 INFO - PID 1637 | |4;prototype.html;398.96608788252996;400.73150988837483;404.591362723649;404.62024496467035;405.72816213137196;5219.817306394281;6237.502999280174;6309;6315.905261421082;6365.840853978647;4920.753962301888;5121.897562048757;5523.364813046491;6149.877002459948;6203.162573052637;3954.5452272988614;4159.480064991876;4399.922019105324;4796.784144713496;4800.399950006249;3973.7417067890638;4415.470143582775;4431.977840110795;4829;4845.854624361257;3911.2373087247997;3938.940915886264;4722.5513576210205;4755.429348478187;4804.879878003043;3680.2455496623397;4302.161078589676;4494.786407669911;4597.2874204498185;4654.255319148954;3853.903652408684;4231.978840105779;4234.195502854448;4265.232258193524;4265.9786701066605;5313.707746073965;5708.486236238717;5805.419458054161;6003.549733769984;6128;5258.894822103574;5517;5528.944710552883;5559.499645031926;5581.888362232733;6331.14529538511;6635.611179975205;6672.799816005528;6688.598684078922;6698.83252918676;3975.3241948868763;4685.625149988027;4691.636777796648;4711.199096153662;4750.358701575277;6703.798886033378;6987.26633703464;7006.684699188549;7045.823854403681;7113.64431778409;4027.597240275949;4694.671373003857;4756.072565849695;4810.95189048112;4835.419749630067;4290.592393722592;4919.87700307488;4923.679960802608;4942.504035462069;4942.6654803203655;5241.868953276123;5455.9454405455435;5508.421615730371;5556.888862222733;5557.165555766508;5170.164591570014;5801.3328467225965;5936.851578710481;6638.701258443381;6655.134832471845;1491.9850801492064;1504.729148753213;1658.1129095933618;1684.115839184438;1684.7978242610966;1107.396468924438;1163.743976325207;1169.6373724610733;1173.3957012138756;1173.6713720158368;6251.874962500725;6337.746490140435;6347.777827776005;6360.618362898241;6365;5946.721454887133;6288.465480434214;6309.64342666329;6825.146856642919;6835.496190838107;6104.511639068953;6298.716557754912;6324.588901721373;6357.315311442471;6359.618422894641;3129.358481511285;3403.1923662978143;3552.857885684544;3577.83899724513;3581.9433267185723;6119.724612392364;6938.791836244921;6987.336203060701;7026.613536255452;7033.437325013987;5769.307683078057;6625.269142191969;6925.6190909499455;6962.408195303355;7016.438684905195;6620.212542613371;6675.833104172338;6960.37356637905;7041.436685065182;7053.753118640926;5444;6797;6809.285025072494;6820.488463365168;6822.181338239443;6090.330063693079;6416;6649.833754155991;6692.297308782508;6709;6270.7491700331475;6829.89755153683;6978.581285122909;7045.823854403681;7195.388391986739;3924.862629807943;4756.500567440369;4888.804447822047;4903.8528844134735;4925.596205081534;6113.663748493876;6546.443552298108;6836.248012718696;6917.9308206917285;7029.824254393681;5851;6666.733330666719;6702.162229721285;6705.932940670531;6743.089682892943;6223.253209614694;6658.833529161616;6670.299618540177;6703.028060931235;6713.765018224534;6137.263528376445;6556.770513032021;6574.868502629825;6583.703733332108;6604.471642268511;5727.31272247333;6217.658028808459;6692.297308782702;6718.891382921764;6723.394894459522;6162.014077747539;6469.126667899774;6490.545661803629;6509.349065093453;6511.804645860631 18:12:31 INFO - PID 1637 | |5;yui.html;654.9246836613789;670.1556039390367;713.4934196720328;723.9927600723994;764.0220517737298;14803.33384997675;17410.56473588161;18291.268349266047;18468.261269549235;18500.2599896004;16329.591760206;16489.505314840575;16681.582960425996;16723.16384180792;16791.580210494743;984.8965858584853;1026.538057873956;1030.2582140858608;1032.4992378696327;1040.4069680282241;978.838491648877;1009.5406590001569;1017.959281628734;1019.4851599942019;1025.5128813813417;1068.8984546468073;1075.3118004477142;1077.9514921828536;1080.2330345454736;1081.4376524207407;1042.9634962776342;1062.521865160677;1067.7063807452973;1070.314998401024;1071.4374953149581;792.6472719639762;799.6841247707181;801.9518828870229;802.594689681707;803.2770506544099;16177.433789817418;16251.349946002145;16411;16840.32638694463;17099.05955172465;14607.78088328676;14721.682477997456;14837;14869;15491.225438728132;11469.31184128955;11533.308001519852;11898.821517677241;11932.650802986684;11950.641480755592;3006.969930300713;3071.877124915001;3098.5507101470384;3152.1489197916435;3165.1454107391105;95504;99281.5107773384;108645.82708345867;115774;118033.40983295029;9608.75978100539;11583.76832463346;11625.88374116265;11856.347900865363;12016.819747703792;24755.25734227976;25102;25321.493570128496;25417.364565886;25453.872730636227;7736.7292144774665;7813.257740514642;7896.368290536743;7956.960215199003;7975.72084977023;4101.446304748881;4103.097318589906;4112.712110152261;4145.958540414617;4156.044109854751;4057.69567282455;4094.447249621263;4155.252054630196;4186.848616630451;4196.895077623084;800.8486605208523;802.1256830055268;812.7033632724014;816.8529664660418;831.9168083191754;554.8862483191017;556.6854727079187;565.8557067947729;568.2470726287686;586.6186978464033;7476.289752473506;8067.919320806835;9269.980302166756;9750.366226195416;10221.540030698636;519.8804275016768;530.3132443485696;543.5651478816931;572.3075079154187;575.6718670357856;4514.503404625489;4757.3577567028005;4777.071646136955;4865.635077369211;5189.455107213764;84254.8936276598;94315.52842235746;99392;99530.5023474878;100943;60641.69679151576;74836.62581687166;89617;91667.5416622926;91885.54057229671;64579.677101614194;65925.34074659384;66147.33852661509;66221.00668489977;66426;10415.078546552339;10479.895201048197;10529.315594486334;10594.417307048187;10642.201834862262;33275.334493309514;33878;33932.66067339394;34059;34337.62649494092;49054.75472622614;59717.80564388601;63657;64707.676461619274;67535.66232168808;13721.313934303445;13772.380242888894;13805.516806912112;13828.114470551254;14144.363503642566;14382.640433989234;14397.42410303576;14850.257487125384;15180.316885739947;15219.847801521842;12763.553275635308;12958.740825183255;13742.701751579749;13816.032877698464;13845.376958036712;13611.31943402806;13611.43468501135;13756.793648095087;13763.31183440804;14022.57932261993;13351.866481335062;13588.93205533966;13777.242251676254;13825.861741382458;13921.234332111831;9644.469554174308;9687.757806054906;9871.457069861226;9892.653757118467;10677.9466102669;8267.669293228204;10388.065074143364;10859;11288.492017859051;11328.433578320886 18:12:31 INFO - PID 1637 | __end_tp_report 18:12:31 INFO - PID 1637 | __start_cc_report 18:12:31 INFO - PID 1637 | _x_x_mozilla_cycle_collect,435 18:12:31 INFO - PID 1637 | __end_cc_report 18:12:31 INFO - PID 1637 | __startTimestamp1490749951483__endTimestamp 18:12:31 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | ------- Summary: start ------- 18:12:31 INFO - PID 1637 | Number of tests: 6 18:12:31 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6145.84 Median:6386.17 stddev:719.83 (11.3%) stddev-sans-first:684.39 18:12:31 INFO - PID 1637 | Values: 3217.4 3534.0 3570.2 3586.9 3590.6 5421.0 6052.8 6125.8 6260.5 6321.4 6116.4 6146.9 6246.8 6286.5 6290.0 4148.5 5111.7 5647.0 5666.8 5783.1 5584.9 5663.7 5761.2 5817.4 5847.1 5473.5 5533.1 5551.5 5679.6 5717.3 5602.3 5610.8 5655.1 5678.7 5690.5 5040.8 5550.0 5588.8 5616.3 5616.7 6081.3 6103.5 6154.4 6169.1 6319.1 6410.8 6608.6 6672.3 6684.3 6694.2 6772.4 6772.7 6792.3 6803.0 6814.1 6324.8 6392.1 6393.8 6426.8 6433.9 6551.2 6612.5 6631.0 6674.1 6696.6 5740.8 6070.0 6304.9 6521.7 6590.2 6450.0 6467.6 6591.0 6662.6 6770.3 6657.2 6657.7 6659.8 6669.0 6697.3 6357.1 6415.3 6607.9 6683.3 6745.1 5332.5 5334.5 5383.7 5383.8 5403.8 4703.8 4725.6 4736.0 4746.7 4758.0 6340.7 6375.1 6391.4 6402.9 6404.3 6480.2 6550.9 6739.9 6741.8 6768.1 6342.9 6375.6 6380.0 6396.4 6412.0 5150.2 5731.1 5882.5 5953.1 5966.5 6658.4 6796.4 6912.7 6936.1 7004.4 5968.4 6139.0 6786.4 6827.6 6853.1 6973.9 7018.1 7018.4 7029.8 7053.0 6362.9 6398.5 6574.8 6628.4 6670.8 6660.0 6670.3 6677.5 6739.4 6746.2 6234.8 6467.0 6477.0 6481.9 6488.5 5763.8 6125.2 6301.5 6456.8 6481.9 5769.6 6347.6 6414.7 6429.4 6439.2 6541.2 6548.8 6552.4 6555.8 6584.4 6168.1 6214.8 6332.0 6386.2 6391.5 18:12:31 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:53403.19 Median:35955.78 stddev:110130.65 (306.3%) stddev-sans-first:110368.58 18:12:31 INFO - PID 1637 | Values: 726.4 802.1 828.7 1213.9 1268.0 37286.6 38485.6 38523.6 38530.0 38541.0 38039.6 38110.9 38218.4 38229.6 38379.0 7890.4 8080.4 8330.5 8547.9 8555.5 7634.0 8501.9 8550.5 8635.7 8641.1 8326.2 8574.8 8664.8 8664.9 8671.8 8591.7 8665.7 8712.0 8726.7 8738.8 8460.4 8515.8 8526.4 8535.8 8537.6 37943.4 38150.0 38154.0 38158.0 38358.4 3013.4 3034.3 3039.5 3046.9 3057.3 23695.6 27938.9 28093.7 28151.7 28494.0 2769.7 2822.1 2843.0 2853.4 2856.7 221434.0 237663.0 269313.0 350559.0 350695.0 46681.1 46746.0 46912.0 46916.5 47014.3 48835.5 48896.5 48947.8 48984.8 49257.6 13758.0 14026.4 14107.9 14276.2 14299.4 11587.8 11595.7 11621.2 11640.8 11649.0 9724.8 13616.9 13747.9 13833.4 13926.9 5937.7 5970.0 5995.0 6003.2 6021.0 948.4 954.0 966.7 994.6 1005.6 27617.3 28470.4 29103.6 29430.0 30114.7 1039.1 1040.2 1066.9 1072.7 1078.3 11770.6 12356.4 12433.8 12434.4 12457.3 457366.0 461271.0 461502.0 462558.0 462601.0 45350.8 52618.5 53019.7 53380.0 53486.5 1061.6 1267.8 1269.7 1269.9 1434.6 20458.6 22765.7 22966.1 23203.3 23830.6 31207.0 38425.7 38494.4 38803.2 39633.0 450442.0 465533.0 466924.0 468660.0 471577.0 31919.0 32217.5 32537.0 33620.7 33704.8 41833.6 42887.8 43377.6 43871.1 43972.0 28539.0 29247.0 29442.0 30341.1 30504.0 28163.7 29414.7 30269.4 30352.5 30592.7 35217.8 35401.8 35805.8 36105.7 36190.6 26372.9 28907.9 29670.4 30090.7 30691.9 21231.7 21831.6 23615.4 23620.5 24142.6 18:12:31 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:36312.90 Median:22968.83 stddev:97917.62 (426.3%) stddev-sans-first:98155.16 18:12:31 INFO - PID 1637 | Values: 393.5 405.5 406.7 408.9 422.4 21002.0 21573.8 21920.5 22159.7 22853.7 18021.5 22820.9 22831.7 23084.0 23579.8 6060.2 6328.3 6329.1 6332.7 6338.7 6054.6 6149.0 6194.3 6222.8 6263.5 6219.2 6219.2 6226.5 6233.0 6236.0 5053.7 6114.6 6159.2 6167.4 6188.0 5396.2 5399.2 5417.9 5430.4 5484.2 26799.3 26908.8 26962.7 27248.6 27333.8 1979.5 2001.0 2005.8 2007.1 2032.5 16068.0 16794.6 17006.4 17366.4 17394.4 1555.2 1644.0 1663.8 1673.3 1674.1 145328.0 153482.0 154726.0 155361.4 156262.0 12483.5 12665.9 12681.9 12736.3 12814.7 15928.8 16095.8 16128.2 16129.8 16202.1 7803.1 8892.8 8991.1 9102.0 9103.8 8444.2 8558.8 8641.7 8743.1 8790.1 6428.0 6569.6 8017.9 8023.3 8052.5 2127.9 2141.0 2142.3 2144.8 2673.5 889.5 903.3 906.7 933.5 950.3 20264.7 20915.9 20942.9 20949.0 20982.8 935.7 964.3 969.4 972.6 972.9 10170.1 10409.9 10785.5 10871.8 11144.0 407943.0 578122.5 579974.0 588678.0 702681.0 104990.0 114628.0 114865.4 146876.0 149731.3 1070.5 1129.8 1139.3 1201.7 1208.7 20208.7 20876.6 21095.2 21360.1 21386.9 48891.8 60921.0 61424.0 64980.4 66445.3 81898.6 82804.2 82990.0 84379.6 90217.0 6437.7 7133.4 7676.3 8708.5 8921.7 13733.0 14080.5 14146.2 14510.8 14617.2 11348.7 11674.9 11802.7 11838.7 12118.4 9771.0 11615.6 11645.4 11796.6 11872.3 12045.8 12175.5 12441.4 12499.5 12582.9 8507.6 8579.4 8774.7 8989.6 9039.2 11436.0 11776.8 12118.0 12149.8 12162.7 18:12:31 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6742.38 Median:2486.79 stddev:4166.36 (167.5%) stddev-sans-first:4158.61 18:12:31 INFO - PID 1637 | Values: 1386.7 1421.0 1431.9 1437.8 1470.8 10671.2 10874.9 11099.2 11136.4 11164.0 10813.7 10980.9 10992.8 11011.1 11013.0 2424.7 2470.7 2479.9 2487.8 2490.9 2387.1 2438.5 2457.8 2467.6 2469.9 2427.9 2441.0 2450.1 2484.4 2485.8 2428.0 2429.2 2447.2 2447.9 2475.5 2077.8 2334.8 2437.4 2447.8 2463.4 11074.8 11125.7 11180.8 11186.2 11194.8 6274.2 6490.8 6496.1 6497.6 6516.6 4051.1 4104.5 4110.8 4124.6 4153.9 1099.8 1174.3 1183.5 1219.9 1227.4 13866.9 13985.0 14938.7 15250.2 15377.6 4696.4 5101.8 5227.1 5409.9 5440.9 9463.5 11441.1 11455.6 11547.8 11573.6 6249.7 6294.8 6318.6 6325.4 6349.0 5195.6 5911.4 5938.8 5999.3 6058.1 4247.9 4323.6 4348.5 4362.3 4362.9 1022.6 1045.7 1203.7 1237.6 1237.9 754.9 757.3 779.8 785.2 797.4 5092.6 5928.3 5975.0 6064.8 6152.4 565.0 584.0 594.5 595.9 596.6 3925.2 4212.1 4221.3 4301.1 4335.9 13587.7 14173.6 14178.3 14215.3 14215.5 13297.0 13767.0 14134.0 14138.6 14144.7 8849.4 10061.7 10345.0 10356.7 10371.6 5671.2 5673.1 5814.4 6725.5 6729.8 10941.7 11859.3 12101.0 12140.9 12148.8 12196.8 12341.7 12345.4 12361.8 12566.0 8869.1 9141.1 9201.3 9208.1 9230.1 10090.1 10246.5 10314.9 10337.7 10339.0 8568.7 8617.4 8708.9 8880.0 8905.9 8306.0 8414.4 8425.3 8476.1 8624.6 7713.0 7731.3 7741.7 7817.0 7852.1 5827.9 5890.2 5983.1 6033.5 6063.7 5606.5 5682.7 5692.7 5755.7 5804.5 18:12:31 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5379.62 Median:6046.94 stddev:1653.15 (27.3%) stddev-sans-first:1614.96 18:12:31 INFO - PID 1637 | Values: 399.0 400.7 404.6 404.6 405.7 5219.8 6237.5 6309.0 6315.9 6365.8 4920.8 5121.9 5523.4 6149.9 6203.2 3954.5 4159.5 4399.9 4796.8 4800.4 3973.7 4415.5 4432.0 4829.0 4845.9 3911.2 3938.9 4722.6 4755.4 4804.9 3680.2 4302.2 4494.8 4597.3 4654.3 3853.9 4232.0 4234.2 4265.2 4266.0 5313.7 5708.5 5805.4 6003.5 6128.0 5258.9 5517.0 5528.9 5559.5 5581.9 6331.1 6635.6 6672.8 6688.6 6698.8 3975.3 4685.6 4691.6 4711.2 4750.4 6703.8 6987.3 7006.7 7045.8 7113.6 4027.6 4694.7 4756.1 4811.0 4835.4 4290.6 4919.9 4923.7 4942.5 4942.7 5241.9 5455.9 5508.4 5556.9 5557.2 5170.2 5801.3 5936.9 6638.7 6655.1 1492.0 1504.7 1658.1 1684.1 1684.8 1107.4 1163.7 1169.6 1173.4 1173.7 6251.9 6337.7 6347.8 6360.6 6365.0 5946.7 6288.5 6309.6 6825.1 6835.5 6104.5 6298.7 6324.6 6357.3 6359.6 3129.4 3403.2 3552.9 3577.8 3581.9 6119.7 6938.8 6987.3 7026.6 7033.4 5769.3 6625.3 6925.6 6962.4 7016.4 6620.2 6675.8 6960.4 7041.4 7053.8 5444.0 6797.0 6809.3 6820.5 6822.2 6090.3 6416.0 6649.8 6692.3 6709.0 6270.7 6829.9 6978.6 7045.8 7195.4 3924.9 4756.5 4888.8 4903.9 4925.6 6113.7 6546.4 6836.2 6917.9 7029.8 5851.0 6666.7 6702.2 6705.9 6743.1 6223.3 6658.8 6670.3 6703.0 6713.8 6137.3 6556.8 6574.9 6583.7 6604.5 5727.3 6217.7 6692.3 6718.9 6723.4 6162.0 6469.1 6490.5 6509.3 6511.8 18:12:31 INFO - PID 1637 | 18:12:31 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:19457.94 Median:25435.62 stddev:27334.61 (107.5%) stddev-sans-first:27374.82 18:12:31 INFO - PID 1637 | Values: 654.9 670.2 713.5 724.0 764.0 14803.3 17410.6 18291.3 18468.3 18500.3 16329.6 16489.5 16681.6 16723.2 16791.6 984.9 1026.5 1030.3 1032.5 1040.4 978.8 1009.5 1018.0 1019.5 1025.5 1068.9 1075.3 1078.0 1080.2 1081.4 1043.0 1062.5 1067.7 1070.3 1071.4 792.6 799.7 802.0 802.6 803.3 16177.4 16251.3 16411.0 16840.3 17099.1 14607.8 14721.7 14837.0 14869.0 15491.2 11469.3 11533.3 11898.8 11932.7 11950.6 3007.0 3071.9 3098.6 3152.1 3165.1 95504.0 99281.5 108645.8 115774.0 118033.4 9608.8 11583.8 11625.9 11856.3 12016.8 24755.3 25102.0 25321.5 25417.4 25453.9 7736.7 7813.3 7896.4 7957.0 7975.7 4101.4 4103.1 4112.7 4146.0 4156.0 4057.7 4094.4 4155.3 4186.8 4196.9 800.8 802.1 812.7 816.9 831.9 554.9 556.7 565.9 568.2 586.6 7476.3 8067.9 9270.0 9750.4 10221.5 519.9 530.3 543.6 572.3 575.7 4514.5 4757.4 4777.1 4865.6 5189.5 84254.9 94315.5 99392.0 99530.5 100943.0 60641.7 74836.6 89617.0 91667.5 91885.5 64579.7 65925.3 66147.3 66221.0 66426.0 10415.1 10479.9 10529.3 10594.4 10642.2 33275.3 33878.0 33932.7 34059.0 34337.6 49054.8 59717.8 63657.0 64707.7 67535.7 13721.3 13772.4 13805.5 13828.1 14144.4 14382.6 14397.4 14850.3 15180.3 15219.8 12763.6 12958.7 13742.7 13816.0 13845.4 13611.3 13611.4 13756.8 13763.3 14022.6 13351.9 13588.9 13777.2 13825.9 13921.2 9644.5 9687.8 9871.5 9892.7 10677.9 8267.7 10388.1 10859.0 11288.5 11328.4 18:12:31 INFO - PID 1637 | -------- Summary: end -------- 18:12:31 INFO - PID 1637 | 18:12:32 INFO - TEST-INFO | 1637: exit 0 18:12:32 INFO - TEST-OK | dromaeo_css | took 1117846ms 18:12:32 INFO - TEST-START | kraken 18:12:32 INFO - operating with platform_type : mac_ 18:12:32 INFO - Initialising browser for kraken test... 18:12:32 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpbpccCy/profile http://localhost:49236/getInfo.html) 18:12:35 INFO - PID 1649 | 2017-03-28 18:12:35.029 plugin-container[1650:10000] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 18:12:35 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:12:35 INFO - PID 1649 | 2017-03-28 18:12:35.032 plugin-container[1650:10000] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1b, name = 'com.apple.CFPasteboardClient' 18:12:35 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:12:35 INFO - PID 1649 | 2017-03-28 18:12:35.032 plugin-container[1650:10000] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:12:35 INFO - PID 1649 | __metrics Screen width/height:1600/1200 18:12:35 INFO - PID 1649 | colorDepth:24 18:12:35 INFO - PID 1649 | Browser inner width/height: 1024/689 18:12:35 INFO - PID 1649 | __metrics 18:12:35 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 18:12:35 ERROR - PID 1649 | 1490749955235 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/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpbpccCy/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpbpccCy/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpbpccCy/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpbpccCy/profile/extensions/talos-powers@TalosPowersService.js:55:9 18:12:36 INFO - TEST-INFO | 1649: exit 0 18:12:36 INFO - Browser initialized. 18:12:36 INFO - Running cycle 1/1 for kraken test... 18:12:36 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpbpccCy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:12:38 INFO - PID 1651 | 2017-03-28 18:12:38.674 plugin-container[1652:10118] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x904b, name = 'com.apple.tsm.portname' 18:12:38 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:12:38 INFO - PID 1651 | 2017-03-28 18:12:38.675 plugin-container[1652:10118] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.CFPasteboardClient' 18:12:38 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:12:38 INFO - PID 1651 | 2017-03-28 18:12:38.675 plugin-container[1652:10118] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:12:48 INFO - PID 1651 | RSS: Main: 229670912 18:12:48 INFO - PID 1651 | 18:12:51 INFO - PID 1651 | 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) 18:12:52 INFO - PID 1651 | RSS: Main: 244850688 18:12:52 INFO - PID 1651 | 18:12:54 INFO - PID 1651 | 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) 18:12:55 INFO - PID 1651 | RSS: Main: 253427712 18:12:55 INFO - PID 1651 | 18:12:59 INFO - PID 1651 | 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) 18:13:00 INFO - PID 1651 | RSS: Main: 255680512 18:13:00 INFO - PID 1651 | 18:13:02 INFO - PID 1651 | 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) 18:13:02 INFO - PID 1651 | RSS: Main: 257630208 18:13:02 INFO - PID 1651 | 18:13:04 INFO - PID 1651 | 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) 18:13:05 INFO - PID 1651 | RSS: Main: 258363392 18:13:05 INFO - PID 1651 | 18:13:09 INFO - PID 1651 | 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) 18:13:09 INFO - PID 1651 | RSS: Main: 261099520 18:13:09 INFO - PID 1651 | 18:13:12 INFO - PID 1651 | 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) 18:13:13 INFO - PID 1651 | RSS: Main: 263299072 18:13:13 INFO - PID 1651 | 18:13:16 INFO - PID 1651 | 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) 18:13:17 INFO - PID 1651 | RSS: Main: 263335936 18:13:17 INFO - PID 1651 | 18:13:19 INFO - PID 1651 | 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) 18:13:19 INFO - PID 1651 | RSS: Main: 263413760 18:13:19 INFO - PID 1651 | 18:13:21 INFO - PID 1651 | 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) 18:13:22 INFO - PID 1651 | RSS: Main: 263376896 18:13:22 INFO - PID 1651 | 18:13:24 INFO - PID 1651 | 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) 18:13:25 INFO - PID 1651 | RSS: Main: 263401472 18:13:25 INFO - PID 1651 | 18:13:28 INFO - PID 1651 | 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) 18:13:29 INFO - PID 1651 | RSS: Main: 264052736 18:13:29 INFO - PID 1651 | 18:13:32 INFO - PID 1651 | 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) 18:13:33 INFO - PID 1651 | RSS: Main: 261758976 18:13:33 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | 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) 18:13:35 INFO - PID 1651 | RSS: Main: 264982528 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | __start_tp_report 18:13:35 INFO - PID 1651 | _x_x_mozilla_page_load 18:13:35 INFO - PID 1651 | _x_x_mozilla_page_load_details 18:13:35 INFO - PID 1651 | |i|pagename|runs| 18:13:35 INFO - PID 1651 | |0;ai-astar;114;124;120;126;126;125;125;126;126;126 18:13:35 INFO - PID 1651 | |1;audio-beat-detection;153;116;150;181;144;117;160;101;120;158 18:13:35 INFO - PID 1651 | |2;audio-dft;246;244;254;310;254;304;279;241;300;214 18:13:35 INFO - PID 1651 | |3;audio-fft;83;50;77;120;91;98;58;58;77;64 18:13:35 INFO - PID 1651 | |4;audio-oscillator;87;72;88;76;73;73;89;72;73;88 18:13:35 INFO - PID 1651 | |5;imaging-gaussian-blur;129;143;109;99;141;96;142;143;94;126 18:13:35 INFO - PID 1651 | |6;imaging-darkroom;103;102;101;110;102;103;100;102;101;101 18:13:35 INFO - PID 1651 | |7;imaging-desaturate;120;103;102;104;103;103;104;132;104;102 18:13:35 INFO - PID 1651 | |8;json-parse-financial;69;68;67;68;70;68;70;70;70;69 18:13:35 INFO - PID 1651 | |9;json-stringify-tinderbox;68;98;90;105;82;65;61;62;61;84 18:13:35 INFO - PID 1651 | |10;stanford-crypto-aes;86;77;95;112;116;86;91;91;88;86 18:13:35 INFO - PID 1651 | |11;stanford-crypto-ccm;148;152;152;147;178;167;153;152;227;171 18:13:35 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;204;239;178;238;238;257;199;237;250;193 18:13:35 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;73;72;71;70;73;73;74;73;71;72 18:13:35 INFO - PID 1651 | __end_tp_report 18:13:35 INFO - PID 1651 | __start_cc_report 18:13:35 INFO - PID 1651 | _x_x_mozilla_cycle_collect,1032 18:13:35 INFO - PID 1651 | __end_cc_report 18:13:35 INFO - PID 1651 | __startTimestamp1490750015468__endTimestamp 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | ------- Summary: start ------- 18:13:35 INFO - PID 1651 | Number of tests: 14 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:123.80 Median:126.00 stddev:3.91 (3.1%) stddev-sans-first:1.96 18:13:35 INFO - PID 1651 | Values: 114.0 124.0 120.0 126.0 126.0 125.0 125.0 126.0 126.0 126.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:151.50 stddev:25.20 (16.6%) stddev-sans-first:26.29 18:13:35 INFO - PID 1651 | Values: 153.0 116.0 150.0 181.0 144.0 117.0 160.0 101.0 120.0 158.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:264.60 Median:266.50 stddev:31.95 (12.0%) stddev-sans-first:33.17 18:13:35 INFO - PID 1651 | Values: 246.0 244.0 254.0 310.0 254.0 304.0 279.0 241.0 300.0 214.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:77.60 Median:77.00 stddev:21.44 (27.8%) stddev-sans-first:22.66 18:13:35 INFO - PID 1651 | Values: 83.0 50.0 77.0 120.0 91.0 98.0 58.0 58.0 77.0 64.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:79.10 Median:81.50 stddev:7.75 (9.5%) stddev-sans-first:7.68 18:13:35 INFO - PID 1651 | Values: 87.0 72.0 88.0 76.0 73.0 73.0 89.0 72.0 73.0 88.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:122.20 Median:143.00 stddev:20.72 (14.5%) stddev-sans-first:21.84 18:13:35 INFO - PID 1651 | Values: 129.0 143.0 109.0 99.0 141.0 96.0 142.0 143.0 94.0 126.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:102.00 stddev:2.80 (2.7%) stddev-sans-first:2.96 18:13:35 INFO - PID 1651 | Values: 103.0 102.0 101.0 110.0 102.0 103.0 100.0 102.0 101.0 101.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:107.70 Median:104.00 stddev:10.08 (9.7%) stddev-sans-first:9.66 18:13:35 INFO - PID 1651 | Values: 120.0 103.0 102.0 104.0 103.0 103.0 104.0 132.0 104.0 102.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:69.50 stddev:1.10 (1.6%) stddev-sans-first:1.17 18:13:35 INFO - PID 1651 | Values: 69.0 68.0 67.0 68.0 70.0 68.0 70.0 70.0 70.0 69.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:77.60 Median:75.00 stddev:16.42 (21.9%) stddev-sans-first:17.04 18:13:35 INFO - PID 1651 | Values: 68.0 98.0 90.0 105.0 82.0 65.0 61.0 62.0 61.0 84.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:87.00 stddev:12.15 (14.0%) stddev-sans-first:12.64 18:13:35 INFO - PID 1651 | Values: 86.0 77.0 95.0 112.0 116.0 86.0 91.0 91.0 88.0 86.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:164.70 Median:160.00 stddev:24.30 (15.2%) stddev-sans-first:25.02 18:13:35 INFO - PID 1651 | Values: 148.0 152.0 152.0 147.0 178.0 167.0 153.0 152.0 227.0 171.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:223.30 Median:238.00 stddev:27.18 (11.4%) stddev-sans-first:27.92 18:13:35 INFO - PID 1651 | Values: 204.0 239.0 178.0 238.0 238.0 257.0 199.0 237.0 250.0 193.0 18:13:35 INFO - PID 1651 | 18:13:35 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:73.00 stddev:1.23 (1.7%) stddev-sans-first:1.27 18:13:35 INFO - PID 1651 | Values: 73.0 72.0 71.0 70.0 73.0 73.0 74.0 73.0 71.0 72.0 18:13:35 INFO - PID 1651 | -------- Summary: end -------- 18:13:35 INFO - PID 1651 | 18:13:36 INFO - TEST-INFO | 1651: exit 0 18:13:36 INFO - TEST-OK | kraken | took 64238ms 18:13:36 INFO - SUITE-END | took 1182s 18:13:36 INFO - Completed test suite (00:19:42) 18:13:36 INFO - javascript benchmark 18:13:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7695.696808751658, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.356528694261, 3534.0281422608764, 3570.1967057412085, 3586.92826143477, 3590.605033446322, 5420.972895135524, 6052.818415447538, 6125.845966769335, 6260.46786023188, 6321.43107120359, 6116.366205733227, 6146.862830376373, 6246.781362652308, 6286.497080233582, 6290.0250461178475, 4148.543660197376, 5111.744412779365, 5646.971765141178, 5666.773329066832, 5783.074708046714, 5584.944150558483, 5663.7168141592965, 5761.193432919395, 5817.360090390053, 5847.093700476433, 5473.480019398151, 5533.114701647757, 5551.472610102034, 5679.602427830033, 5717.342505611867, 5602.299712535933, 5610.775568977236, 5655.123455864347, 5678.716064196774, 5690.5447564194765, 5040.7731652075745, 5550.028744969654, 5588.832335029955, 5616.32604087508, 5616.66300022, 6081.270247570275, 6103.481204097613, 6154.44609985099, 6169.105479705418, 6319.115323854664, 6410.839729006765, 6608.570442921195, 6672.332766723337, 6684.2981486944145, 6694.196696396415, 6772.390484856337, 6772.69522871472, 6792.320767923217, 6803.0, 6814.080099186596, 6324.810255692337, 6392.073149393358, 6393.776217832354, 6426.807195784134, 6433.871322573569, 6551.246606640202, 6612.537122401482, 6631.0, 6674.098996635392, 6696.598204107671, 5740.823131258082, 6070.0, 6304.936950630527, 6521.673916304166, 6590.242122155918, 6450.0, 6467.579607325509, 6591.0, 6662.600243985376, 6770.255271920085, 6657.234418041987, 6657.73369065232, 6659.833504162338, 6669.0, 6697.296783837724, 6357.11000459936, 6415.26224484181, 6607.933920660828, 6683.331666833278, 6745.0556922030955, 5332.520073193354, 5334.466553344712, 5383.67697938125, 5383.757730902119, 5403.756830942617, 4703.764811759398, 4725.64557658177, 4736.005438857878, 4746.667733258639, 4758.000819827875, 6340.746370145235, 6375.10748495211, 6391.392817682313, 6402.935970640234, 6404.263509696351, 6480.157579514633, 6550.868982620321, 6739.932600674028, 6741.831454213684, 6768.052472653832, 6342.904856427157, 6375.6174629522375, 6380.042993551005, 6396.42432181106, 6411.967940160363, 5150.175971844487, 5731.111677689882, 5882.470577647947, 5953.136795164677, 5966.492848107872, 6658.3674550917585, 6796.388325050769, 6912.723491060502, 6936.132983377078, 7004.4046256068805, 5968.373320801254, 6139.017757159012, 6786.423154031962, 6827.556208846409, 6853.074834897225, 6973.860522789414, 7018.052562904147, 7018.438524918097, 7029.789106326818, 7052.9647351762915, 6362.936370636235, 6398.5201109916015, 6574.80275591733, 6628.370304821035, 6670.799876003728, 6659.966700166468, 6670.332966703291, 6677.499187561049, 6739.359760822714, 6746.156730408699, 6234.781782637586, 6467.029945508211, 6476.967615162082, 6481.935180648133, 6488.513361497812, 5763.84723055399, 6125.235451818428, 6301.495880329654, 6456.838579035561, 6481.902771458337, 5769.624974376652, 6347.5556711029785, 6414.743410263537, 6429.38920802523, 6439.162908821823, 6541.2477565078725, 6548.770793022221, 6552.410283074546, 6555.80332590023, 6584.440322572635, 6168.136460895568, 6214.819184354958, 6331.96834015827, 6386.16979792624, 6391.520635952415], "value": 6098.712902070485, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.3608024938054, 802.0936341933615, 828.6892415344244, 1213.8968187704045, 1268.0426278159987, 37286.62713372865, 38485.61514384859, 38523.614763852354, 38530.0, 38541.0, 38039.61960380402, 38110.856674299815, 38218.426723599165, 38229.61770382295, 38379.0, 7890.4476686631815, 8080.39397045221, 8330.458520196198, 8547.914520854774, 8555.48667079974, 7633.969414129105, 8501.872471912926, 8550.52972086535, 8635.740927772145, 8641.135886411372, 8326.209010144026, 8574.78563035926, 8664.826703465927, 8664.8700269496, 8671.783205419884, 8591.742247732547, 8665.740027799176, 8712.0, 8726.650933962634, 8738.825223495494, 8460.36547258952, 8515.787105322355, 8526.403151779377, 8535.829283414298, 8537.57312134397, 37943.4308485373, 38150.0, 38154.0, 38158.0, 38358.424623630664, 3013.4123845850067, 3034.263934066369, 3039.4680930837017, 3046.8781248750024, 3057.327387974642, 23695.644565331535, 27938.860305698545, 28093.71906280952, 28151.71848281532, 28494.002709905053, 2769.695333513312, 2822.068717323278, 2843.0, 2853.4007858349773, 2856.685764565896, 221434.0, 237663.0, 269313.0, 350559.0, 350695.0, 46681.066378672236, 46746.0, 46912.0, 46916.53083469121, 47014.29478557824, 48835.51164488381, 48896.51103488991, 48947.755261223465, 48984.755076225105, 49257.56681898647, 13758.0, 14026.438942442188, 14107.85892141086, 14276.214808185441, 14299.428022879176, 11587.826182607268, 11595.652130436101, 11621.24461909973, 11640.767184656259, 11649.0, 9724.756881077888, 13616.910647148205, 13747.862521374858, 13833.446662133401, 13926.860731392759, 5937.732802023833, 5970.014947533695, 5995.025743521792, 6003.249593800775, 6021.0, 948.3977674176947, 953.9618415263451, 966.7099870039101, 994.6419289055934, 1005.6228914157191, 27617.309567260978, 28470.430591388056, 29103.56344654832, 29429.96995105161, 30114.69885301119, 1039.0856046679025, 1040.1990467340106, 1066.8666416697913, 1072.7103682005816, 1078.2506158219962, 11770.646880593597, 12356.38218089092, 12433.813492797615, 12434.440450179764, 12457.252564846138, 457366.0, 461271.0, 461502.0, 462558.0, 462601.0, 45350.77324613356, 52618.47381526212, 53019.734901325246, 53380.0, 53486.465135348924, 1061.6496556136456, 1267.7908145155945, 1269.707967167538, 1269.8730126987227, 1434.6126545832776, 20458.590828183056, 22765.658515121955, 22966.08135674621, 23203.303900883002, 23830.642540362256, 31207.0, 38425.655102071294, 38494.42258366071, 38803.22393552057, 39633.0, 450442.0, 465533.0, 466924.0, 468660.0, 471577.0, 31919.0, 32217.51673724849, 32537.0, 33620.66379336175, 33704.83147584246, 41833.58166418419, 42887.78556107199, 43377.56622433735, 43871.12257754891, 43972.0, 28539.0, 29247.0, 29441.96953106631, 30341.089767307018, 30504.03447551804, 28163.71836281611, 29414.705852941195, 30269.394612108073, 30352.54471182978, 30592.694073058985, 35217.82391088028, 35401.822990885914, 35805.82097089498, 36105.7362992294, 36190.63809361873, 26372.945082196497, 28907.855460722563, 29670.40659186761, 30090.69909300879, 30691.925782597504, 21231.726096434264, 21831.563368732855, 23615.40961475977, 23620.52758944846, 24142.637860431758], "value": 17533.263417038328, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.5375933278397, 405.47693475416696, 406.66247014977574, 408.8752930356241, 422.442376063596, 21002.0, 21573.801955256982, 21920.45198870029, 22159.667604985938, 22853.657195142045, 18021.4593562193, 22820.8858955705, 22831.657525137136, 23084.0, 23579.764202357972, 6060.2424696912885, 6328.335524769902, 6329.082283068952, 6332.746690132389, 6338.683065846712, 6054.60645058072, 6149.0, 6194.349593292707, 6222.81331560054, 6263.530235232353, 6219.160413344209, 6219.222597175353, 6226.533010024244, 6233.0338797486465, 6235.968820155893, 5053.722045287508, 6114.602550834204, 6159.23009623797, 6167.383261673842, 6188.0, 5396.192275587683, 5399.217113518518, 5417.918731219035, 5430.429804870471, 5484.204790305424, 26799.330016749544, 26908.789104490148, 26962.730372696216, 27248.59127113095, 27333.76998035093, 1979.4556496963307, 2000.9699854502194, 2005.81947624713, 2007.0968064370945, 2032.542677897476, 16068.0, 16794.580135496588, 17006.404775832787, 17366.39217627377, 17394.391196308068, 1555.237933412631, 1643.9506814795575, 1663.808662003873, 1673.3306677329044, 1674.148244732202, 145328.0, 153482.0, 154726.0, 155361.44638553582, 156262.0, 12483.50065997368, 12665.936670316589, 12681.93659031699, 12736.299503527394, 12814.743705126031, 15928.76106858398, 16095.758563621555, 16128.193590320438, 16129.758053629204, 16202.108884011492, 7803.099180739141, 8892.777680557909, 8991.100889911088, 9102.0, 9103.817923641622, 8444.240018398374, 8558.78603034929, 8641.697540586048, 8743.081976392514, 8790.120987901159, 6428.0, 6569.605823650596, 8017.919820801834, 8023.277904988462, 8052.516848989079, 2127.8936053197276, 2140.978590214109, 2142.32516757221, 2144.7855214478427, 2673.46530693862, 889.5196593839254, 903.2683526343551, 906.7098528470958, 933.4725879877848, 950.2587981374539, 20264.696029559567, 20915.8954205228, 20942.89528552378, 20949.0, 20982.790172098084, 935.7473482159779, 964.2719746591279, 969.4038166527534, 972.644984580623, 972.9318947673595, 10170.08469237772, 10409.895901040893, 10785.46072696362, 10871.83692244617, 11143.997040266415, 407943.0, 578122.5003999424, 579974.0, 588678.0, 702681.0, 104990.0, 114628.0, 114865.42567287278, 146876.0, 149731.2513437426, 1070.5236169904474, 1129.7966366054025, 1139.3448766959032, 1201.6575276046285, 1208.6555331730415, 20208.686435381653, 20876.58246835085, 21095.15619375208, 21360.087179948387, 21386.930653466952, 48891.75554122207, 60921.0, 61424.0, 64980.35019649932, 66445.33554664391, 81898.59050704708, 82804.17195828205, 82990.0, 84379.5781021091, 90217.0, 6437.742490300336, 7133.357997780225, 7676.347510461674, 8708.521031343336, 8921.687740929035, 13732.970027247797, 14080.507182248572, 14146.151230926178, 14510.782338265146, 14617.222727681707, 11348.694900086595, 11674.941625292158, 11802.704932376759, 11838.704032399259, 12118.394080296126, 9771.0, 11615.5934542294, 11645.41772911334, 11796.646100616996, 11872.347020913932, 12045.819312710491, 12175.512979480722, 12441.44013519412, 12499.500019999097, 12582.874171258536, 8507.617157228064, 8579.442336248125, 8774.736757897273, 8989.617751296042, 9039.231665308522, 11436.027937625395, 11776.76446471083, 12118.03055755555, 12149.757004859677, 12162.695932601755], "value": 10314.317161828129, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.6949271160345, 1421.0, 1431.899767016308, 1437.820986790832, 1470.7867359232912, 10671.1996600255, 10874.891251087487, 11099.16756243282, 11136.443177841105, 11163.99524042836, 10813.729656758585, 10980.890191098086, 10992.835107473375, 11011.119110471163, 11013.0, 2424.6726691896542, 2470.72821989581, 2479.913203037894, 2487.825852190347, 2490.86300253486, 2387.1167667962877, 2438.5244877248942, 2457.8156638252112, 2467.629855521668, 2469.900894102125, 2427.9150229741963, 2440.9633855492184, 2450.0567281596536, 2484.428581426273, 2485.763852434016, 2428.0287884846116, 2429.2226487524, 2447.21689059501, 2447.865367404792, 2475.4553998120386, 2077.792220777925, 2334.7781960713705, 2437.439388940536, 2447.7919376852988, 2463.3718401807423, 11074.833877491845, 11125.666230013032, 11180.832287515694, 11186.216964812467, 11194.776104477865, 6274.1843560336865, 6490.837729056764, 6496.058071579641, 6497.610143391412, 6516.609003459807, 4051.0682543015273, 4104.527979282361, 4110.753354798691, 4124.649404800595, 4153.875383738493, 1099.8295264234057, 1174.2602160638744, 1183.4970137691446, 1219.9390030498528, 1227.3679055286534, 13866.93066534671, 13985.021048526607, 14938.70122597542, 15250.161241131733, 15377.615559610987, 4696.412948381452, 5101.821436249787, 5227.059129356752, 5409.864753381197, 5440.939016891668, 9463.479508626953, 11441.084713222923, 11455.59905403307, 11547.76904461906, 11573.594924177612, 6249.6562689052535, 6294.8111556653375, 6318.62088274705, 6325.3674632537295, 6349.0, 5195.584353251731, 5911.379305172981, 5938.762449501972, 5999.340072592011, 6058.091286307089, 4247.851325203603, 4323.610875021263, 4348.456298014223, 4362.280223763107, 4362.890927726832, 1022.5551884930079, 1045.72288343589, 1203.6870413692327, 1237.6287113865803, 1237.8762123787549, 754.8943147959291, 757.3221966340095, 779.8479296537121, 785.2383188307333, 797.401948538596, 5092.567131793765, 5928.347881733006, 5975.0, 6064.787732429344, 6152.3540028297275, 565.0422533805242, 583.9708014599338, 594.5243804956104, 595.8808238352347, 596.6062398816847, 3925.175713100281, 4212.136512015029, 4221.303484925014, 4301.07526881722, 4335.891602709958, 13587.660308492366, 14173.645658858612, 14178.291085445686, 14215.289235538181, 14215.50737172582, 13297.0, 13767.0, 14134.010619256758, 14138.575842724735, 14144.71710565783, 8849.4247873888, 10061.69814905554, 10345.017223363768, 10356.741081473023, 10371.585136594602, 5671.206031155559, 5673.06394444912, 5814.418558144152, 6725.495587830835, 6729.803343412036, 10941.726456838644, 11859.34773587461, 12101.0, 12140.878591213976, 12148.817767733315, 12196.756064878475, 12341.691457713629, 12345.382730863601, 12361.752764944471, 12566.0, 8869.06874778139, 9141.085891411072, 9201.30990175753, 9208.12522810332, 9230.123138301851, 10090.09189172978, 10246.53890574911, 10314.916933722152, 10337.74155646115, 10339.017793309624, 8568.742937711879, 8617.439866408664, 8708.869366959627, 8879.978802437803, 8905.866412003696, 8306.0, 8414.410991230556, 8425.325973922194, 8476.110008449024, 8624.611892464729, 7713.035870516185, 7731.265529774662, 7741.651625676745, 7817.022872140982, 7852.097008844056, 5827.912581311199, 5890.234269545103, 5983.102534619842, 6033.487153591818, 6063.727132279147, 5606.467385598362, 5682.659040457585, 5692.658440493584, 5755.7409916553015, 5804.46181761825], "value": 5093.839258797437, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.96608788252996, 400.73150988837483, 404.591362723649, 404.62024496467035, 405.72816213137196, 5219.817306394281, 6237.502999280174, 6309.0, 6315.905261421082, 6365.840853978647, 4920.753962301888, 5121.897562048757, 5523.364813046491, 6149.877002459948, 6203.162573052637, 3954.5452272988614, 4159.480064991876, 4399.922019105324, 4796.784144713496, 4800.399950006249, 3973.7417067890638, 4415.470143582775, 4431.977840110795, 4829.0, 4845.854624361257, 3911.2373087247997, 3938.940915886264, 4722.5513576210205, 4755.429348478187, 4804.879878003043, 3680.2455496623397, 4302.161078589676, 4494.786407669911, 4597.2874204498185, 4654.255319148954, 3853.903652408684, 4231.978840105779, 4234.195502854448, 4265.232258193524, 4265.9786701066605, 5313.707746073965, 5708.486236238717, 5805.419458054161, 6003.549733769984, 6128.0, 5258.894822103574, 5517.0, 5528.944710552883, 5559.499645031926, 5581.888362232733, 6331.14529538511, 6635.611179975205, 6672.799816005528, 6688.598684078922, 6698.83252918676, 3975.3241948868763, 4685.625149988027, 4691.636777796648, 4711.199096153662, 4750.358701575277, 6703.798886033378, 6987.26633703464, 7006.684699188549, 7045.823854403681, 7113.64431778409, 4027.597240275949, 4694.671373003857, 4756.072565849695, 4810.95189048112, 4835.419749630067, 4290.592393722592, 4919.87700307488, 4923.679960802608, 4942.504035462069, 4942.6654803203655, 5241.868953276123, 5455.9454405455435, 5508.421615730371, 5556.888862222733, 5557.165555766508, 5170.164591570014, 5801.3328467225965, 5936.851578710481, 6638.701258443381, 6655.134832471845, 1491.9850801492064, 1504.729148753213, 1658.1129095933618, 1684.115839184438, 1684.7978242610966, 1107.396468924438, 1163.743976325207, 1169.6373724610733, 1173.3957012138756, 1173.6713720158368, 6251.874962500725, 6337.746490140435, 6347.777827776005, 6360.618362898241, 6365.0, 5946.721454887133, 6288.465480434214, 6309.64342666329, 6825.146856642919, 6835.496190838107, 6104.511639068953, 6298.716557754912, 6324.588901721373, 6357.315311442471, 6359.618422894641, 3129.358481511285, 3403.1923662978143, 3552.857885684544, 3577.83899724513, 3581.9433267185723, 6119.724612392364, 6938.791836244921, 6987.336203060701, 7026.613536255452, 7033.437325013987, 5769.307683078057, 6625.269142191969, 6925.6190909499455, 6962.408195303355, 7016.438684905195, 6620.212542613371, 6675.833104172338, 6960.37356637905, 7041.436685065182, 7053.753118640926, 5444.0, 6797.0, 6809.285025072494, 6820.488463365168, 6822.181338239443, 6090.330063693079, 6416.0, 6649.833754155991, 6692.297308782508, 6709.0, 6270.7491700331475, 6829.89755153683, 6978.581285122909, 7045.823854403681, 7195.388391986739, 3924.862629807943, 4756.500567440369, 4888.804447822047, 4903.8528844134735, 4925.596205081534, 6113.663748493876, 6546.443552298108, 6836.248012718696, 6917.9308206917285, 7029.824254393681, 5851.0, 6666.733330666719, 6702.162229721285, 6705.932940670531, 6743.089682892943, 6223.253209614694, 6658.833529161616, 6670.299618540177, 6703.028060931235, 6713.765018224534, 6137.263528376445, 6556.770513032021, 6574.868502629825, 6583.703733332108, 6604.471642268511, 5727.31272247333, 6217.658028808459, 6692.297308782702, 6718.891382921764, 6723.394894459522, 6162.014077747539, 6469.126667899774, 6490.545661803629, 6509.349065093453, 6511.804645860631], "value": 4874.160685754753, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.9246836613789, 670.1556039390367, 713.4934196720328, 723.9927600723994, 764.0220517737298, 14803.33384997675, 17410.56473588161, 18291.268349266047, 18468.261269549235, 18500.2599896004, 16329.591760206, 16489.505314840575, 16681.582960425996, 16723.16384180792, 16791.580210494743, 984.8965858584853, 1026.538057873956, 1030.2582140858608, 1032.4992378696327, 1040.4069680282241, 978.838491648877, 1009.5406590001569, 1017.959281628734, 1019.4851599942019, 1025.5128813813417, 1068.8984546468073, 1075.3118004477142, 1077.9514921828536, 1080.2330345454736, 1081.4376524207407, 1042.9634962776342, 1062.521865160677, 1067.7063807452973, 1070.314998401024, 1071.4374953149581, 792.6472719639762, 799.6841247707181, 801.9518828870229, 802.594689681707, 803.2770506544099, 16177.433789817418, 16251.349946002145, 16411.0, 16840.32638694463, 17099.05955172465, 14607.78088328676, 14721.682477997456, 14837.0, 14869.0, 15491.225438728132, 11469.31184128955, 11533.308001519852, 11898.821517677241, 11932.650802986684, 11950.641480755592, 3006.969930300713, 3071.877124915001, 3098.5507101470384, 3152.1489197916435, 3165.1454107391105, 95504.0, 99281.5107773384, 108645.82708345867, 115774.0, 118033.40983295029, 9608.75978100539, 11583.76832463346, 11625.88374116265, 11856.347900865363, 12016.819747703792, 24755.25734227976, 25102.0, 25321.493570128496, 25417.364565886, 25453.872730636227, 7736.7292144774665, 7813.257740514642, 7896.368290536743, 7956.960215199003, 7975.72084977023, 4101.446304748881, 4103.097318589906, 4112.712110152261, 4145.958540414617, 4156.044109854751, 4057.69567282455, 4094.447249621263, 4155.252054630196, 4186.848616630451, 4196.895077623084, 800.8486605208523, 802.1256830055268, 812.7033632724014, 816.8529664660418, 831.9168083191754, 554.8862483191017, 556.6854727079187, 565.8557067947729, 568.2470726287686, 586.6186978464033, 7476.289752473506, 8067.919320806835, 9269.980302166756, 9750.366226195416, 10221.540030698636, 519.8804275016768, 530.3132443485696, 543.5651478816931, 572.3075079154187, 575.6718670357856, 4514.503404625489, 4757.3577567028005, 4777.071646136955, 4865.635077369211, 5189.455107213764, 84254.8936276598, 94315.52842235746, 99392.0, 99530.5023474878, 100943.0, 60641.69679151576, 74836.62581687166, 89617.0, 91667.5416622926, 91885.54057229671, 64579.677101614194, 65925.34074659384, 66147.33852661509, 66221.00668489977, 66426.0, 10415.078546552339, 10479.895201048197, 10529.315594486334, 10594.417307048187, 10642.201834862262, 33275.334493309514, 33878.0, 33932.66067339394, 34059.0, 34337.62649494092, 49054.75472622614, 59717.80564388601, 63657.0, 64707.676461619274, 67535.66232168808, 13721.313934303445, 13772.380242888894, 13805.516806912112, 13828.114470551254, 14144.363503642566, 14382.640433989234, 14397.42410303576, 14850.257487125384, 15180.316885739947, 15219.847801521842, 12763.553275635308, 12958.740825183255, 13742.701751579749, 13816.032877698464, 13845.376958036712, 13611.31943402806, 13611.43468501135, 13756.793648095087, 13763.31183440804, 14022.57932261993, 13351.866481335062, 13588.93205533966, 13777.242251676254, 13825.861741382458, 13921.234332111831, 9644.469554174308, 9687.757806054906, 9871.457069861226, 9892.653757118467, 10677.9466102669, 8267.669293228204, 10388.065074143364, 10859.0, 11288.492017859051, 11328.433578320886], "value": 7585.253415693483, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1717.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 124.0, 120.0, 126.0, 126.0, 125.0, 125.0, 126.0, 126.0, 126.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 116.0, 150.0, 181.0, 144.0, 117.0, 160.0, 101.0, 120.0, 158.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [246.0, 244.0, 254.0, 310.0, 254.0, 304.0, 279.0, 241.0, 300.0, 214.0], "value": 264.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 50.0, 77.0, 120.0, 91.0, 98.0, 58.0, 58.0, 77.0, 64.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 88.0, 76.0, 73.0, 73.0, 89.0, 72.0, 73.0, 88.0], "value": 79.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 143.0, 109.0, 99.0, 141.0, 96.0, 142.0, 143.0, 94.0, 126.0], "value": 122.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 102.0, 101.0, 110.0, 102.0, 103.0, 100.0, 102.0, 101.0, 101.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 103.0, 102.0, 104.0, 103.0, 103.0, 104.0, 132.0, 104.0, 102.0], "value": 107.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 68.0, 70.0, 68.0, 70.0, 70.0, 70.0, 69.0], "value": 68.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 98.0, 90.0, 105.0, 82.0, 65.0, 61.0, 62.0, 61.0, 84.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 77.0, 95.0, 112.0, 116.0, 86.0, 91.0, 91.0, 88.0, 86.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 152.0, 147.0, 178.0, 167.0, 153.0, 152.0, 227.0, 171.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 239.0, 178.0, 238.0, 238.0, 257.0, 199.0, 237.0, 250.0, 193.0], "value": 223.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 71.0, 70.0, 73.0, 73.0, 74.0, 73.0, 71.0, 72.0], "value": 72.2, "unit": "score"}]}]} 18:13:36 INFO - Return code: 0 18:13:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:13:36 ERROR - # TBPL SUCCESS # 18:13:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:13:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:13:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:13:36 INFO - 2017-03-28 18:13:36.791 screenresolution[1656:10505] starting screenresolution argv=screenresolution get 18:13:36 INFO - 2017-03-28 18:13:36.793 screenresolution[1656:10505] Display 0: 1600x1200x32@60 18:13:36 INFO - 2017-03-28 18:13:36.799 screenresolution[1657:10508] starting screenresolution argv=screenresolution list 18:13:36 INFO - Available Modes on Display 0 18:13:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:13:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:13:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:13:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:13:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:13:37 INFO - Intel Iris: 18:13:37 INFO - Chipset Model: Intel Iris 18:13:37 INFO - Type: GPU 18:13:37 INFO - Bus: Built-In 18:13:37 INFO - VRAM (Dynamic, Max): 1536 MB 18:13:37 INFO - Vendor: Intel (0x8086) 18:13:37 INFO - Device ID: 0x0a2e 18:13:37 INFO - Revision ID: 0x0009 18:13:37 INFO - Displays: 18:13:37 INFO - Display: 18:13:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:13:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:13:37 INFO - Display Serial Number: 1600x1200 60 18:13:37 INFO - Main Display: Yes 18:13:37 INFO - Mirror: Off 18:13:37 INFO - Online: Yes 18:13:37 INFO - Rotation: Supported 18:13:37 INFO - Return code: 0 18:13:37 INFO - Running post-action listener: _resource_record_post_action 18:13:37 INFO - [mozharness: 2017-03-29 01:13:37.035916Z] Finished run-tests step (success) 18:13:37 INFO - Running post-run listener: _resource_record_post_run 18:13:37 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 40106496; Write bytes: 514320384; Read time: 444; Write time: 1662 18:13:37 INFO - TinderboxPrint: CPU usage
54.3% 18:13:37 INFO - TinderboxPrint: I/O read bytes / time
40,106,496 / 444 18:13:37 INFO - TinderboxPrint: I/O write bytes / time
514,320,384 / 1,662 18:13:37 INFO - TinderboxPrint: CPU idle
2,197.6 (45.7%) 18:13:37 INFO - TinderboxPrint: CPU system
974.1 (20.2%) 18:13:37 INFO - TinderboxPrint: CPU user
1,642.0 (34.1%) 18:13:37 INFO - TinderboxPrint: Swap in / out
252,063,744 / 0 18:13:37 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200499712; Write bytes: 194197504; Read time: 15768; Write time: 384 18:13:37 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 39148032; Write bytes: 300290048; Read time: 428; Write time: 1226 18:13:37 INFO - Running post-run listener: _upload_blobber_files 18:13:37 INFO - Blob upload gear active. 18:13:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:13:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:13:37 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:13:37 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:13:37 INFO - (blobuploader) - INFO - Open directory for files ... 18:13:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:13:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:13:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:13:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:13:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:13:38 INFO - (blobuploader) - INFO - Done attempting. 18:13:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:13:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:13:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:13:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:13:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:13:39 INFO - (blobuploader) - INFO - Done attempting. 18:13:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:13:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:13:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:13:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:13:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:13:40 INFO - (blobuploader) - INFO - Done attempting. 18:13:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:13:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:13:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:13:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:13:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:13:42 INFO - (blobuploader) - INFO - Done attempting. 18:13:42 INFO - (blobuploader) - INFO - Iteration through files over. 18:13:42 INFO - Return code: 0 18:13:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:13:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:13:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1c70645272b9277f698c3c845d9872dfeee5ff92e44fb51e991f7ae1e3e43cd69bf3f68a418cbfae789db438254333c2566f505a590d58bed8f6b8e71ef60410", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f9a3b0bb4bff7b3ba712d3520ce9fa0b380d0eee558f620ee25f5756fda19c07b0d5e10131a9c67d64534a94a08f169a6d6a7c91f43389fcff52da6a3b51f0c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fc613318bf9eb222a7335a9cc8a5dbeae350dd031267cd70b190dda54087ad9a7cec60a8ca5bee6c85d21ad4713f8fb083575cb7c25b9f44e466b95d23bd16dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bd20509d354fe30bc1af75837b68cbfdd402c6da0f30cd1992d8ee296e6c6038cda9a8ae643b8ba0702e952a36b28f8c5e66adae277e459f61085b8333e70895"} 18:13:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:13:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:13:42 INFO - Contents: 18:13:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1c70645272b9277f698c3c845d9872dfeee5ff92e44fb51e991f7ae1e3e43cd69bf3f68a418cbfae789db438254333c2566f505a590d58bed8f6b8e71ef60410", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f9a3b0bb4bff7b3ba712d3520ce9fa0b380d0eee558f620ee25f5756fda19c07b0d5e10131a9c67d64534a94a08f169a6d6a7c91f43389fcff52da6a3b51f0c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fc613318bf9eb222a7335a9cc8a5dbeae350dd031267cd70b190dda54087ad9a7cec60a8ca5bee6c85d21ad4713f8fb083575cb7c25b9f44e466b95d23bd16dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bd20509d354fe30bc1af75837b68cbfdd402c6da0f30cd1992d8ee296e6c6038cda9a8ae643b8ba0702e952a36b28f8c5e66adae277e459f61085b8333e70895"} 18:13:42 INFO - Running post-run listener: copy_logs_to_upload_dir 18:13:42 INFO - Copying logs to upload dir... 18:13:42 INFO - mkdir: /builds/slave/test/build/upload/logs 18:13:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.847131 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-28 18:13:42.904612) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 18:13:42.908684) ========= 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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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/Pine/sha512/1c70645272b9277f698c3c845d9872dfeee5ff92e44fb51e991f7ae1e3e43cd69bf3f68a418cbfae789db438254333c2566f505a590d58bed8f6b8e71ef60410", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f9a3b0bb4bff7b3ba712d3520ce9fa0b380d0eee558f620ee25f5756fda19c07b0d5e10131a9c67d64534a94a08f169a6d6a7c91f43389fcff52da6a3b51f0c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fc613318bf9eb222a7335a9cc8a5dbeae350dd031267cd70b190dda54087ad9a7cec60a8ca5bee6c85d21ad4713f8fb083575cb7c25b9f44e466b95d23bd16dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bd20509d354fe30bc1af75837b68cbfdd402c6da0f30cd1992d8ee296e6c6038cda9a8ae643b8ba0702e952a36b28f8c5e66adae277e459f61085b8333e70895"} build_url:https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010985 build_url: 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1c70645272b9277f698c3c845d9872dfeee5ff92e44fb51e991f7ae1e3e43cd69bf3f68a418cbfae789db438254333c2566f505a590d58bed8f6b8e71ef60410", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f9a3b0bb4bff7b3ba712d3520ce9fa0b380d0eee558f620ee25f5756fda19c07b0d5e10131a9c67d64534a94a08f169a6d6a7c91f43389fcff52da6a3b51f0c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fc613318bf9eb222a7335a9cc8a5dbeae350dd031267cd70b190dda54087ad9a7cec60a8ca5bee6c85d21ad4713f8fb083575cb7c25b9f44e466b95d23bd16dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bd20509d354fe30bc1af75837b68cbfdd402c6da0f30cd1992d8ee296e6c6038cda9a8ae643b8ba0702e952a36b28f8c5e66adae277e459f61085b8333e70895"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 18:13:42.944012) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 18:13:42.944470) ========= 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.M0mYwFjlGY/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.3HIzsP17Ic/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005063 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 18:13:42.993460) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 18:13:42.993784) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 18:13:42.994124) ========= ========= Total master_lag: 0.18 =========