builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0389 starttime: 1490864885.34 results: success (0) buildid: 20170330004004 builduid: 0a5c29dce87a438eb08ba5997e7fdc42 revision: 437ac8a1ad2cdb94ba9b5573da06049855ea4a38 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:05.338106) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:05.338629) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:05.338928) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:05.364759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:05.365154) ========= 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.Age4WbNrVy/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.M6dA66oBZs/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-30 02:08:05-- 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% 66.8M=0s 2017-03-30 02:08:05 (66.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.244110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:05.633065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:05.633430) ========= 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.Age4WbNrVy/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.M6dA66oBZs/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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.021861 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:05.682859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2017-03-30 02:08:05.683385) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 437ac8a1ad2cdb94ba9b5573da06049855ea4a38 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 437ac8a1ad2cdb94ba9b5573da06049855ea4a38 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Age4WbNrVy/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.M6dA66oBZs/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-30 02:08:05,828 truncating revision to first 12 chars 2017-03-30 02:08:05,828 Setting DEBUG logging. 2017-03-30 02:08:05,828 attempt 1/10 2017-03-30 02:08:05,828 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/437ac8a1ad2c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 02:08:10,215 unpacking tar archive at: mozilla-aurora-437ac8a1ad2c/testing/mozharness/ program finished with exit code 0 elapsedTime=8.693264 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2017-03-30 02:08:14.400801) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:14.401292) ========= script_repo_revision: 437ac8a1ad2cdb94ba9b5573da06049855ea4a38 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:14.401805) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:14.402113) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 02:08:14.422343) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 42 secs) (at 2017-03-30 02:08:14.422771) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Age4WbNrVy/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.M6dA66oBZs/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:08:14 INFO - MultiFileLogger online at 20170330 02:08:14 in /builds/slave/test 02:08:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:08:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:08:14 INFO - {'append_to_log': False, 02:08:14 INFO - 'base_work_dir': '/builds/slave/test', 02:08:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:08:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:08:14 INFO - 'branch': 'Mozilla-Aurora', 02:08:14 INFO - 'buildbot_json_path': 'buildprops.json', 02:08:14 INFO - 'config_files': ('talos/mac_config.py',), 02:08:14 INFO - 'default_actions': ('clobber', 02:08:14 INFO - 'read-buildbot-config', 02:08:14 INFO - 'download-and-extract', 02:08:14 INFO - 'populate-webroot', 02:08:14 INFO - 'create-virtualenv', 02:08:14 INFO - 'install', 02:08:14 INFO - 'run-tests'), 02:08:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:08:14 INFO - 'download_minidump_stackwalk': True, 02:08:14 INFO - 'download_symbols': 'ondemand', 02:08:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:08:14 INFO - 'tooltool.py': '/tools/tooltool.py', 02:08:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:08:14 INFO - '/tools/misc-python/virtualenv.py')}, 02:08:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:08:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:08:14 INFO - 'gecko_profile': False, 02:08:14 INFO - 'gecko_profile_interval': 0, 02:08:14 INFO - 'installer_path': 'installer.exe', 02:08:14 INFO - 'log_level': 'info', 02:08:14 INFO - 'log_name': 'talos', 02:08:14 INFO - 'log_to_console': True, 02:08:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:08:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:08:14 INFO - 'opt_config_files': (), 02:08:14 INFO - 'pip_index': False, 02:08:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:14 INFO - 'cmd': ('bash', 02:08:14 INFO - '-c', 02:08:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:14 INFO - 'enabled': True, 02:08:14 INFO - 'halt_on_failure': False, 02:08:14 INFO - 'name': 'check_screen_resolution'},), 02:08:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:14 INFO - 'cmd': ('bash', 02:08:14 INFO - '-c', 02:08:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:14 INFO - 'enabled': True, 02:08:14 INFO - 'halt_on_failure': False, 02:08:14 INFO - 'name': 'check_screen_resolution'},), 02:08:14 INFO - 'run_cmd_checks_enabled': True, 02:08:14 INFO - 'suite': 'dromaeojs-e10s', 02:08:14 INFO - 'system_bits': '32', 02:08:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:08:14 INFO - 'title': 't-yosemite-r7-0389', 02:08:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:08:14 INFO - 'use_talos_json': True, 02:08:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:08:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:08:14 INFO - 'work_dir': 'build'} 02:08:14 INFO - [mozharness: 2017-03-30 09:08:14.681623Z] Running clobber step. 02:08:14 INFO - Running pre-action listener: _resource_record_pre_action 02:08:14 INFO - Running main action method: clobber 02:08:14 INFO - rmtree: /builds/slave/test/build 02:08:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:08:16 INFO - Running post-action listener: _resource_record_post_action 02:08:16 INFO - [mozharness: 2017-03-30 09:08:16.852831Z] Finished clobber step (success) 02:08:16 INFO - [mozharness: 2017-03-30 09:08:16.853098Z] Running read-buildbot-config step. 02:08:16 INFO - Running pre-action listener: _resource_record_pre_action 02:08:16 INFO - Running main action method: read_buildbot_config 02:08:16 INFO - Using buildbot properties: 02:08:16 INFO - { 02:08:16 INFO - "project": "", 02:08:16 INFO - "product": "firefox", 02:08:16 INFO - "script_repo_revision": "production", 02:08:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:08:16 INFO - "repository": "", 02:08:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:08:16 INFO - "buildid": "20170330004004", 02:08:16 INFO - "stage_platform": "macosx64", 02:08:16 INFO - "basedir": "/builds/slave/test", 02:08:16 INFO - "nightly_build": "True", 02:08:16 INFO - "buildnumber": 9, 02:08:16 INFO - "slavename": "t-yosemite-r7-0389", 02:08:16 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:08:16 INFO - "platform": "yosemite_r7", 02:08:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:08:16 INFO - "branch": "mozilla-aurora", 02:08:16 INFO - "repo_path": "releases/mozilla-aurora", 02:08:16 INFO - "revision": "437ac8a1ad2cdb94ba9b5573da06049855ea4a38", 02:08:16 INFO - "pgo_build": "False", 02:08:16 INFO - "builduid": "0a5c29dce87a438eb08ba5997e7fdc42", 02:08:16 INFO - "slavebuilddir": "test" 02:08:16 INFO - } 02:08:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:08:16 INFO - Running post-action listener: _resource_record_post_action 02:08:16 INFO - [mozharness: 2017-03-30 09:08:16.858954Z] Finished read-buildbot-config step (success) 02:08:16 INFO - [mozharness: 2017-03-30 09:08:16.859182Z] Running download-and-extract step. 02:08:16 INFO - Running pre-action listener: _resource_record_pre_action 02:08:16 INFO - Running main action method: download_and_extract 02:08:16 INFO - mkdir: /builds/slave/test/build/tests 02:08: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')]} 02:08:16 INFO - trying https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:08:16 INFO - Downloading https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:08:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:08:18 INFO - Downloaded 1199 bytes. 02:08:18 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:08:18 INFO - Using the following test package requirements: 02:08:18 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:08:18 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:18 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:08:18 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:18 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:08:18 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:18 INFO - u'jsshell-mac.zip'], 02:08:18 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:18 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:08:18 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:08:18 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:18 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:08:18 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:18 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:08:18 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:18 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:08:18 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:18 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:08:18 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:08:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:08:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:18 INFO - Fetch https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:08:20 INFO - Expected file size: 51791038 02:08:20 INFO - Obtained file size: 51791038 02:08:36 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:08:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:08:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:36 INFO - Fetch https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:08:39 INFO - Expected file size: 51791038 02:08:39 INFO - Obtained file size: 51791038 02:08:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:08:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:08:54 INFO - Fetch https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:08:56 INFO - Expected file size: 14404438 02:08:56 INFO - Obtained file size: 14404438 02:08: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')]} 02:08:56 INFO - trying https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:08:56 INFO - Downloading https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:08:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:08:59 INFO - Downloaded 74750363 bytes. 02:08:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:08:59 INFO - mkdir: /builds/slave/test/properties 02:08:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:08:59 INFO - Writing to file /builds/slave/test/properties/build_url 02:08:59 INFO - Contents: 02:08:59 INFO - build_url:https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:09:02 INFO - Running post-action listener: _resource_record_post_action 02:09:02 INFO - Running post-action listener: set_extra_try_arguments 02:09:02 INFO - [mozharness: 2017-03-30 09:09:02.025841Z] Finished download-and-extract step (success) 02:09:02 INFO - [mozharness: 2017-03-30 09:09:02.025948Z] Running populate-webroot step. 02:09:02 INFO - Running pre-action listener: _resource_record_pre_action 02:09:02 INFO - Running main action method: populate_webroot 02:09:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:09:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:09:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:09:02 INFO - '--tppagecycles', 02:09:02 INFO - '7'], 02:09:02 INFO - 'tests': ['tsvgm']}}, 02:09:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:09:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:09:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:09:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:09:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:09:02 INFO - 'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:09:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:09:02 INFO - 'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['damp', 'tps']}, 02:09:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:09:02 INFO - 'tests': ['damp', 'tps']}, 02:09:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['dromaeo_dom']}, 02:09:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:09:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['basic_compositor_video']}, 02:09:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:09:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['a11yr', 02:09:02 INFO - 'ts_paint', 02:09:02 INFO - 'tpaint', 02:09:02 INFO - 'sessionrestore', 02:09:02 INFO - 'sessionrestore_no_auto_restore', 02:09:02 INFO - 'tabpaint']}, 02:09:02 INFO - 'other-e10s': {'tests': ['a11yr', 02:09:02 INFO - 'ts_paint', 02:09:02 INFO - 'tpaint', 02:09:02 INFO - 'sessionrestore', 02:09:02 INFO - 'sessionrestore_no_auto_restore', 02:09:02 INFO - 'tabpaint']}, 02:09:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:09:02 INFO - 'ts_paint', 02:09:02 INFO - 'tpaint', 02:09:02 INFO - 'sessionrestore', 02:09:02 INFO - 'sessionrestore_no_auto_restore', 02:09:02 INFO - 'tabpaint']}, 02:09:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:09:02 INFO - 'ts_paint', 02:09:02 INFO - 'tpaint', 02:09:02 INFO - 'sessionrestore', 02:09:02 INFO - 'sessionrestore_no_auto_restore', 02:09:02 INFO - 'tabpaint']}, 02:09:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['a11yr', 02:09:02 INFO - 'ts_paint', 02:09:02 INFO - 'tpaint', 02:09:02 INFO - 'sessionrestore', 02:09:02 INFO - 'sessionrestore_no_auto_restore', 02:09:02 INFO - 'tabpaint']}, 02:09:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['a11yr', 02:09:02 INFO - 'ts_paint', 02:09:02 INFO - 'tpaint', 02:09:02 INFO - 'sessionrestore', 02:09:02 INFO - 'sessionrestore_no_auto_restore', 02:09:02 INFO - 'tabpaint']}, 02:09:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['tsvgx', 02:09:02 INFO - 'tsvgr_opacity', 02:09:02 INFO - 'tart', 02:09:02 INFO - 'tscrollx', 02:09:02 INFO - 'cart', 02:09:02 INFO - 'tsvg_static']}, 02:09:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:09:02 INFO - 'tsvgr_opacity', 02:09:02 INFO - 'tart', 02:09:02 INFO - 'tscrollx', 02:09:02 INFO - 'cart', 02:09:02 INFO - 'tsvg_static']}, 02:09:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:09:02 INFO - 'talos_options': ['--disable-e10s'], 02:09:02 INFO - 'tests': ['tp5o']}, 02:09:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:09:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:09:02 INFO - 'talos_options': ['--disable-e10s', 02:09:02 INFO - '--xperf_path', 02:09:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:02 INFO - 'tests': ['tp5n']}, 02:09:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:09:02 INFO - 'talos_options': ['--xperf_path', 02:09:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:02 INFO - 'tests': ['tp5n']}}, 02:09:02 INFO - 'talos.zip': {'path': '', 02:09:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:09:02 INFO - Running post-action listener: _resource_record_post_action 02:09:02 INFO - [mozharness: 2017-03-30 09:09:02.044060Z] Finished populate-webroot step (success) 02:09:02 INFO - [mozharness: 2017-03-30 09:09:02.044192Z] Running create-virtualenv step. 02:09:02 INFO - Running pre-action listener: _resource_record_pre_action 02:09:02 INFO - Running main action method: create_virtualenv 02:09:02 INFO - Creating virtualenv /builds/slave/test/build/venv 02:09:02 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 02:09:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:09:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:09:02 INFO - Using real prefix '/tools/python27' 02:09:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:09:02 INFO - Installing distribute.............................................................................................................................................................................................done. 02:09:05 INFO - Installing pip.................done. 02:09:05 INFO - Return code: 0 02:09:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:09:06 INFO - Reading from file tmpfile_stdout 02:09:06 INFO - Output received: 02:09:06 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) 02:09:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:07 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 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:07 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 02:09:07 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 02:09:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:07 INFO - 'HOME': '/Users/cltbld', 02:09:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:07 INFO - 'LOGNAME': 'cltbld', 02:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:07 INFO - 'MOZ_NO_REMOTE': '1', 02:09:07 INFO - 'NO_EM_RESTART': '1', 02:09:07 INFO - 'PAGER': '/bin/cat', 02:09:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:07 INFO - 'PWD': '/builds/slave/test', 02:09:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:07 INFO - 'SHELL': '/bin/bash', 02:09:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:07 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:07 INFO - 'USER': 'cltbld', 02:09:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:07 INFO - 'XPC_FLAGS': '0x0', 02:09:07 INFO - 'XPC_SERVICE_NAME': '0', 02:09:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:08 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 02:09:08 INFO - Cleaning up... 02:09:08 INFO - Return code: 0 02:09:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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')]} 02:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:08 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 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:08 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 02:09:08 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 02:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:08 INFO - 'HOME': '/Users/cltbld', 02:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:08 INFO - 'LOGNAME': 'cltbld', 02:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:08 INFO - 'MOZ_NO_REMOTE': '1', 02:09:08 INFO - 'NO_EM_RESTART': '1', 02:09:08 INFO - 'PAGER': '/bin/cat', 02:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:08 INFO - 'PWD': '/builds/slave/test', 02:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:08 INFO - 'SHELL': '/bin/bash', 02:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:08 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:08 INFO - 'USER': 'cltbld', 02:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:08 INFO - 'XPC_FLAGS': '0x0', 02:09:08 INFO - 'XPC_SERVICE_NAME': '0', 02:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:09 INFO - Downloading/unpacking psutil>=3.1.1 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:09:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:18 INFO - Installing collected packages: psutil 02:09:18 INFO - Running setup.py install for psutil 02:09:18 INFO - building 'psutil._psutil_osx' extension 02:09:18 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 02:09:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:09:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:09:19 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 02:09:19 INFO - building 'psutil._psutil_posix' extension 02:09:19 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 02:09:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:09:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:09:19 INFO - ^ 02:09:19 INFO - 1 warning generated. 02:09:19 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 02:09:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:19 INFO - Successfully installed psutil 02:09:19 INFO - Cleaning up... 02:09:19 INFO - Return code: 0 02:09:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:09:19 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')]} 02:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:19 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')]} 02:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:19 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 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:19 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 02:09:19 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 02:09:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:19 INFO - 'HOME': '/Users/cltbld', 02:09:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:19 INFO - 'LOGNAME': 'cltbld', 02:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:19 INFO - 'MOZ_NO_REMOTE': '1', 02:09:19 INFO - 'NO_EM_RESTART': '1', 02:09:19 INFO - 'PAGER': '/bin/cat', 02:09:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:19 INFO - 'PWD': '/builds/slave/test', 02:09:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:19 INFO - 'SHELL': '/bin/bash', 02:09:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:19 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:19 INFO - 'USER': 'cltbld', 02:09:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:19 INFO - 'XPC_FLAGS': '0x0', 02:09:19 INFO - 'XPC_SERVICE_NAME': '0', 02:09:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:09:23 INFO - Installing collected packages: mozsystemmonitor 02:09:23 INFO - Running setup.py install for mozsystemmonitor 02:09:23 INFO - Successfully installed mozsystemmonitor 02:09:23 INFO - Cleaning up... 02:09:23 INFO - Return code: 0 02:09:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:09:23 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')]} 02:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:23 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')]} 02:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:23 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 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:23 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 02:09:23 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 02:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:23 INFO - 'HOME': '/Users/cltbld', 02:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:23 INFO - 'LOGNAME': 'cltbld', 02:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:23 INFO - 'MOZ_NO_REMOTE': '1', 02:09:23 INFO - 'NO_EM_RESTART': '1', 02:09:23 INFO - 'PAGER': '/bin/cat', 02:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:23 INFO - 'PWD': '/builds/slave/test', 02:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:23 INFO - 'SHELL': '/bin/bash', 02:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:23 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:23 INFO - 'USER': 'cltbld', 02:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:23 INFO - 'XPC_FLAGS': '0x0', 02:09:23 INFO - 'XPC_SERVICE_NAME': '0', 02:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:23 INFO - Downloading/unpacking jsonschema==2.5.1 02:09:23 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 02:09:23 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 02:09:23 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 02:09:23 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 02:09:23 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 02:09:23 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 02:09:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:09:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:32 INFO - Downloading functools32-3.2.3-2.tar.gz 02:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:09:32 INFO - warning: no files found matching '*.txt' 02:09:32 INFO - no previously-included directories found matching 'build' 02:09:32 INFO - no previously-included directories found matching 'dist' 02:09:32 INFO - no previously-included directories found matching '.git*' 02:09:32 INFO - Installing collected packages: jsonschema, functools32 02:09:32 INFO - Running setup.py install for functools32 02:09:32 INFO - warning: no files found matching '*.txt' 02:09:32 INFO - no previously-included directories found matching 'build' 02:09:32 INFO - no previously-included directories found matching 'dist' 02:09:32 INFO - no previously-included directories found matching '.git*' 02:09:32 INFO - Successfully installed jsonschema functools32 02:09:32 INFO - Cleaning up... 02:09:33 INFO - Return code: 0 02:09:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:33 INFO - 'HOME': '/Users/cltbld', 02:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:33 INFO - 'LOGNAME': 'cltbld', 02:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:33 INFO - 'MOZ_NO_REMOTE': '1', 02:09:33 INFO - 'NO_EM_RESTART': '1', 02:09:33 INFO - 'PAGER': '/bin/cat', 02:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:33 INFO - 'PWD': '/builds/slave/test', 02:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:33 INFO - 'SHELL': '/bin/bash', 02:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:33 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:33 INFO - 'USER': 'cltbld', 02:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:33 INFO - 'XPC_FLAGS': '0x0', 02:09:33 INFO - 'XPC_SERVICE_NAME': '0', 02:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:09:33 INFO - Cleaning up... 02:09:33 INFO - Return code: 0 02:09:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:33 INFO - 'HOME': '/Users/cltbld', 02:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:33 INFO - 'LOGNAME': 'cltbld', 02:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:33 INFO - 'MOZ_NO_REMOTE': '1', 02:09:33 INFO - 'NO_EM_RESTART': '1', 02:09:33 INFO - 'PAGER': '/bin/cat', 02:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:33 INFO - 'PWD': '/builds/slave/test', 02:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:33 INFO - 'SHELL': '/bin/bash', 02:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:33 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:33 INFO - 'USER': 'cltbld', 02:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:33 INFO - 'XPC_FLAGS': '0x0', 02:09:33 INFO - 'XPC_SERVICE_NAME': '0', 02:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:33 INFO - Downloading/unpacking blobuploader==1.2.4 02:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:37 INFO - Downloading blobuploader-1.2.4.tar.gz 02:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:09:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:09:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:38 INFO - Downloading docopt-0.6.1.tar.gz 02:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:09:38 INFO - Installing collected packages: blobuploader, requests, docopt 02:09:38 INFO - Running setup.py install for blobuploader 02:09:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:09:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:09:38 INFO - Running setup.py install for requests 02:09:39 INFO - Running setup.py install for docopt 02:09:39 INFO - Successfully installed blobuploader requests docopt 02:09:39 INFO - Cleaning up... 02:09:39 INFO - Return code: 0 02:09:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:39 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')]} 02:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:39 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')]} 02:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:39 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 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:39 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 02:09:39 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 02:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:39 INFO - 'HOME': '/Users/cltbld', 02:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:39 INFO - 'LOGNAME': 'cltbld', 02:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:39 INFO - 'MOZ_NO_REMOTE': '1', 02:09:39 INFO - 'NO_EM_RESTART': '1', 02:09:39 INFO - 'PAGER': '/bin/cat', 02:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:39 INFO - 'PWD': '/builds/slave/test', 02:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:39 INFO - 'SHELL': '/bin/bash', 02:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:39 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:39 INFO - 'USER': 'cltbld', 02:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:39 INFO - 'XPC_FLAGS': '0x0', 02:09:39 INFO - 'XPC_SERVICE_NAME': '0', 02:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:09:39 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-Vnx4Qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:09:39 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-5gG3Z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:09:39 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-h9Tnw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:09:39 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-2BEK8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-3y_1uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-rUGCtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-5QcT4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-zT0Owb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-UA5NVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-2BkD3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-DSXRAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-rwXezN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-qELmPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:09:40 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-eEKhDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:41 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-jpfBot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:09:41 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-Lk1yzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:41 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-yzl4Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:09:41 INFO - Running setup.py install for manifestparser 02:09:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Running setup.py install for mozcrash 02:09:41 INFO - Running setup.py install for mozdebug 02:09:41 INFO - Running setup.py install for mozdevice 02:09:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Running setup.py install for mozfile 02:09:42 INFO - Running setup.py install for mozhttpd 02:09:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Running setup.py install for mozinfo 02:09:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Running setup.py install for mozInstall 02:09:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Running setup.py install for mozleak 02:09:42 INFO - Running setup.py install for mozlog 02:09:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Running setup.py install for moznetwork 02:09:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:09:43 INFO - Running setup.py install for mozprocess 02:09:43 INFO - Running setup.py install for mozprofile 02:09:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:09:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:09:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:09:43 INFO - Running setup.py install for mozrunner 02:09:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:09:43 INFO - Running setup.py install for mozscreenshot 02:09:43 INFO - Running setup.py install for moztest 02:09:43 INFO - Running setup.py install for mozversion 02:09:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:09:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:09:43 INFO - Cleaning up... 02:09:43 INFO - Return code: 0 02:09:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:09:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:43 INFO - 'HOME': '/Users/cltbld', 02:09:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:43 INFO - 'LOGNAME': 'cltbld', 02:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:43 INFO - 'MOZ_NO_REMOTE': '1', 02:09:43 INFO - 'NO_EM_RESTART': '1', 02:09:43 INFO - 'PAGER': '/bin/cat', 02:09:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:43 INFO - 'PWD': '/builds/slave/test', 02:09:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:43 INFO - 'SHELL': '/bin/bash', 02:09:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:43 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:43 INFO - 'USER': 'cltbld', 02:09:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:43 INFO - 'XPC_FLAGS': '0x0', 02:09:43 INFO - 'XPC_SERVICE_NAME': '0', 02:09:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:09:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-OyEIjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:09:44 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)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:09:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-Uk7sBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:09:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-PHTsDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:09:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-e7tIFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:09:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-OZJ7Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:09:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-8oVS1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:09:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-y7LCl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:09:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-AV3x2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:09:45 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)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-l6FVeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:09:45 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)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-0DFnuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:09:45 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)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-M6M_PU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:09:45 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)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-05JLpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-gjhwK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-rxV8pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-j3MuVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-5OZZd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-ok8mOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:46 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)) 02:09:46 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)) 02:09:46 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)) 02:09:46 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)) 02:09:46 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)) 02:09:46 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)) 02:09:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:09:46 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 02:09:46 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 02:09:46 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 02:09:46 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 02:09:46 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 02:09:46 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 02:09:49 INFO - Downloading blessings-1.6.tar.gz 02:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:09:49 INFO - Installing collected packages: blessings 02:09:49 INFO - Running setup.py install for blessings 02:09:50 INFO - Successfully installed blessings 02:09:50 INFO - Cleaning up... 02:09:50 INFO - Return code: 0 02:09:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:09:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:50 INFO - Reading from file tmpfile_stdout 02:09:50 INFO - Current package versions: 02:09:50 INFO - blessings == 1.6 02:09:50 INFO - blobuploader == 1.2.4 02:09:50 INFO - docopt == 0.6.1 02:09:50 INFO - functools32 == 3.2.3-2 02:09:50 INFO - jsonschema == 2.5.1 02:09:50 INFO - manifestparser == 1.1 02:09:50 INFO - mozInstall == 1.12 02:09:50 INFO - mozcrash == 1.0 02:09:50 INFO - mozdebug == 0.1 02:09:50 INFO - mozdevice == 0.49 02:09:50 INFO - mozfile == 1.2 02:09:50 INFO - mozhttpd == 0.7 02:09:50 INFO - mozinfo == 0.9 02:09:50 INFO - mozleak == 0.1 02:09:50 INFO - mozlog == 3.4 02:09:50 INFO - moznetwork == 0.27 02:09:50 INFO - mozprocess == 0.25 02:09:50 INFO - mozprofile == 0.28 02:09:50 INFO - mozrunner == 6.13 02:09:50 INFO - mozscreenshot == 0.1 02:09:50 INFO - mozsystemmonitor == 0.3 02:09:50 INFO - moztest == 0.8 02:09:50 INFO - mozversion == 1.4 02:09:50 INFO - psutil == 3.1.1 02:09:50 INFO - requests == 1.2.3 02:09:50 INFO - wsgiref == 0.1.2 02:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:50 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 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:50 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 02:09:50 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 02:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:50 INFO - 'HOME': '/Users/cltbld', 02:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:50 INFO - 'LOGNAME': 'cltbld', 02:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:50 INFO - 'MOZ_NO_REMOTE': '1', 02:09:50 INFO - 'NO_EM_RESTART': '1', 02:09:50 INFO - 'PAGER': '/bin/cat', 02:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:50 INFO - 'PWD': '/builds/slave/test', 02:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:50 INFO - 'SHELL': '/bin/bash', 02:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:50 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:50 INFO - 'USER': 'cltbld', 02:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:50 INFO - 'XPC_FLAGS': '0x0', 02:09:50 INFO - 'XPC_SERVICE_NAME': '0', 02:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 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)) 02:09:50 INFO - Cleaning up... 02:09:50 INFO - Return code: 0 02:09:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:50 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 0x10ef74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe21499a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, '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.Age4WbNrVy/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.M6dA66oBZs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 02:09:50 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 02:09:50 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 02:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:50 INFO - 'HOME': '/Users/cltbld', 02:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:50 INFO - 'LOGNAME': 'cltbld', 02:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:50 INFO - 'MOZ_NO_REMOTE': '1', 02:09:50 INFO - 'NO_EM_RESTART': '1', 02:09:50 INFO - 'PAGER': '/bin/cat', 02:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:50 INFO - 'PWD': '/builds/slave/test', 02:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:50 INFO - 'SHELL': '/bin/bash', 02:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:09:50 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:50 INFO - 'USER': 'cltbld', 02:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:50 INFO - 'XPC_FLAGS': '0x0', 02:09:50 INFO - 'XPC_SERVICE_NAME': '0', 02:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:09:51 INFO - Cleaning up... 02:09:51 INFO - Return code: 0 02:09:51 INFO - Running post-action listener: _resource_record_post_action 02:09:51 INFO - Running post-action listener: _start_resource_monitoring 02:09:51 INFO - Starting resource monitoring. 02:09:51 INFO - [mozharness: 2017-03-30 09:09:51.045195Z] Finished create-virtualenv step (success) 02:09:51 INFO - [mozharness: 2017-03-30 09:09:51.046179Z] Running install step. 02:09:51 INFO - Running pre-action listener: _resource_record_pre_action 02:09:51 INFO - Running main action method: install 02:09:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:51 INFO - Reading from file tmpfile_stdout 02:09:51 INFO - Detecting whether we're running mozinstall >=1.0... 02:09:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:09:51 INFO - Reading from file tmpfile_stdout 02:09:51 INFO - Output received: 02:09:51 INFO - Usage: mozinstall [options] installer 02:09:51 INFO - Options: 02:09:51 INFO - -h, --help show this help message and exit 02:09:51 INFO - -d DEST, --destination=DEST 02:09:51 INFO - Directory to install application into. [default: 02:09:51 INFO - "/builds/slave/test"] 02:09:51 INFO - --app=APP Application being installed. [default: firefox] 02:09:51 INFO - mkdir: /builds/slave/test/build/application 02:09:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:10:11 INFO - Reading from file tmpfile_stdout 02:10:11 INFO - Output received: 02:10:11 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:10:11 INFO - Running post-action listener: _resource_record_post_action 02:10:11 INFO - [mozharness: 2017-03-30 09:10:11.740687Z] Finished install step (success) 02:10:11 INFO - [mozharness: 2017-03-30 09:10:11.740785Z] Running run-tests step. 02:10:11 INFO - Running pre-action listener: _resource_record_pre_action 02:10:11 INFO - Running main action method: run_tests 02:10:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:10:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:10:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:10:11 INFO - 2017-03-30 02:10:11.753 screenresolution[1894:14557] starting screenresolution argv=screenresolution get 02:10:11 INFO - 2017-03-30 02:10:11.756 screenresolution[1894:14557] Display 0: 1600x1200x32@60 02:10:11 INFO - 2017-03-30 02:10:11.761 screenresolution[1895:14559] starting screenresolution argv=screenresolution list 02:10:11 INFO - Available Modes on Display 0 02:10:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:10:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:10:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:10:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:10:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:10:11 INFO - Intel Iris: 02:10:11 INFO - Chipset Model: Intel Iris 02:10:11 INFO - Type: GPU 02:10:11 INFO - Bus: Built-In 02:10:11 INFO - VRAM (Dynamic, Max): 1536 MB 02:10:11 INFO - Vendor: Intel (0x8086) 02:10:11 INFO - Device ID: 0x0a2e 02:10:11 INFO - Revision ID: 0x0009 02:10:11 INFO - Displays: 02:10:11 INFO - Display: 02:10:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:10:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:10:11 INFO - Display Serial Number: 1600x1200 60 02:10:11 INFO - Main Display: Yes 02:10:11 INFO - Mirror: Off 02:10:11 INFO - Online: Yes 02:10:11 INFO - Rotation: Supported 02:10:11 INFO - Return code: 0 02:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:10:11 INFO - Python 2.7.3 02:10:11 INFO - Return code: 0 02:10:12 INFO - grabbing minidump binary from tooltool 02:10:12 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')]} 02:10:12 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 0x10ed52d30>, '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 0x10ec79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:10:12 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 02:10:12 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 02:10:12 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 02:10:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:10:12 INFO - Return code: 0 02:10:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:10:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:10:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:10:12 INFO - ENV: RUST_BACKTRACE is now 1 02:10:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:10:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:10:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0389', '--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 02:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0389 --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 02:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Age4WbNrVy/Render', 02:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:12 INFO - 'HOME': '/Users/cltbld', 02:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:12 INFO - 'LOGNAME': 'cltbld', 02:10:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:10:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:12 INFO - 'MOZ_NO_REMOTE': '1', 02:10:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:10:12 INFO - 'NO_EM_RESTART': '1', 02:10:12 INFO - 'PAGER': '/bin/cat', 02:10:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:12 INFO - 'PWD': '/builds/slave/test', 02:10:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:12 INFO - 'RUST_BACKTRACE': '1', 02:10:12 INFO - 'SHELL': '/bin/bash', 02:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M6dA66oBZs/Listeners', 02:10:12 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 02:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:12 INFO - 'USER': 'cltbld', 02:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:12 INFO - 'XPC_FLAGS': '0x0', 02:10:12 INFO - 'XPC_SERVICE_NAME': '0', 02:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0389', '--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 02:10:12 INFO - mozversion application_buildid: 20170330004004 02:10:12 INFO - mozversion application_changeset: 437ac8a1ad2cdb94ba9b5573da06049855ea4a38 02:10:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:10:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:10:12 INFO - mozversion application_name: Firefox 02:10:12 INFO - mozversion application_remotingname: firefox-dev 02:10:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:12 INFO - mozversion application_vendor: Mozilla 02:10:12 INFO - mozversion application_version: 54.0a2 02:10:12 INFO - mozversion platform_buildid: 20170330004004 02:10:12 INFO - mozversion platform_changeset: 437ac8a1ad2cdb94ba9b5573da06049855ea4a38 02:10:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:12 INFO - mozversion platform_version: 54.0a2 02:10:12 INFO - using testdate: 1490865012 02:10:12 INFO - actual date: 1490865012 02:10:12 INFO - starting webserver on 'localhost:49369' 02:10:12 INFO - SUITE-START | Running 2 tests 02:10:12 INFO - TEST-START | dromaeo_css 02:10:12 INFO - operating with platform_type : mac_ 02:10:12 INFO - Initialising browser for dromaeo_css test... 02:10:12 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpMHkMb1/profile http://localhost:49369/getInfo.html) 02:10:15 INFO - PROCESS | 1905 | 2017-03-30 02:10:15.469 plugin-container[1908:14734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 02:10:15 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:15 INFO - PROCESS | 1905 | 2017-03-30 02:10:15.469 plugin-container[1908:14734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 02:10:15 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:15 INFO - PROCESS | 1905 | 2017-03-30 02:10:15.469 plugin-container[1908:14734] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:10:15 INFO - PROCESS | 1905 | __metrics Screen width/height:1600/1200 02:10:15 INFO - PROCESS | 1905 | colorDepth:24 02:10:15 INFO - PROCESS | 1905 | Browser inner width/height: 1024/689 02:10:15 INFO - PROCESS | 1905 | __metrics 02:10:15 ERROR - PROCESS | 1905 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 02:10:16 INFO - TEST-INFO | 1905: exit 0 02:10:16 INFO - Browser initialized. 02:10:16 INFO - Running cycle 1/1 for dromaeo_css test... 02:10:16 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpMHkMb1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:10:18 INFO - PROCESS | 1909 | 2017-03-30 02:10:18.946 plugin-container[1910:14868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 02:10:18 INFO - PROCESS | 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:18 INFO - PROCESS | 1909 | 2017-03-30 02:10:18.946 plugin-container[1910:14868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:10:18 INFO - PROCESS | 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:18 INFO - PROCESS | 1909 | 2017-03-30 02:10:18.947 plugin-container[1910:14868] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:10:28 INFO - PROCESS | 1909 | RSS: Main: 220450816 02:10:28 INFO - PROCESS | 1909 | 02:13:32 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49369/tests/dromaeo/cssquery-ext.html) 02:13:34 INFO - PROCESS | 1909 | RSS: Main: 238440448 02:13:34 INFO - PROCESS | 1909 | 02:15:16 INFO - PROCESS | 1909 | ************************* 02:15:16 INFO - PROCESS | 1909 | A coding exception was thrown and uncaught in a Task. 02:15:16 INFO - PROCESS | 1909 | 02:15:16 ERROR - PROCESS | 1909 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:15:16 INFO - PROCESS | 1909 | Full stack: 02:15:16 INFO - PROCESS | 1909 | ************************* 02:16:36 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-ext.html (next: http://localhost:49369/tests/dromaeo/cssquery-jquery.html) 02:16:36 INFO - PROCESS | 1909 | RSS: Main: 248725504 02:16:36 INFO - PROCESS | 1909 | 02:19:38 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49369/tests/dromaeo/cssquery-mootools.html) 02:19:39 INFO - PROCESS | 1909 | RSS: Main: 256466944 02:19:39 INFO - PROCESS | 1909 | 02:22:41 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49369/tests/dromaeo/cssquery-prototype.html) 02:22:41 INFO - PROCESS | 1909 | RSS: Main: 252674048 02:22:41 INFO - PROCESS | 1909 | 02:25:45 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49369/tests/dromaeo/cssquery-yui.html) 02:25:45 INFO - PROCESS | 1909 | RSS: Main: 257904640 02:25:45 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-yui.html (next: http://localhost:49369/tests/dromaeo/cssquery-dojo.html) 02:28:48 INFO - PROCESS | 1909 | RSS: Main: 253927424 02:28:48 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | __start_tp_report 02:28:48 INFO - PROCESS | 1909 | _x_x_mozilla_page_load 02:28:48 INFO - PROCESS | 1909 | _x_x_mozilla_page_load_details 02:28:48 INFO - PROCESS | 1909 | |i|pagename|runs| 02:28:48 INFO - PROCESS | 1909 | |0;dojo.html;3447.9310413791723;3514.24443744595;3535.8585656573737;3546.0425685065034;3549.0417587251436;6019.518438524919;6125.724342404591;6133.693315334238;6178.969105154468;6182.443580077792;6117.938820611793;6191.442770150686;6198.659073750942;6200.3179650238435;6235.968820155904;5482.588805839558;5533.750981205855;5537.501624853762;5557.3886872444;5622.987862184805;5592;5603.7478313475995;5615.438456154372;5625.71871406428;5666.291713535808;5394.244805727201;5399.19012148179;5514.91727624084;5604.635698679573;5621.465960733744;5364.409914909357;5608.102703567451;5618.269624948751;5653.802116925908;5660.405657405995;4592.563706447882;5480.616356855022;5526.447355264481;5551.666899985973;5566.415526369754;5822.650640961556;5948.583599148062;6073.240844894388;6142.815715528496;6250.374962503759;6306.621602703853;6314.147590075327;6314.336994615591;6331.398517140864;6357.427831495188;6549.115869357671;6563.835904102388;6571.408573228432;6579.80260592183;6581.769638062693;6203.503719702413;6222.3466536013975;6233.501319894398;6241.219847519014;6256.78101266458;5124.692518448905;6368.681565921685;6445.2265728112925;6464.579802312929;6747.493937954673;6285.622862628257;6331.873362532723;6336.461400780893;6353.555251132469;6392.584482008655;6537.869242615121;6563.113979612786;6584.86830263392;6603.570767900071;6604.207495100619;6255.155554000152;6301.779437719658;6493.123428337209;6497.415232628991;6513;5989.161517387569;6259.374062593704;6300.495960323163;6356.396142366468;6438.7102580384;5174.120399532088;5223.164293712987;5224.817131400383;5241.030409374278;5266.1574148136115;4625.699329543569;4632.166210082218;4668.11305851887;4674.766261686902;4691.695039822401;5978.790742323998;6036.3360030396625;6232.065190221503;6313.2424109107205;6352.205974253218;5445;5515.172724091338;6428.77499287532;6569;6569.343065693392;6225.343693963013;6249.187605611241;6265.404786545271;6271.27880293763;6271.592346497463;5519.089350257243;5760.798372056958;5769.913451298234;5773.134029895549;5798.710064496759;5051.292819005269;6518.413342799171;6570.967145164243;6574.408303252731;6648.235452922782;5154.5103215194495;6481.740730370921;6510.18622672166;6549.410553050248;6562.704678289393;5611.3806594746975;6466.159399278252;6497.610143391412;6751;6811.421029212374;5355.357357117171;5903.822885313448;5956.434138756811;6208.944479438416;6503.089567460654;6048.455638992512;6410.326915673787;6524.967375163284;6535.477161826947;6549.508786840911;5396.865078372915;6308.116863639186;6329.398707122816;6335.778247761306;6369.522285828674;6217.564770466024;6233.563650544418;6242;6345.7144428499905;6351.047342898602;4911.778969946433;6151.9234134027265;6255.71849266775;6261.029540421242;6271.686415679106;6136.58034916419;6322.8735425290315;6334.4932405406535;6345.587536810092;6358.046293056079;6142.170806941184;6146.293176284785;6182;6277.121203031488;6303.149074875017 02:28:48 INFO - PROCESS | 1909 | |1;ext.html;775.0002496779155;799.3325573146423;830.6511265268587;834.3116928533962;956.7942892278159;37180.81409592955;37975.43036854446;38029.80985095074;38980.22039559214;39246.60753392465;37668.49326026963;37742.62257377426;37793.62206377935;37851.24297514048;37852.675156369536;7891.960540197291;8028.478148920331;10590.41752703601;10863;10981.505832237528;10579.312344697608;10800;10809.729756756105;10819.188560857929;10833.187510936672;10817.891821081766;10818.134549236041;10847.132229421666;10847.511861966192;10883.727906802354;10761.192910531703;10768.24622276441;10809.405482698448;10821.945890270577;10840.891591084068;10527.684169474927;10560.26078174528;10577.73555661107;10594.894051059468;10595;32503.837480812683;32530.349393012242;32735.854245101305;32743.50884736731;32776.50835237473;3705.1292946157632;3746.157114649182;3746.756460830037;3761.4545890845948;3762.285165818513;24475.510489790282;24588.508229835483;24602.015919363384;25047.248582542554;25791.871040644677;3073.938521229585;3087.5831762711973;3088.783785135019;3091.474449343617;3102.4260511805164;372590;377097;378260.99999999726;379758;380035.0998245019;44472.77763611161;44488.3326750099;44565;44627.55372446299;44712.77643611761;41136.79431602823;41871.16257674829;45467;45519.08961820745;45998;11491.77016459666;13347.933260333637;13574;13793.931030344784;13807.861921380658;11872.40637968098;11931.701707457209;11942.522299108112;11960.820587691192;11979.520819167135;10045.397276163454;11236.494357753922;11880.821787673192;11916.642500724993;11919.940400298117;5057.204692334242;5891.617044892068;5975.312839023568;5993.880122397528;5996.190514280604;1127.4813585750483;1132.3602164776876;1168.2873447197203;1173.6831055614937;1175.7119505721153;26610.80251388692;26649.941246176244;26788.330291742863;26793.464130717275;26977.865110674713;1211.7273613436905;1239.6405042537563;1254.7239607286383;1280.8975281977418;1293.0754510525019;11623;13619.659508512168;13813.033087683765;13858;13930.791038134435;502353;504632;505610;505693;506021;37586;44446;54817.72591137019;55360.33918982437;55587.44412555823;1422.0304877744193;1453.1644304524941;1457.1038811131077;1471.6173794813212;1472.7864459653504;28437;29532;29851.70148298576;30290.394192115593;30372;45726.77136614296;45787;45824.54175458203;45851.77074114608;46008.539914601766;460134;460700;460972;462472;462710;30420.543691844196;30712.23219419443;31054.37891242208;31100.222494436915;31212.843935780176;41687.58312416837;42904.35643465413;43329.56670433381;43465;43826.561734383526;29755.10734677963;29777.404451911272;29923.401531969674;30204.24489387783;30363.544546832258;24481;29535.409291814474;29543.852280738458;30423.239419014702;30524.236894076937;34839.47740783839;34989.125271868405;35170.64829351674;35352.29295414129;35769.46345804763;30004.549931750604;30070.54894176633;30140;30550;30902;22701.43246418853;24173.395665108514;24194.75805241925;24513.89687464104;24541.631875521525 02:28:48 INFO - PROCESS | 1909 | |2;jquery.html;406.11061774713374;414.62476458804775;417.54279064424446;423.487580028166;453.8933350662594;19492.610147797037;20331;21239.362819115413;21424.571508569818;21599;21262.468438289052;21884.90575471228;21941.451463713416;22004.00981955812;22498.43753906153;6369.490440764739;6648.036034774955;6664.433523150538;6669.166354205724;6680.699368528428;6253.749850005995;6375.23497180339;6484.092227088187;6487.545871788953;6491.772787952423;6446.871062578745;6449.613023218599;6466.967665161667;6501.02484627305;6502.382273684016;6209.068639704035;6435.6782160892;6436.195475565554;6439.3882581155185;6445.581037232589;5610.186522954149;6053.545984051214;6311.52663550231;6335.524835637346;6386.42522173002;26131.60802587963;26349.20952371432;26369.20892373232;26569.46861062787;27028.459430811272;2846.274200078987;2976.345204055126;2979.508381117113;2986.148947549938;2986.462436761382;16550;16627.836051476403;16898.15509224534;16992.320307187696;17254;1726.4302780082542;1986.4735845001087;1998.1507859159944;1999.800019998003;2012.9899350503301;169824;172840.40739389046;174169.12915435468;175278;178469;18038.549036273937;19597.902010489855;20191.798082019286;21161.470963226042;21394.893025535082;30254;30464.84767576192;30843.8457807714;31052.534211986836;31112.533312000338;10244.436555989343;10375.377477351383;10568.894311056945;10583;10660.573577056832;8855.911440885637;8882.777930551789;8883.600237989305;9067.81864362709;9068.319876009326;9379.343445958853;9411.529423528797;9551.14039736427;9581.329306948446;9617.422954622745;3559.875404360835;4011.277969965376;4047.6559492442884;4148.62662360389;4151.211269858778;946.1153821177221;958.4824194934797;974.133021610767;985.3890587835588;1002.4085789384299;14727.631809204642;21601.567968640855;21644.675329870064;21648.242311519338;21728.456788580097;972.8783902012249;975.5756246032998;997.2420960070399;1000.8098461292332;1014.2697257974247;10875.401852898009;10985.725356865982;11068.667939961813;11069.833952490719;11106.944465277784;775614;779672;780513;783523;801409;131367;154883;159544;160005;161914;1179.4102948525738;1203.1517779965104;1233.3524899427873;1235.493447686462;1243.533674871923;17830.554236143682;18021.90989045099;18168.72746908771;18375.356862509747;18389.816101839344;46161;60516.69741651264;62636.37363626488;64080.03879941898;64134;78061;85752.57123714342;86829.56585217286;87286.5635671843;87906;11129.721756956142;11510.446089777774;11663.88336116628;11867.821982670093;11965.581204657796;12816;12894.03294752916;13056.738865222833;13103.934480327538;13203.405846736729;10695.94652026735;11099.278546894426;11214.102871770401;11238.325700458;11469.827952580885;10357;10452;10523.631672891412;10559.894401055892;10755.193360497837;11287.492062857027;11400.885991139983;11495.310281383145;11561.942190288995;11732.70668233301;11572.074234061087;11698.94150529242;11858.822117668069;11945.641630751092;11955.76088478243;10771.353718776898;10872.184586155912;10917.23579349438;10946.1391563436;11032.076499727738 02:28:48 INFO - PROCESS | 1909 | |3;mootools.html;1251.912366134371;1390.4090761426394;1479.1568805780703;1574.7874037005001;1595.8803089768267;9997.400155990637;10366;10875.456227188628;11357.488912998915;11639.767204655902;11164.832527512093;11331.150163737731;11350.829737553922;11359.1480638952;11364.147688923344;2114.4819519217813;2285.645724912641;2376.1564644551195;2409.277216834951;2415.867127307997;1987.28457755208;2421.1889017179265;2441.682581264442;2447.6206188040883;2447.9143229986953;2366.171839856045;2406.506666133447;2413.625887987356;2428.1137384854487;2429.5626787178303;2342.672025916373;2367.75138610446;2389.2115601851347;2397.9280621581293;2443.890024948864;2389.4265376309736;2404.110479122736;2419.237940048879;2425.9393515162087;2428.805695544352;9464.621415143385;11453.14101442395;11472.426378681033;11478.885211147865;11507;5884.588078834483;6053.213082299273;6059.575829691923;6071.332153463116;6091.634501929854;3519.542459480251;3651.379265524841;3697.482352470656;3726.795026273554;3754.042719106638;1198.478661782119;1207.6256360528178;1237.2700106936861;1238.325112813519;1266.2972050511962;13754;13907;15040.398384064625;15624.453144139901;15730.528084157493;4159.646430053419;4388.912221755547;4641.489436162019;4818.927716084261;4824.903501929942;11813.704657383634;12011.699707507381;12013.819792703116;12040.277583345027;12049;5482.479164479368;5486.615936884459;5830.650160990269;5927.318358388754;5946.167536544887;5246.501582349671;5285.339332583427;5302.840914772563;5357.7589008494715;5364.517193452608;3313.93372132556;3481.0427132538753;3885.339492286318;3979.9851037985127;4021.4973128358956;1017.5624481472889;1052.6684094510206;1058.772363941756;1062.0070234330926;1064;897.8473659477904;935.854942483916;944.3049725742309;954.5561313989028;995.690666773191;6625.701843417058;6675.198976122896;6744.258131605519;6893.069435626227;6945.40964018054;954.160338901762;975.3708857786688;999.4503023337136;1022.8772547294373;1031.2471895516314;4064.9796751016056;4109.2808758467745;4328.783560821947;4361.345798130243;4388.956110438855;13364.933175334256;13961.581152565439;13965.231912244924;13975.510857119953;14055.578332650037;13366;13607.659808504866;13843;13885.930570347286;13937.372818223204;8066.596670166586;10169;10231;10276.177905767521;10385;5537.750801214036;6351.745930162741;6859.794206173823;7127.85744285101;7214.458915581457;11828.585999489977;12134.968527675246;12208.32854193028;12384.380780960735;12534.247945123321;12599.622011339674;12618.305993170463;12775;12783.105182637126;12843.100982931104;8481.57592120404;8606.698765543177;8638.395312328077;8649.437786543855;8699.782505437415;10278.794424111626;10449.425281609585;10467.633632822815;10514.737131571772;10538.315009524356;8791;9005.189532941804;9054.818903622023;9103.772405689911;9231.123043310874;8782.648694052166;8831.470111793313;8871.201591856763;8902.777430564029;8909.73270801877;7955.4828936121285;8130.186981301823;8131.186881312058;8133.2680058794995;8144;5890.911636325544;5892.292924849045;5897.587168898136;6055.1522786809;6104.938950610437;5951.434613711691;5978.8206353809455;6151.9384806151365;6164.753409863555;6253.437190652863 02:28:48 INFO - PROCESS | 1909 | |4;prototype.html;366.36252919919315;374.61601858095446;378.36623655377235;386.37407400011966;386.7099675243567;4838.879028024301;5117.744112794359;5133.435322114569;5149.974250128754;5257.526822585966;4628.745419001953;4814.8314808981695;4831.975840120795;5021.196608542634;5240.8208153165515;4007.5572793794213;4323.162655871258;4402.207602631525;4437.84467543636;4529.569690879362;4051.9189616207805;4334.97832510837;4457.2171131547475;4547.431136257986;4581.244094724367;4234.390931446062;4438.9556104439025;4567.200739870526;4568.565986231302;4596.149712303235;3808.4668146459517;4158.750474971512;4214.683898707609;4387.714798538085;4522.9547704522865;3652.4521321801676;4235.047114399235;4266.679999000087;4291;4371.23503386906;4118.732282401635;4317.41714868495;4687.851476388307;5059.2916991621205;5125;4694.788734506921;4730.5032971538185;4939.925901111486;5000.199928025908;5312.537468526456;4667.719936803803;5057.772400241998;5574.442555744434;5606.766511367533;5636.267285252932;4391.770304314797;4575.199340115467;4699.271612900006;4863.057850000734;4888.926666100011;4857.8299759508245;4954.076261842628;5032.974835125801;5632.183333416672;5677.318721753416;4291.6137547620865;4529.252673308867;4651.767411629405;4818.44622350155;4887.289448693;4673.836415725434;4874.073925954128;5074.923876141861;5113.122849767057;5143.922841157386;4408.911821763547;4678.836240731558;5160.761417259906;5172.741362931838;5225.346831646044;5322.733863306897;5354.54486368663;5581.134924086773;5601.719914004283;5641.251212124254;1460.5326295585523;1582.6280824006349;1768.3545505890258;1769.0889192066097;1773.140027086862;1302.9055593301482;1334.6196334044944;1370.3624169117809;1429.4496618801836;1444.768836986077;4614.2617181250835;4840.564349208588;4991.0267497838295;5051.449018245762;5251.422343542207;5088;5403.594730395236;5601.411851755588;5627.943720562742;5802.622829516068;4496.662750293741;5078.568321692697;5278.366596008426;5284.233786100994;5300.257963885059;2434.831280985102;2592.1705054382414;2901.3181902252954;2922.7661787056986;2973.5022581032035;5236.633435659467;5750.6007903953505;5950.851228719317;5980.43185897339;5983.491403230774;5511.724413779295;5626.606137570413;5672.844319158693;5894.970525147432;5909.6158749681135;4814.927776083432;5368.147988943808;5799.88400231993;5989.880202396015;6013.248343957005;5496.285482887199;5597.944020559742;5783.2240524547615;5812.94187058141;5847.561432892635;5128.410232823273;5209.775702709784;5284.418713941386;5411.377691565521;5533.7786488540005;6010.732380010241;6012.819615411545;6034.818955431344;6059.21230240066;6091.238595175603;4360.443336713079;4377.365282034151;4437.644988400855;4511.323301504801;4534.387857639308;5063.620228482805;5097.133487307241;5694.715264236855;5744.224529688438;5821.912671309849;4540.523245059353;5063.392392912874;5173.92239116406;5297.88744363688;5632.436756324499;4916.664584322418;5114.33513643224;5205.3863302376385;5419.512243898068;5550.944490555043;4523.84166554169;4680.976595117003;5227.210272552369;5454;5477.302036368738;5399.892002160013;5460.945390546202;5568.582356323373;5573.27547418833;5622.353429355677;4386.7367957922625;4629.125204292267;5223.701571308612;5369;5443.564514838883 02:28:48 INFO - PROCESS | 1909 | |5;yui.html;678.9864202715946;731.2650785960116;767.9347255483284;815.074929521737;823.069930977995;12836.48654053839;15139.545813625595;15227;15974.361025558963;16491.257893394795;13728.656783580416;16504.75242871355;16660.250288737006;17558.82441175588;17687.29250829965;943.76877664972;967.0088159636358;969.8351280282369;970;971.5870754929163;909.6452383570413;954.2509130332691;959.2517836087862;964.0793042644258;964.9131578157965;996.5166894056376;996.611321584579;999.0059890409017;999.2905037423402;999.4003597841273;992.1864071461405;992.4888682328607;992.7369247149511;992.7964767222702;994.8259054665441;729.5002922997711;733.4205977277944;735.8528294341153;735.9852802943911;736.1497470421672;15892.682146357123;16199.35202591895;16292.42976495817;16795;16970.490885273462;14206.289685515683;14301.427962700582;14375.928120359436;14455;14591.270436478133;11576.36330001858;11761.823572646417;11926.403679815974;12031.338276394796;12423;3247.707706306444;3315.5026745988052;3348.7823291485975;3374.139594403436;3465.8786942457145;106831;107135.92864071338;109249.45375273153;110029.44985275023;110695.89304107017;10265;10652.786944261072;11105.83341249882;11292;11297.983181513704;21252.468688282606;22419.439514011952;22438.43903902383;22511;22782.860856957086;7641.3886889048745;7922.801929951797;7955.443118981616;8021.719239826578;8060.717874874351;4071.6539094176733;4154.646855017297;4156.542780294165;4161.396597493407;4183.226103170923;3411.2665776858094;3566.661167189113;3582.6954708849516;3586.8027258501033;3597.964020359763;679.2765704524666;682.7815099168218;688.9862202755917;694.1565997313288;698.5773606967712;663.4853903705;678.7081554931328;685.8251145957748;699.0702365853402;719.126261592168;10097.475631092228;10354.223433242538;10372.066514013624;10424.635137770141;10561.788764224673;652.5073569455126;663.6416335179046;669.8425869920565;677.695037233247;698.9231184569693;4847.418309802846;5070.239464080344;5085.898282034339;5095.337606111256;5107.54032137102;106072;107582.46208768905;111343.44328278306;112043;113679;79902;81374;89550.55224723835;94494.52752736192;95200;78949.42101158059;83154;83636;84370.57814711009;85200;7901.446898717035;8830.42602230853;9711.345928907253;9787.853182202414;9793.608255669693;46713.76643116763;53009.734951324994;53168.734156330516;53406;54114.72942635419;55192;59040.704796475744;59848;64020.67989660022;64594.677026616446;10406.739831504274;11295;12803.10378273512;13506.054576179573;13685.384157713126;14452.494162704255;14587.781183282046;14792.318972692034;14877.47928822486;15097.39610415571;13650.453981841012;13736.862631373559;13803.723925521634;13965.930170349084;13968.930155349159;12322.199057061622;12378.133530652767;12454.128211025503;12625.621231363073;13171.143875648053;11089.778204436028;13290.468381265027;13731.931340343235;13761.655958601115;13877.930610347288;9348.439093654402;9513.429194248367;9706.6117355305;9850.408975461496;9905.059019393146;9980.950095249478;10985.450727463434;11304.485875594242;11588.884111159117;11678.299302041565 02:28:48 INFO - PROCESS | 1909 | __end_tp_report 02:28:48 INFO - PROCESS | 1909 | __start_cc_report 02:28:48 INFO - PROCESS | 1909 | _x_x_mozilla_cycle_collect,419 02:28:48 INFO - PROCESS | 1909 | __end_cc_report 02:28:48 INFO - PROCESS | 1909 | __startTimestamp1490866128853__endTimestamp 02:28:48 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | ------- Summary: start ------- 02:28:48 INFO - PROCESS | 1909 | Number of tests: 6 02:28:48 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | [#0] dojo.html Cycles:165 Average:5965.08 Median:6225.34 stddev:658.97 (10.6%) stddev-sans-first:630.71 02:28:48 INFO - PROCESS | 1909 | Values: 3447.9 3514.2 3535.9 3546.0 3549.0 6019.5 6125.7 6133.7 6179.0 6182.4 6117.9 6191.4 6198.7 6200.3 6236.0 5482.6 5533.8 5537.5 5557.4 5623.0 5592.0 5603.7 5615.4 5625.7 5666.3 5394.2 5399.2 5514.9 5604.6 5621.5 5364.4 5608.1 5618.3 5653.8 5660.4 4592.6 5480.6 5526.4 5551.7 5566.4 5822.7 5948.6 6073.2 6142.8 6250.4 6306.6 6314.1 6314.3 6331.4 6357.4 6549.1 6563.8 6571.4 6579.8 6581.8 6203.5 6222.3 6233.5 6241.2 6256.8 5124.7 6368.7 6445.2 6464.6 6747.5 6285.6 6331.9 6336.5 6353.6 6392.6 6537.9 6563.1 6584.9 6603.6 6604.2 6255.2 6301.8 6493.1 6497.4 6513.0 5989.2 6259.4 6300.5 6356.4 6438.7 5174.1 5223.2 5224.8 5241.0 5266.2 4625.7 4632.2 4668.1 4674.8 4691.7 5978.8 6036.3 6232.1 6313.2 6352.2 5445.0 5515.2 6428.8 6569.0 6569.3 6225.3 6249.2 6265.4 6271.3 6271.6 5519.1 5760.8 5769.9 5773.1 5798.7 5051.3 6518.4 6571.0 6574.4 6648.2 5154.5 6481.7 6510.2 6549.4 6562.7 5611.4 6466.2 6497.6 6751.0 6811.4 5355.4 5903.8 5956.4 6208.9 6503.1 6048.5 6410.3 6525.0 6535.5 6549.5 5396.9 6308.1 6329.4 6335.8 6369.5 6217.6 6233.6 6242.0 6345.7 6351.0 4911.8 6151.9 6255.7 6261.0 6271.7 6136.6 6322.9 6334.5 6345.6 6358.0 6142.2 6146.3 6182.0 6277.1 6303.1 02:28:48 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | [#1] ext.html Cycles:180 Average:57227.20 Median:30473.74 stddev:120018.62 (393.8%) stddev-sans-first:120280.46 02:28:48 INFO - PROCESS | 1909 | Values: 775.0 799.3 830.7 834.3 956.8 37180.8 37975.4 38029.8 38980.2 39246.6 37668.5 37742.6 37793.6 37851.2 37852.7 7892.0 8028.5 10590.4 10863.0 10981.5 10579.3 10800.0 10809.7 10819.2 10833.2 10817.9 10818.1 10847.1 10847.5 10883.7 10761.2 10768.2 10809.4 10821.9 10840.9 10527.7 10560.3 10577.7 10594.9 10595.0 32503.8 32530.3 32735.9 32743.5 32776.5 3705.1 3746.2 3746.8 3761.5 3762.3 24475.5 24588.5 24602.0 25047.2 25791.9 3073.9 3087.6 3088.8 3091.5 3102.4 372590.0 377097.0 378261.0 379758.0 380035.1 44472.8 44488.3 44565.0 44627.6 44712.8 41136.8 41871.2 45467.0 45519.1 45998.0 11491.8 13347.9 13574.0 13793.9 13807.9 11872.4 11931.7 11942.5 11960.8 11979.5 10045.4 11236.5 11880.8 11916.6 11919.9 5057.2 5891.6 5975.3 5993.9 5996.2 1127.5 1132.4 1168.3 1173.7 1175.7 26610.8 26649.9 26788.3 26793.5 26977.9 1211.7 1239.6 1254.7 1280.9 1293.1 11623.0 13619.7 13813.0 13858.0 13930.8 502353.0 504632.0 505610.0 505693.0 506021.0 37586.0 44446.0 54817.7 55360.3 55587.4 1422.0 1453.2 1457.1 1471.6 1472.8 28437.0 29532.0 29851.7 30290.4 30372.0 45726.8 45787.0 45824.5 45851.8 46008.5 460134.0 460700.0 460972.0 462472.0 462710.0 30420.5 30712.2 31054.4 31100.2 31212.8 41687.6 42904.4 43329.6 43465.0 43826.6 29755.1 29777.4 29923.4 30204.2 30363.5 24481.0 29535.4 29543.9 30423.2 30524.2 34839.5 34989.1 35170.6 35352.3 35769.5 30004.5 30070.5 30140.0 30550.0 30902.0 22701.4 24173.4 24194.8 24513.9 24541.6 02:28:48 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | [#2] jquery.html Cycles:180 Average:44356.44 Median:21623.12 stddev:131023.81 (605.9%) stddev-sans-first:131349.81 02:28:48 INFO - PROCESS | 1909 | Values: 406.1 414.6 417.5 423.5 453.9 19492.6 20331.0 21239.4 21424.6 21599.0 21262.5 21884.9 21941.5 22004.0 22498.4 6369.5 6648.0 6664.4 6669.2 6680.7 6253.7 6375.2 6484.1 6487.5 6491.8 6446.9 6449.6 6467.0 6501.0 6502.4 6209.1 6435.7 6436.2 6439.4 6445.6 5610.2 6053.5 6311.5 6335.5 6386.4 26131.6 26349.2 26369.2 26569.5 27028.5 2846.3 2976.3 2979.5 2986.1 2986.5 16550.0 16627.8 16898.2 16992.3 17254.0 1726.4 1986.5 1998.2 1999.8 2013.0 169824.0 172840.4 174169.1 175278.0 178469.0 18038.5 19597.9 20191.8 21161.5 21394.9 30254.0 30464.8 30843.8 31052.5 31112.5 10244.4 10375.4 10568.9 10583.0 10660.6 8855.9 8882.8 8883.6 9067.8 9068.3 9379.3 9411.5 9551.1 9581.3 9617.4 3559.9 4011.3 4047.7 4148.6 4151.2 946.1 958.5 974.1 985.4 1002.4 14727.6 21601.6 21644.7 21648.2 21728.5 972.9 975.6 997.2 1000.8 1014.3 10875.4 10985.7 11068.7 11069.8 11106.9 775614.0 779672.0 780513.0 783523.0 801409.0 131367.0 154883.0 159544.0 160005.0 161914.0 1179.4 1203.2 1233.4 1235.5 1243.5 17830.6 18021.9 18168.7 18375.4 18389.8 46161.0 60516.7 62636.4 64080.0 64134.0 78061.0 85752.6 86829.6 87286.6 87906.0 11129.7 11510.4 11663.9 11867.8 11965.6 12816.0 12894.0 13056.7 13103.9 13203.4 10695.9 11099.3 11214.1 11238.3 11469.8 10357.0 10452.0 10523.6 10559.9 10755.2 11287.5 11400.9 11495.3 11561.9 11732.7 11572.1 11698.9 11858.8 11945.6 11955.8 10771.4 10872.2 10917.2 10946.1 11032.1 02:28:48 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | [#3] mootools.html Cycles:180 Average:6771.68 Median:2442.79 stddev:4237.45 (173.5%) stddev-sans-first:4229.04 02:28:48 INFO - PROCESS | 1909 | Values: 1251.9 1390.4 1479.2 1574.8 1595.9 9997.4 10366.0 10875.5 11357.5 11639.8 11164.8 11331.2 11350.8 11359.1 11364.1 2114.5 2285.6 2376.2 2409.3 2415.9 1987.3 2421.2 2441.7 2447.6 2447.9 2366.2 2406.5 2413.6 2428.1 2429.6 2342.7 2367.8 2389.2 2397.9 2443.9 2389.4 2404.1 2419.2 2425.9 2428.8 9464.6 11453.1 11472.4 11478.9 11507.0 5884.6 6053.2 6059.6 6071.3 6091.6 3519.5 3651.4 3697.5 3726.8 3754.0 1198.5 1207.6 1237.3 1238.3 1266.3 13754.0 13907.0 15040.4 15624.5 15730.5 4159.6 4388.9 4641.5 4818.9 4824.9 11813.7 12011.7 12013.8 12040.3 12049.0 5482.5 5486.6 5830.7 5927.3 5946.2 5246.5 5285.3 5302.8 5357.8 5364.5 3313.9 3481.0 3885.3 3980.0 4021.5 1017.6 1052.7 1058.8 1062.0 1064.0 897.8 935.9 944.3 954.6 995.7 6625.7 6675.2 6744.3 6893.1 6945.4 954.2 975.4 999.5 1022.9 1031.2 4065.0 4109.3 4328.8 4361.3 4389.0 13364.9 13961.6 13965.2 13975.5 14055.6 13366.0 13607.7 13843.0 13885.9 13937.4 8066.6 10169.0 10231.0 10276.2 10385.0 5537.8 6351.7 6859.8 7127.9 7214.5 11828.6 12135.0 12208.3 12384.4 12534.2 12599.6 12618.3 12775.0 12783.1 12843.1 8481.6 8606.7 8638.4 8649.4 8699.8 10278.8 10449.4 10467.6 10514.7 10538.3 8791.0 9005.2 9054.8 9103.8 9231.1 8782.6 8831.5 8871.2 8902.8 8909.7 7955.5 8130.2 8131.2 8133.3 8144.0 5890.9 5892.3 5897.6 6055.2 6104.9 5951.4 5978.8 6151.9 6164.8 6253.4 02:28:48 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | [#4] prototype.html Cycles:180 Average:4674.50 Median:5042.21 stddev:1256.18 (24.9%) stddev-sans-first:1217.37 02:28:48 INFO - PROCESS | 1909 | Values: 366.4 374.6 378.4 386.4 386.7 4838.9 5117.7 5133.4 5150.0 5257.5 4628.7 4814.8 4832.0 5021.2 5240.8 4007.6 4323.2 4402.2 4437.8 4529.6 4051.9 4335.0 4457.2 4547.4 4581.2 4234.4 4439.0 4567.2 4568.6 4596.1 3808.5 4158.8 4214.7 4387.7 4523.0 3652.5 4235.0 4266.7 4291.0 4371.2 4118.7 4317.4 4687.9 5059.3 5125.0 4694.8 4730.5 4939.9 5000.2 5312.5 4667.7 5057.8 5574.4 5606.8 5636.3 4391.8 4575.2 4699.3 4863.1 4888.9 4857.8 4954.1 5033.0 5632.2 5677.3 4291.6 4529.3 4651.8 4818.4 4887.3 4673.8 4874.1 5074.9 5113.1 5143.9 4408.9 4678.8 5160.8 5172.7 5225.3 5322.7 5354.5 5581.1 5601.7 5641.3 1460.5 1582.6 1768.4 1769.1 1773.1 1302.9 1334.6 1370.4 1429.4 1444.8 4614.3 4840.6 4991.0 5051.4 5251.4 5088.0 5403.6 5601.4 5627.9 5802.6 4496.7 5078.6 5278.4 5284.2 5300.3 2434.8 2592.2 2901.3 2922.8 2973.5 5236.6 5750.6 5950.9 5980.4 5983.5 5511.7 5626.6 5672.8 5895.0 5909.6 4814.9 5368.1 5799.9 5989.9 6013.2 5496.3 5597.9 5783.2 5812.9 5847.6 5128.4 5209.8 5284.4 5411.4 5533.8 6010.7 6012.8 6034.8 6059.2 6091.2 4360.4 4377.4 4437.6 4511.3 4534.4 5063.6 5097.1 5694.7 5744.2 5821.9 4540.5 5063.4 5173.9 5297.9 5632.4 4916.7 5114.3 5205.4 5419.5 5550.9 4523.8 4681.0 5227.2 5454.0 5477.3 5399.9 5460.9 5568.6 5573.3 5622.4 4386.7 4629.1 5223.7 5369.0 5443.6 02:28:48 INFO - PROCESS | 1909 | 02:28:48 INFO - PROCESS | 1909 | [#5] yui.html Cycles:180 Average:20797.55 Median:4155.59 stddev:30217.92 (727.2%) stddev-sans-first:30264.93 02:28:48 INFO - PROCESS | 1909 | Values: 679.0 731.3 767.9 815.1 823.1 12836.5 15139.5 15227.0 15974.4 16491.3 13728.7 16504.8 16660.3 17558.8 17687.3 943.8 967.0 969.8 970.0 971.6 909.6 954.3 959.3 964.1 964.9 996.5 996.6 999.0 999.3 999.4 992.2 992.5 992.7 992.8 994.8 729.5 733.4 735.9 736.0 736.1 15892.7 16199.4 16292.4 16795.0 16970.5 14206.3 14301.4 14375.9 14455.0 14591.3 11576.4 11761.8 11926.4 12031.3 12423.0 3247.7 3315.5 3348.8 3374.1 3465.9 106831.0 107135.9 109249.5 110029.4 110695.9 10265.0 10652.8 11105.8 11292.0 11298.0 21252.5 22419.4 22438.4 22511.0 22782.9 7641.4 7922.8 7955.4 8021.7 8060.7 4071.7 4154.6 4156.5 4161.4 4183.2 3411.3 3566.7 3582.7 3586.8 3598.0 679.3 682.8 689.0 694.2 698.6 663.5 678.7 685.8 699.1 719.1 10097.5 10354.2 10372.1 10424.6 10561.8 652.5 663.6 669.8 677.7 698.9 4847.4 5070.2 5085.9 5095.3 5107.5 106072.0 107582.5 111343.4 112043.0 113679.0 79902.0 81374.0 89550.6 94494.5 95200.0 78949.4 83154.0 83636.0 84370.6 85200.0 7901.4 8830.4 9711.3 9787.9 9793.6 46713.8 53009.7 53168.7 53406.0 54114.7 55192.0 59040.7 59848.0 64020.7 64594.7 10406.7 11295.0 12803.1 13506.1 13685.4 14452.5 14587.8 14792.3 14877.5 15097.4 13650.5 13736.9 13803.7 13965.9 13968.9 12322.2 12378.1 12454.1 12625.6 13171.1 11089.8 13290.5 13731.9 13761.7 13877.9 9348.4 9513.4 9706.6 9850.4 9905.1 9981.0 10985.5 11304.5 11588.9 11678.3 02:28:48 INFO - PROCESS | 1909 | -------- Summary: end -------- 02:28:48 INFO - PROCESS | 1909 | 02:28:49 INFO - TEST-INFO | 1909: exit 0 02:28:49 INFO - TEST-OK | dromaeo_css | took 1117159ms 02:28:49 INFO - TEST-START | kraken 02:28:49 INFO - operating with platform_type : mac_ 02:28:49 INFO - Initialising browser for kraken test... 02:28:49 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpqfokcg/profile http://localhost:49369/getInfo.html) 02:28:52 INFO - PROCESS | 1914 | 2017-03-30 02:28:52.213 plugin-container[1916:17012] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 02:28:52 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:52 INFO - PROCESS | 1914 | 2017-03-30 02:28:52.214 plugin-container[1916:17012] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 02:28:52 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:52 INFO - PROCESS | 1914 | 2017-03-30 02:28:52.214 plugin-container[1916:17012] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:52 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 02:28:52 INFO - PROCESS | 1914 | colorDepth:24 02:28:52 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 02:28:52 INFO - PROCESS | 1914 | __metrics 02:28:52 ERROR - PROCESS | 1914 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 02:28:53 INFO - TEST-INFO | 1914: exit 0 02:28:53 INFO - Browser initialized. 02:28:53 INFO - Running cycle 1/1 for kraken test... 02:28:53 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpqfokcg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:55 INFO - PROCESS | 1917 | 2017-03-30 02:28:55.755 plugin-container[1918:17141] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 02:28:55 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:55 INFO - PROCESS | 1917 | 2017-03-30 02:28:55.755 plugin-container[1918:17141] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 02:28:55 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:55 INFO - PROCESS | 1917 | 2017-03-30 02:28:55.755 plugin-container[1918:17141] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:05 INFO - PROCESS | 1917 | RSS: Main: 220925952 02:29:05 INFO - PROCESS | 1917 | 02:29:08 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49369/tests/kraken/driver.html?testName=audio-beat-detection) 02:29:09 INFO - PROCESS | 1917 | RSS: Main: 235311104 02:29:09 INFO - PROCESS | 1917 | 02:29:11 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49369/tests/kraken/driver.html?testName=audio-dft) 02:29:12 INFO - PROCESS | 1917 | RSS: Main: 243310592 02:29:12 INFO - PROCESS | 1917 | 02:29:15 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49369/tests/kraken/driver.html?testName=audio-fft) 02:29:16 INFO - PROCESS | 1917 | RSS: Main: 247017472 02:29:16 INFO - PROCESS | 1917 | 02:29:18 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49369/tests/kraken/driver.html?testName=audio-oscillator) 02:29:19 INFO - PROCESS | 1917 | RSS: Main: 247386112 02:29:19 INFO - PROCESS | 1917 | 02:29:21 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49369/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:29:21 INFO - PROCESS | 1917 | RSS: Main: 248766464 02:29:21 INFO - PROCESS | 1917 | 02:29:24 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49369/tests/kraken/driver.html?testName=imaging-darkroom) 02:29:25 INFO - PROCESS | 1917 | RSS: Main: 252694528 02:29:25 INFO - PROCESS | 1917 | 02:29:28 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49369/tests/kraken/driver.html?testName=imaging-desaturate) 02:29:29 INFO - PROCESS | 1917 | RSS: Main: 252489728 02:29:29 INFO - PROCESS | 1917 | 02:29:32 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49369/tests/kraken/driver.html?testName=json-parse-financial) 02:29:32 INFO - PROCESS | 1917 | RSS: Main: 252469248 02:29:32 INFO - PROCESS | 1917 | 02:29:34 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49369/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:29:35 INFO - PROCESS | 1917 | RSS: Main: 252170240 02:29:35 INFO - PROCESS | 1917 | 02:29:37 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:29:37 INFO - PROCESS | 1917 | RSS: Main: 252198912 02:29:37 INFO - PROCESS | 1917 | 02:29:39 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:29:40 INFO - PROCESS | 1917 | RSS: Main: 252239872 02:29:40 INFO - PROCESS | 1917 | 02:29:43 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:29:44 INFO - PROCESS | 1917 | RSS: Main: 252657664 02:29:44 INFO - PROCESS | 1917 | 02:29:47 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:29:47 INFO - PROCESS | 1917 | RSS: Main: 252801024 02:29:47 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49369/tests/kraken/driver.html?testName=ai-astar) 02:29:49 INFO - PROCESS | 1917 | RSS: Main: 254795776 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | __start_tp_report 02:29:49 INFO - PROCESS | 1917 | _x_x_mozilla_page_load 02:29:49 INFO - PROCESS | 1917 | _x_x_mozilla_page_load_details 02:29:49 INFO - PROCESS | 1917 | |i|pagename|runs| 02:29:49 INFO - PROCESS | 1917 | |0;ai-astar;116;131;99;145;172;174;122;120;120;120 02:29:49 INFO - PROCESS | 1917 | |1;audio-beat-detection;150;145;138;145;112;111;110;125;166;136 02:29:49 INFO - PROCESS | 1917 | |2;audio-dft;195;199;195;199;195;196;198;193;197;196 02:29:49 INFO - PROCESS | 1917 | |3;audio-fft;83;61;71;110;82;84;54;52;87;76 02:29:49 INFO - PROCESS | 1917 | |4;audio-oscillator;90;71;86;75;96;56;95;90;56;68 02:29:49 INFO - PROCESS | 1917 | |5;imaging-gaussian-blur;97;91;91;90;90;137;88;134;133;99 02:29:49 INFO - PROCESS | 1917 | |6;imaging-darkroom;97;98;98;100;97;98;99;101;96;99 02:29:49 INFO - PROCESS | 1917 | |7;imaging-desaturate;108;110;91;91;92;92;119;103;90;93 02:29:49 INFO - PROCESS | 1917 | |8;json-parse-financial;65;63;72;97;99;98;87;67;82;86 02:29:49 INFO - PROCESS | 1917 | |9;json-stringify-tinderbox;58;64;59;64;66;60;59;60;60;61 02:29:49 INFO - PROCESS | 1917 | |10;stanford-crypto-aes;83;85;85;83;85;80;83;83;90;88 02:29:49 INFO - PROCESS | 1917 | |11;stanford-crypto-ccm;154;128;147;176;147;150;147;147;184;153 02:29:49 INFO - PROCESS | 1917 | |12;stanford-crypto-pbkdf2;165;166;177;214;177;218;225;170;164;164 02:29:49 INFO - PROCESS | 1917 | |13;stanford-crypto-sha256-iterative;66;65;64;60;66;66;63;64;66;64 02:29:49 INFO - PROCESS | 1917 | __end_tp_report 02:29:49 INFO - PROCESS | 1917 | __start_cc_report 02:29:49 INFO - PROCESS | 1917 | _x_x_mozilla_cycle_collect,1017 02:29:49 INFO - PROCESS | 1917 | __end_cc_report 02:29:49 INFO - PROCESS | 1917 | __startTimestamp1490866189840__endTimestamp 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | ------- Summary: start ------- 02:29:49 INFO - PROCESS | 1917 | Number of tests: 14 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#0] ai-astar Cycles:10 Average:131.90 Median:138.00 stddev:24.52 (17.8%) stddev-sans-first:25.32 02:29:49 INFO - PROCESS | 1917 | Values: 116.0 131.0 99.0 145.0 172.0 174.0 122.0 120.0 120.0 120.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:141.50 stddev:18.89 (13.4%) stddev-sans-first:19.10 02:29:49 INFO - PROCESS | 1917 | Values: 150.0 145.0 138.0 145.0 112.0 111.0 110.0 125.0 166.0 136.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#2] audio-dft Cycles:10 Average:196.30 Median:196.50 stddev:1.95 (1.0%) stddev-sans-first:2.01 02:29:49 INFO - PROCESS | 1917 | Values: 195.0 199.0 195.0 199.0 195.0 196.0 198.0 193.0 197.0 196.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#3] audio-fft Cycles:10 Average:76.00 Median:79.00 stddev:17.44 (22.1%) stddev-sans-first:18.31 02:29:49 INFO - PROCESS | 1917 | Values: 83.0 61.0 71.0 110.0 82.0 84.0 54.0 52.0 87.0 76.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:88.00 stddev:15.24 (17.3%) stddev-sans-first:15.56 02:29:49 INFO - PROCESS | 1917 | Values: 90.0 71.0 86.0 75.0 96.0 56.0 95.0 90.0 56.0 68.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#5] imaging-gaussian-blur Cycles:10 Average:105.00 Median:90.50 stddev:20.76 (22.9%) stddev-sans-first:21.82 02:29:49 INFO - PROCESS | 1917 | Values: 97.0 91.0 91.0 90.0 90.0 137.0 88.0 134.0 133.0 99.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:98.00 stddev:1.49 (1.5%) stddev-sans-first:1.51 02:29:49 INFO - PROCESS | 1917 | Values: 97.0 98.0 98.0 100.0 97.0 98.0 99.0 101.0 96.0 99.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#7] imaging-desaturate Cycles:10 Average:98.90 Median:91.00 stddev:10.33 (11.4%) stddev-sans-first:10.42 02:29:49 INFO - PROCESS | 1917 | Values: 108.0 110.0 91.0 91.0 92.0 92.0 119.0 103.0 90.0 93.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#8] json-parse-financial Cycles:10 Average:81.60 Median:86.50 stddev:14.08 (16.3%) stddev-sans-first:13.59 02:29:49 INFO - PROCESS | 1917 | Values: 65.0 63.0 72.0 97.0 99.0 98.0 87.0 67.0 82.0 86.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:60.50 stddev:2.64 (4.4%) stddev-sans-first:2.55 02:29:49 INFO - PROCESS | 1917 | Values: 58.0 64.0 59.0 64.0 66.0 60.0 59.0 60.0 60.0 61.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:2.84 (3.3%) stddev-sans-first:2.96 02:29:49 INFO - PROCESS | 1917 | Values: 83.0 85.0 85.0 83.0 85.0 80.0 83.0 83.0 90.0 88.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#11] stanford-crypto-ccm Cycles:10 Average:153.30 Median:151.50 stddev:15.87 (10.5%) stddev-sans-first:16.84 02:29:49 INFO - PROCESS | 1917 | Values: 154.0 128.0 147.0 176.0 147.0 150.0 147.0 147.0 184.0 153.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.00 Median:177.00 stddev:24.76 (14.0%) stddev-sans-first:25.29 02:29:49 INFO - PROCESS | 1917 | Values: 165.0 166.0 177.0 214.0 177.0 218.0 225.0 170.0 164.0 164.0 02:29:49 INFO - PROCESS | 1917 | 02:29:49 INFO - PROCESS | 1917 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.50 stddev:1.90 (2.9%) stddev-sans-first:1.92 02:29:49 INFO - PROCESS | 1917 | Values: 66.0 65.0 64.0 60.0 66.0 66.0 63.0 64.0 66.0 64.0 02:29:49 INFO - PROCESS | 1917 | -------- Summary: end -------- 02:29:49 INFO - PROCESS | 1917 | 02:29:50 INFO - TEST-INFO | 1917: exit 0 02:29:50 INFO - TEST-OK | kraken | took 61244ms 02:29:50 INFO - SUITE-END | took 1178s 02:29:51 INFO - Completed test suite (00:19:38) 02:29:51 INFO - javascript benchmark 02:29:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7738.425649506448, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3447.9310413791723, 3514.24443744595, 3535.8585656573737, 3546.0425685065034, 3549.0417587251436, 6019.518438524919, 6125.724342404591, 6133.693315334238, 6178.969105154468, 6182.443580077792, 6117.938820611793, 6191.442770150686, 6198.659073750942, 6200.3179650238435, 6235.968820155904, 5482.588805839558, 5533.750981205855, 5537.501624853762, 5557.3886872444, 5622.987862184805, 5592.0, 5603.7478313475995, 5615.438456154372, 5625.71871406428, 5666.291713535808, 5394.244805727201, 5399.19012148179, 5514.91727624084, 5604.635698679573, 5621.465960733744, 5364.409914909357, 5608.102703567451, 5618.269624948751, 5653.802116925908, 5660.405657405995, 4592.563706447882, 5480.616356855022, 5526.447355264481, 5551.666899985973, 5566.415526369754, 5822.650640961556, 5948.583599148062, 6073.240844894388, 6142.815715528496, 6250.374962503759, 6306.621602703853, 6314.147590075327, 6314.336994615591, 6331.398517140864, 6357.427831495188, 6549.115869357671, 6563.835904102388, 6571.408573228432, 6579.80260592183, 6581.769638062693, 6203.503719702413, 6222.3466536013975, 6233.501319894398, 6241.219847519014, 6256.78101266458, 5124.692518448905, 6368.681565921685, 6445.2265728112925, 6464.579802312929, 6747.493937954673, 6285.622862628257, 6331.873362532723, 6336.461400780893, 6353.555251132469, 6392.584482008655, 6537.869242615121, 6563.113979612786, 6584.86830263392, 6603.570767900071, 6604.207495100619, 6255.155554000152, 6301.779437719658, 6493.123428337209, 6497.415232628991, 6513.0, 5989.161517387569, 6259.374062593704, 6300.495960323163, 6356.396142366468, 6438.7102580384, 5174.120399532088, 5223.164293712987, 5224.817131400383, 5241.030409374278, 5266.1574148136115, 4625.699329543569, 4632.166210082218, 4668.11305851887, 4674.766261686902, 4691.695039822401, 5978.790742323998, 6036.3360030396625, 6232.065190221503, 6313.2424109107205, 6352.205974253218, 5445.0, 5515.172724091338, 6428.77499287532, 6569.0, 6569.343065693392, 6225.343693963013, 6249.187605611241, 6265.404786545271, 6271.27880293763, 6271.592346497463, 5519.089350257243, 5760.798372056958, 5769.913451298234, 5773.134029895549, 5798.710064496759, 5051.292819005269, 6518.413342799171, 6570.967145164243, 6574.408303252731, 6648.235452922782, 5154.5103215194495, 6481.740730370921, 6510.18622672166, 6549.410553050248, 6562.704678289393, 5611.3806594746975, 6466.159399278252, 6497.610143391412, 6751.0, 6811.421029212374, 5355.357357117171, 5903.822885313448, 5956.434138756811, 6208.944479438416, 6503.089567460654, 6048.455638992512, 6410.326915673787, 6524.967375163284, 6535.477161826947, 6549.508786840911, 5396.865078372915, 6308.116863639186, 6329.398707122816, 6335.778247761306, 6369.522285828674, 6217.564770466024, 6233.563650544418, 6242.0, 6345.7144428499905, 6351.047342898602, 4911.778969946433, 6151.9234134027265, 6255.71849266775, 6261.029540421242, 6271.686415679106, 6136.58034916419, 6322.8735425290315, 6334.4932405406535, 6345.587536810092, 6358.046293056079, 6142.170806941184, 6146.293176284785, 6182.0, 6277.121203031488, 6303.149074875017], "value": 5928.122746891203, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.0002496779155, 799.3325573146423, 830.6511265268587, 834.3116928533962, 956.7942892278159, 37180.81409592955, 37975.43036854446, 38029.80985095074, 38980.22039559214, 39246.60753392465, 37668.49326026963, 37742.62257377426, 37793.62206377935, 37851.24297514048, 37852.675156369536, 7891.960540197291, 8028.478148920331, 10590.41752703601, 10863.0, 10981.505832237528, 10579.312344697608, 10800.0, 10809.729756756105, 10819.188560857929, 10833.187510936672, 10817.891821081766, 10818.134549236041, 10847.132229421666, 10847.511861966192, 10883.727906802354, 10761.192910531703, 10768.24622276441, 10809.405482698448, 10821.945890270577, 10840.891591084068, 10527.684169474927, 10560.26078174528, 10577.73555661107, 10594.894051059468, 10595.0, 32503.837480812683, 32530.349393012242, 32735.854245101305, 32743.50884736731, 32776.50835237473, 3705.1292946157632, 3746.157114649182, 3746.756460830037, 3761.4545890845948, 3762.285165818513, 24475.510489790282, 24588.508229835483, 24602.015919363384, 25047.248582542554, 25791.871040644677, 3073.938521229585, 3087.5831762711973, 3088.783785135019, 3091.474449343617, 3102.4260511805164, 372590.0, 377097.0, 378260.99999999726, 379758.0, 380035.0998245019, 44472.77763611161, 44488.3326750099, 44565.0, 44627.55372446299, 44712.77643611761, 41136.79431602823, 41871.16257674829, 45467.0, 45519.08961820745, 45998.0, 11491.77016459666, 13347.933260333637, 13574.0, 13793.931030344784, 13807.861921380658, 11872.40637968098, 11931.701707457209, 11942.522299108112, 11960.820587691192, 11979.520819167135, 10045.397276163454, 11236.494357753922, 11880.821787673192, 11916.642500724993, 11919.940400298117, 5057.204692334242, 5891.617044892068, 5975.312839023568, 5993.880122397528, 5996.190514280604, 1127.4813585750483, 1132.3602164776876, 1168.2873447197203, 1173.6831055614937, 1175.7119505721153, 26610.80251388692, 26649.941246176244, 26788.330291742863, 26793.464130717275, 26977.865110674713, 1211.7273613436905, 1239.6405042537563, 1254.7239607286383, 1280.8975281977418, 1293.0754510525019, 11623.0, 13619.659508512168, 13813.033087683765, 13858.0, 13930.791038134435, 502353.0, 504632.0, 505610.0, 505693.0, 506021.0, 37586.0, 44446.0, 54817.72591137019, 55360.33918982437, 55587.44412555823, 1422.0304877744193, 1453.1644304524941, 1457.1038811131077, 1471.6173794813212, 1472.7864459653504, 28437.0, 29532.0, 29851.70148298576, 30290.394192115593, 30372.0, 45726.77136614296, 45787.0, 45824.54175458203, 45851.77074114608, 46008.539914601766, 460134.0, 460700.0, 460972.0, 462472.0, 462710.0, 30420.543691844196, 30712.23219419443, 31054.37891242208, 31100.222494436915, 31212.843935780176, 41687.58312416837, 42904.35643465413, 43329.56670433381, 43465.0, 43826.561734383526, 29755.10734677963, 29777.404451911272, 29923.401531969674, 30204.24489387783, 30363.544546832258, 24481.0, 29535.409291814474, 29543.852280738458, 30423.239419014702, 30524.236894076937, 34839.47740783839, 34989.125271868405, 35170.64829351674, 35352.29295414129, 35769.46345804763, 30004.549931750604, 30070.54894176633, 30140.0, 30550.0, 30902.0, 22701.43246418853, 24173.395665108514, 24194.75805241925, 24513.89687464104, 24541.631875521525], "value": 18480.461755654847, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.11061774713374, 414.62476458804775, 417.54279064424446, 423.487580028166, 453.8933350662594, 19492.610147797037, 20331.0, 21239.362819115413, 21424.571508569818, 21599.0, 21262.468438289052, 21884.90575471228, 21941.451463713416, 22004.00981955812, 22498.43753906153, 6369.490440764739, 6648.036034774955, 6664.433523150538, 6669.166354205724, 6680.699368528428, 6253.749850005995, 6375.23497180339, 6484.092227088187, 6487.545871788953, 6491.772787952423, 6446.871062578745, 6449.613023218599, 6466.967665161667, 6501.02484627305, 6502.382273684016, 6209.068639704035, 6435.6782160892, 6436.195475565554, 6439.3882581155185, 6445.581037232589, 5610.186522954149, 6053.545984051214, 6311.52663550231, 6335.524835637346, 6386.42522173002, 26131.60802587963, 26349.20952371432, 26369.20892373232, 26569.46861062787, 27028.459430811272, 2846.274200078987, 2976.345204055126, 2979.508381117113, 2986.148947549938, 2986.462436761382, 16550.0, 16627.836051476403, 16898.15509224534, 16992.320307187696, 17254.0, 1726.4302780082542, 1986.4735845001087, 1998.1507859159944, 1999.800019998003, 2012.9899350503301, 169824.0, 172840.40739389046, 174169.12915435468, 175278.0, 178469.0, 18038.549036273937, 19597.902010489855, 20191.798082019286, 21161.470963226042, 21394.893025535082, 30254.0, 30464.84767576192, 30843.8457807714, 31052.534211986836, 31112.533312000338, 10244.436555989343, 10375.377477351383, 10568.894311056945, 10583.0, 10660.573577056832, 8855.911440885637, 8882.777930551789, 8883.600237989305, 9067.81864362709, 9068.319876009326, 9379.343445958853, 9411.529423528797, 9551.14039736427, 9581.329306948446, 9617.422954622745, 3559.875404360835, 4011.277969965376, 4047.6559492442884, 4148.62662360389, 4151.211269858778, 946.1153821177221, 958.4824194934797, 974.133021610767, 985.3890587835588, 1002.4085789384299, 14727.631809204642, 21601.567968640855, 21644.675329870064, 21648.242311519338, 21728.456788580097, 972.8783902012249, 975.5756246032998, 997.2420960070399, 1000.8098461292332, 1014.2697257974247, 10875.401852898009, 10985.725356865982, 11068.667939961813, 11069.833952490719, 11106.944465277784, 775614.0, 779672.0, 780513.0, 783523.0, 801409.0, 131367.0, 154883.0, 159544.0, 160005.0, 161914.0, 1179.4102948525738, 1203.1517779965104, 1233.3524899427873, 1235.493447686462, 1243.533674871923, 17830.554236143682, 18021.90989045099, 18168.72746908771, 18375.356862509747, 18389.816101839344, 46161.0, 60516.69741651264, 62636.37363626488, 64080.03879941898, 64134.0, 78061.0, 85752.57123714342, 86829.56585217286, 87286.5635671843, 87906.0, 11129.721756956142, 11510.446089777774, 11663.88336116628, 11867.821982670093, 11965.581204657796, 12816.0, 12894.03294752916, 13056.738865222833, 13103.934480327538, 13203.405846736729, 10695.94652026735, 11099.278546894426, 11214.102871770401, 11238.325700458, 11469.827952580885, 10357.0, 10452.0, 10523.631672891412, 10559.894401055892, 10755.193360497837, 11287.492062857027, 11400.885991139983, 11495.310281383145, 11561.942190288995, 11732.70668233301, 11572.074234061087, 11698.94150529242, 11858.822117668069, 11945.641630751092, 11955.76088478243, 10771.353718776898, 10872.184586155912, 10917.23579349438, 10946.1391563436, 11032.076499727738], "value": 11508.218263500385, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1251.912366134371, 1390.4090761426394, 1479.1568805780703, 1574.7874037005001, 1595.8803089768267, 9997.400155990637, 10366.0, 10875.456227188628, 11357.488912998915, 11639.767204655902, 11164.832527512093, 11331.150163737731, 11350.829737553922, 11359.1480638952, 11364.147688923344, 2114.4819519217813, 2285.645724912641, 2376.1564644551195, 2409.277216834951, 2415.867127307997, 1987.28457755208, 2421.1889017179265, 2441.682581264442, 2447.6206188040883, 2447.9143229986953, 2366.171839856045, 2406.506666133447, 2413.625887987356, 2428.1137384854487, 2429.5626787178303, 2342.672025916373, 2367.75138610446, 2389.2115601851347, 2397.9280621581293, 2443.890024948864, 2389.4265376309736, 2404.110479122736, 2419.237940048879, 2425.9393515162087, 2428.805695544352, 9464.621415143385, 11453.14101442395, 11472.426378681033, 11478.885211147865, 11507.0, 5884.588078834483, 6053.213082299273, 6059.575829691923, 6071.332153463116, 6091.634501929854, 3519.542459480251, 3651.379265524841, 3697.482352470656, 3726.795026273554, 3754.042719106638, 1198.478661782119, 1207.6256360528178, 1237.2700106936861, 1238.325112813519, 1266.2972050511962, 13754.0, 13907.0, 15040.398384064625, 15624.453144139901, 15730.528084157493, 4159.646430053419, 4388.912221755547, 4641.489436162019, 4818.927716084261, 4824.903501929942, 11813.704657383634, 12011.699707507381, 12013.819792703116, 12040.277583345027, 12049.0, 5482.479164479368, 5486.615936884459, 5830.650160990269, 5927.318358388754, 5946.167536544887, 5246.501582349671, 5285.339332583427, 5302.840914772563, 5357.7589008494715, 5364.517193452608, 3313.93372132556, 3481.0427132538753, 3885.339492286318, 3979.9851037985127, 4021.4973128358956, 1017.5624481472889, 1052.6684094510206, 1058.772363941756, 1062.0070234330926, 1064.0, 897.8473659477904, 935.854942483916, 944.3049725742309, 954.5561313989028, 995.690666773191, 6625.701843417058, 6675.198976122896, 6744.258131605519, 6893.069435626227, 6945.40964018054, 954.160338901762, 975.3708857786688, 999.4503023337136, 1022.8772547294373, 1031.2471895516314, 4064.9796751016056, 4109.2808758467745, 4328.783560821947, 4361.345798130243, 4388.956110438855, 13364.933175334256, 13961.581152565439, 13965.231912244924, 13975.510857119953, 14055.578332650037, 13366.0, 13607.659808504866, 13843.0, 13885.930570347286, 13937.372818223204, 8066.596670166586, 10169.0, 10231.0, 10276.177905767521, 10385.0, 5537.750801214036, 6351.745930162741, 6859.794206173823, 7127.85744285101, 7214.458915581457, 11828.585999489977, 12134.968527675246, 12208.32854193028, 12384.380780960735, 12534.247945123321, 12599.622011339674, 12618.305993170463, 12775.0, 12783.105182637126, 12843.100982931104, 8481.57592120404, 8606.698765543177, 8638.395312328077, 8649.437786543855, 8699.782505437415, 10278.794424111626, 10449.425281609585, 10467.633632822815, 10514.737131571772, 10538.315009524356, 8791.0, 9005.189532941804, 9054.818903622023, 9103.772405689911, 9231.123043310874, 8782.648694052166, 8831.470111793313, 8871.201591856763, 8902.777430564029, 8909.73270801877, 7955.4828936121285, 8130.186981301823, 8131.186881312058, 8133.2680058794995, 8144.0, 5890.911636325544, 5892.292924849045, 5897.587168898136, 6055.1522786809, 6104.938950610437, 5951.434613711691, 5978.8206353809455, 6151.9384806151365, 6164.753409863555, 6253.437190652863], "value": 5156.387968159757, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.36252919919315, 374.61601858095446, 378.36623655377235, 386.37407400011966, 386.7099675243567, 4838.879028024301, 5117.744112794359, 5133.435322114569, 5149.974250128754, 5257.526822585966, 4628.745419001953, 4814.8314808981695, 4831.975840120795, 5021.196608542634, 5240.8208153165515, 4007.5572793794213, 4323.162655871258, 4402.207602631525, 4437.84467543636, 4529.569690879362, 4051.9189616207805, 4334.97832510837, 4457.2171131547475, 4547.431136257986, 4581.244094724367, 4234.390931446062, 4438.9556104439025, 4567.200739870526, 4568.565986231302, 4596.149712303235, 3808.4668146459517, 4158.750474971512, 4214.683898707609, 4387.714798538085, 4522.9547704522865, 3652.4521321801676, 4235.047114399235, 4266.679999000087, 4291.0, 4371.23503386906, 4118.732282401635, 4317.41714868495, 4687.851476388307, 5059.2916991621205, 5125.0, 4694.788734506921, 4730.5032971538185, 4939.925901111486, 5000.199928025908, 5312.537468526456, 4667.719936803803, 5057.772400241998, 5574.442555744434, 5606.766511367533, 5636.267285252932, 4391.770304314797, 4575.199340115467, 4699.271612900006, 4863.057850000734, 4888.926666100011, 4857.8299759508245, 4954.076261842628, 5032.974835125801, 5632.183333416672, 5677.318721753416, 4291.6137547620865, 4529.252673308867, 4651.767411629405, 4818.44622350155, 4887.289448693, 4673.836415725434, 4874.073925954128, 5074.923876141861, 5113.122849767057, 5143.922841157386, 4408.911821763547, 4678.836240731558, 5160.761417259906, 5172.741362931838, 5225.346831646044, 5322.733863306897, 5354.54486368663, 5581.134924086773, 5601.719914004283, 5641.251212124254, 1460.5326295585523, 1582.6280824006349, 1768.3545505890258, 1769.0889192066097, 1773.140027086862, 1302.9055593301482, 1334.6196334044944, 1370.3624169117809, 1429.4496618801836, 1444.768836986077, 4614.2617181250835, 4840.564349208588, 4991.0267497838295, 5051.449018245762, 5251.422343542207, 5088.0, 5403.594730395236, 5601.411851755588, 5627.943720562742, 5802.622829516068, 4496.662750293741, 5078.568321692697, 5278.366596008426, 5284.233786100994, 5300.257963885059, 2434.831280985102, 2592.1705054382414, 2901.3181902252954, 2922.7661787056986, 2973.5022581032035, 5236.633435659467, 5750.6007903953505, 5950.851228719317, 5980.43185897339, 5983.491403230774, 5511.724413779295, 5626.606137570413, 5672.844319158693, 5894.970525147432, 5909.6158749681135, 4814.927776083432, 5368.147988943808, 5799.88400231993, 5989.880202396015, 6013.248343957005, 5496.285482887199, 5597.944020559742, 5783.2240524547615, 5812.94187058141, 5847.561432892635, 5128.410232823273, 5209.775702709784, 5284.418713941386, 5411.377691565521, 5533.7786488540005, 6010.732380010241, 6012.819615411545, 6034.818955431344, 6059.21230240066, 6091.238595175603, 4360.443336713079, 4377.365282034151, 4437.644988400855, 4511.323301504801, 4534.387857639308, 5063.620228482805, 5097.133487307241, 5694.715264236855, 5744.224529688438, 5821.912671309849, 4540.523245059353, 5063.392392912874, 5173.92239116406, 5297.88744363688, 5632.436756324499, 4916.664584322418, 5114.33513643224, 5205.3863302376385, 5419.512243898068, 5550.944490555043, 4523.84166554169, 4680.976595117003, 5227.210272552369, 5454.0, 5477.302036368738, 5399.892002160013, 5460.945390546202, 5568.582356323373, 5573.27547418833, 5622.353429355677, 4386.7367957922625, 4629.125204292267, 5223.701571308612, 5369.0, 5443.564514838883], "value": 4317.036587990766, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.9864202715946, 731.2650785960116, 767.9347255483284, 815.074929521737, 823.069930977995, 12836.48654053839, 15139.545813625595, 15227.0, 15974.361025558963, 16491.257893394795, 13728.656783580416, 16504.75242871355, 16660.250288737006, 17558.82441175588, 17687.29250829965, 943.76877664972, 967.0088159636358, 969.8351280282369, 970.0, 971.5870754929163, 909.6452383570413, 954.2509130332691, 959.2517836087862, 964.0793042644258, 964.9131578157965, 996.5166894056376, 996.611321584579, 999.0059890409017, 999.2905037423402, 999.4003597841273, 992.1864071461405, 992.4888682328607, 992.7369247149511, 992.7964767222702, 994.8259054665441, 729.5002922997711, 733.4205977277944, 735.8528294341153, 735.9852802943911, 736.1497470421672, 15892.682146357123, 16199.35202591895, 16292.42976495817, 16795.0, 16970.490885273462, 14206.289685515683, 14301.427962700582, 14375.928120359436, 14455.0, 14591.270436478133, 11576.36330001858, 11761.823572646417, 11926.403679815974, 12031.338276394796, 12423.0, 3247.707706306444, 3315.5026745988052, 3348.7823291485975, 3374.139594403436, 3465.8786942457145, 106831.0, 107135.92864071338, 109249.45375273153, 110029.44985275023, 110695.89304107017, 10265.0, 10652.786944261072, 11105.83341249882, 11292.0, 11297.983181513704, 21252.468688282606, 22419.439514011952, 22438.43903902383, 22511.0, 22782.860856957086, 7641.3886889048745, 7922.801929951797, 7955.443118981616, 8021.719239826578, 8060.717874874351, 4071.6539094176733, 4154.646855017297, 4156.542780294165, 4161.396597493407, 4183.226103170923, 3411.2665776858094, 3566.661167189113, 3582.6954708849516, 3586.8027258501033, 3597.964020359763, 679.2765704524666, 682.7815099168218, 688.9862202755917, 694.1565997313288, 698.5773606967712, 663.4853903705, 678.7081554931328, 685.8251145957748, 699.0702365853402, 719.126261592168, 10097.475631092228, 10354.223433242538, 10372.066514013624, 10424.635137770141, 10561.788764224673, 652.5073569455126, 663.6416335179046, 669.8425869920565, 677.695037233247, 698.9231184569693, 4847.418309802846, 5070.239464080344, 5085.898282034339, 5095.337606111256, 5107.54032137102, 106072.0, 107582.46208768905, 111343.44328278306, 112043.0, 113679.0, 79902.0, 81374.0, 89550.55224723835, 94494.52752736192, 95200.0, 78949.42101158059, 83154.0, 83636.0, 84370.57814711009, 85200.0, 7901.446898717035, 8830.42602230853, 9711.345928907253, 9787.853182202414, 9793.608255669693, 46713.76643116763, 53009.734951324994, 53168.734156330516, 53406.0, 54114.72942635419, 55192.0, 59040.704796475744, 59848.0, 64020.67989660022, 64594.677026616446, 10406.739831504274, 11295.0, 12803.10378273512, 13506.054576179573, 13685.384157713126, 14452.494162704255, 14587.781183282046, 14792.318972692034, 14877.47928822486, 15097.39610415571, 13650.453981841012, 13736.862631373559, 13803.723925521634, 13965.930170349084, 13968.930155349159, 12322.199057061622, 12378.133530652767, 12454.128211025503, 12625.621231363073, 13171.143875648053, 11089.778204436028, 13290.468381265027, 13731.931340343235, 13761.655958601115, 13877.930610347288, 9348.439093654402, 9513.429194248367, 9706.6117355305, 9850.408975461496, 9905.059019393146, 9980.950095249478, 10985.450727463434, 11304.485875594242, 11588.884111159117, 11678.299302041565], "value": 7650.801382803415, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 131.0, 99.0, 145.0, 172.0, 174.0, 122.0, 120.0, 120.0, 120.0], "value": 131.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 145.0, 138.0, 145.0, 112.0, 111.0, 110.0, 125.0, 166.0, 136.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 199.0, 195.0, 199.0, 195.0, 196.0, 198.0, 193.0, 197.0, 196.0], "value": 196.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 71.0, 110.0, 82.0, 84.0, 54.0, 52.0, 87.0, 76.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 86.0, 75.0, 96.0, 56.0, 95.0, 90.0, 56.0, 68.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 91.0, 91.0, 90.0, 90.0, 137.0, 88.0, 134.0, 133.0, 99.0], "value": 105.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 98.0, 100.0, 97.0, 98.0, 99.0, 101.0, 96.0, 99.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 91.0, 91.0, 92.0, 92.0, 119.0, 103.0, 90.0, 93.0], "value": 98.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 72.0, 97.0, 99.0, 98.0, 87.0, 67.0, 82.0, 86.0], "value": 81.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 59.0, 64.0, 66.0, 60.0, 59.0, 60.0, 60.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 85.0, 83.0, 85.0, 80.0, 83.0, 83.0, 90.0, 88.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 128.0, 147.0, 176.0, 147.0, 150.0, 147.0, 147.0, 184.0, 153.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 177.0, 214.0, 177.0, 218.0, 225.0, 170.0, 164.0, 164.0], "value": 184.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 60.0, 66.0, 66.0, 63.0, 64.0, 66.0, 64.0], "value": 64.4, "unit": "score"}]}]} 02:29:51 INFO - Return code: 0 02:29:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:29:51 ERROR - # TBPL SUCCESS # 02:29:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:51 INFO - 2017-03-30 02:29:51.162 screenresolution[1922:17517] starting screenresolution argv=screenresolution get 02:29:51 INFO - 2017-03-30 02:29:51.165 screenresolution[1922:17517] Display 0: 1600x1200x32@60 02:29:51 INFO - 2017-03-30 02:29:51.171 screenresolution[1923:17520] starting screenresolution argv=screenresolution list 02:29:51 INFO - Available Modes on Display 0 02:29:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:51 INFO - Intel Iris: 02:29:51 INFO - Chipset Model: Intel Iris 02:29:51 INFO - Type: GPU 02:29:51 INFO - Bus: Built-In 02:29:51 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:51 INFO - Vendor: Intel (0x8086) 02:29:51 INFO - Device ID: 0x0a2e 02:29:51 INFO - Revision ID: 0x0009 02:29:51 INFO - Displays: 02:29:51 INFO - Display: 02:29:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:51 INFO - Display Serial Number: 1600x1200 60 02:29:51 INFO - Main Display: Yes 02:29:51 INFO - Mirror: Off 02:29:51 INFO - Online: Yes 02:29:51 INFO - Rotation: Supported 02:29:51 INFO - Return code: 0 02:29:51 INFO - Running post-action listener: _resource_record_post_action 02:29:51 INFO - [mozharness: 2017-03-30 09:29:51.410180Z] Finished run-tests step (success) 02:29:51 INFO - Running post-run listener: _resource_record_post_run 02:29:51 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 2233344; Write bytes: 510980096; Read time: 20; Write time: 1623 02:29:51 INFO - TinderboxPrint: CPU usage
54.5% 02:29:51 INFO - TinderboxPrint: I/O read bytes / time
2,233,344 / 20 02:29:51 INFO - TinderboxPrint: I/O write bytes / time
510,980,096 / 1,623 02:29:51 INFO - TinderboxPrint: CPU idle
2,183.0 (45.5%) 02:29:51 INFO - TinderboxPrint: CPU system
963.4 (20.1%) 02:29:51 INFO - TinderboxPrint: CPU user
1,651.4 (34.4%) 02:29:51 INFO - TinderboxPrint: Swap in / out
211,664,896 / 0 02:29:51 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201351680; Write bytes: 199163904; Read time: 15847; Write time: 435 02:29:51 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 2233344; Write bytes: 301907968; Read time: 20; Write time: 1152 02:29:51 INFO - Running post-run listener: _upload_blobber_files 02:29:51 INFO - Blob upload gear active. 02:29:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:29:51 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 02:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:29:52 INFO - (blobuploader) - INFO - Open directory for files ... 02:29:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:29:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:29:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:52 INFO - (blobuploader) - INFO - Done attempting. 02:29:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:29:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:29:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:54 INFO - (blobuploader) - INFO - Done attempting. 02:29:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:29:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:29:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:55 INFO - (blobuploader) - INFO - Done attempting. 02:29:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:29:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:29:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:57 INFO - (blobuploader) - INFO - Done attempting. 02:29:57 INFO - (blobuploader) - INFO - Iteration through files over. 02:29:57 INFO - Return code: 0 02:29:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:29:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:29:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9fd851b29cdf4373263dba2217fcb6f50d2d7bf445d5181a5844f87ade43455ca2e53193f3940a9d5896ec9831d2bc9db390f6a8db0cab8645c0102ce5c10a5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fc5010790c9bdf1f9806ebf4f4df7bb017584f9a2c7b506f55bffe3f8250446a14e870f8e1422fe3ca6a81d2890fc9b3dc46c7a5491d82e5e2efd2083e82b40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/96c33dc5f014dbe8a5ef1786d65aa3efb3520cf3fd698c5a6470979dfda4c5d6183dc1c5d67d2954d5f8178fb4b6a7c3a5c3f48bd41df6efdb7cc934fd245ad8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c371d41f6d34132b3cea57117f8a7a002899500bc2ad7bf3464341216b667ee8a95c75d51348642199122fea92cf3f77d657581d064654f14b7f685cae6e676a"} 02:29:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:29:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:29:57 INFO - Contents: 02:29:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9fd851b29cdf4373263dba2217fcb6f50d2d7bf445d5181a5844f87ade43455ca2e53193f3940a9d5896ec9831d2bc9db390f6a8db0cab8645c0102ce5c10a5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fc5010790c9bdf1f9806ebf4f4df7bb017584f9a2c7b506f55bffe3f8250446a14e870f8e1422fe3ca6a81d2890fc9b3dc46c7a5491d82e5e2efd2083e82b40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/96c33dc5f014dbe8a5ef1786d65aa3efb3520cf3fd698c5a6470979dfda4c5d6183dc1c5d67d2954d5f8178fb4b6a7c3a5c3f48bd41df6efdb7cc934fd245ad8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c371d41f6d34132b3cea57117f8a7a002899500bc2ad7bf3464341216b667ee8a95c75d51348642199122fea92cf3f77d657581d064654f14b7f685cae6e676a"} 02:29:57 INFO - Running post-run listener: copy_logs_to_upload_dir 02:29:57 INFO - Copying logs to upload dir... 02:29:57 INFO - mkdir: /builds/slave/test/build/upload/logs 02:29:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1302.634000 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 42 secs) (at 2017-03-30 02:29:57.104483) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 02:29:57.108084) ========= 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.Age4WbNrVy/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.M6dA66oBZs/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9fd851b29cdf4373263dba2217fcb6f50d2d7bf445d5181a5844f87ade43455ca2e53193f3940a9d5896ec9831d2bc9db390f6a8db0cab8645c0102ce5c10a5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fc5010790c9bdf1f9806ebf4f4df7bb017584f9a2c7b506f55bffe3f8250446a14e870f8e1422fe3ca6a81d2890fc9b3dc46c7a5491d82e5e2efd2083e82b40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/96c33dc5f014dbe8a5ef1786d65aa3efb3520cf3fd698c5a6470979dfda4c5d6183dc1c5d67d2954d5f8178fb4b6a7c3a5c3f48bd41df6efdb7cc934fd245ad8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c371d41f6d34132b3cea57117f8a7a002899500bc2ad7bf3464341216b667ee8a95c75d51348642199122fea92cf3f77d657581d064654f14b7f685cae6e676a"} build_url:https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010990 build_url: 'https://queue.taskcluster.net/v1/task/cliBIH9eT96j4Xc6F6-D6w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9fd851b29cdf4373263dba2217fcb6f50d2d7bf445d5181a5844f87ade43455ca2e53193f3940a9d5896ec9831d2bc9db390f6a8db0cab8645c0102ce5c10a5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fc5010790c9bdf1f9806ebf4f4df7bb017584f9a2c7b506f55bffe3f8250446a14e870f8e1422fe3ca6a81d2890fc9b3dc46c7a5491d82e5e2efd2083e82b40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/96c33dc5f014dbe8a5ef1786d65aa3efb3520cf3fd698c5a6470979dfda4c5d6183dc1c5d67d2954d5f8178fb4b6a7c3a5c3f48bd41df6efdb7cc934fd245ad8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c371d41f6d34132b3cea57117f8a7a002899500bc2ad7bf3464341216b667ee8a95c75d51348642199122fea92cf3f77d657581d064654f14b7f685cae6e676a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 02:29:57.142739) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 02:29:57.143129) ========= 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.Age4WbNrVy/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.M6dA66oBZs/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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.005179 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 02:29:57.192599) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 02:29:57.192984) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 02:29:57.193386) ========= ========= Total master_lag: 0.19 =========