builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0015 starttime: 1482337837.15 results: success (0) buildid: 20161221061453 builduid: c51e07f878bb468f8d28bff96353b9d0 revision: 3e82bc16778aa475bc537506df9c0ae6c44518da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:37.147621) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:37.148069) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:37.148346) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:37.175166) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:37.175552) ========= 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.fJiQNmSlfC/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.d3qb8whAeX/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 --2016-12-21 08:30:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.91M=0.001s 2016-12-21 08:30:37 (9.91 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099348 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:37.300222) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:37.300677) ========= 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.fJiQNmSlfC/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.d3qb8whAeX/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.026870 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:37.349450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 08:30:37.349804) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3e82bc16778aa475bc537506df9c0ae6c44518da --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 3e82bc16778aa475bc537506df9c0ae6c44518da --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fJiQNmSlfC/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.d3qb8whAeX/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 2016-12-21 08:30:37,447 truncating revision to first 12 chars 2016-12-21 08:30:37,448 Setting DEBUG logging. 2016-12-21 08:30:37,448 attempt 1/10 2016-12-21 08:30:37,448 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3e82bc16778a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 08:30:38,637 unpacking tar archive at: mozilla-aurora-3e82bc16778a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.459965 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 08:30:38.833390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:38.833708) ========= script_repo_revision: 3e82bc16778aa475bc537506df9c0ae6c44518da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:38.834087) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:38.834365) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 08:30:38.854000) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-21 08:30:38.854408) ========= /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.fJiQNmSlfC/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.d3qb8whAeX/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:30:39 INFO - MultiFileLogger online at 20161221 08:30:39 in /builds/slave/test 08:30:39 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 08:30:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:39 INFO - {'append_to_log': False, 08:30:39 INFO - 'base_work_dir': '/builds/slave/test', 08:30:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:30:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:39 INFO - 'branch': 'Mozilla-Aurora', 08:30:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:39 INFO - 'config_files': ('talos/mac_config.py',), 08:30:39 INFO - 'default_actions': ('clobber', 08:30:39 INFO - 'read-buildbot-config', 08:30:39 INFO - 'download-and-extract', 08:30:39 INFO - 'populate-webroot', 08:30:39 INFO - 'create-virtualenv', 08:30:39 INFO - 'install', 08:30:39 INFO - 'run-tests'), 08:30:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:39 INFO - 'download_minidump_stackwalk': True, 08:30:39 INFO - 'download_symbols': 'ondemand', 08:30:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:39 INFO - 'installer_path': 'installer.exe', 08:30:39 INFO - 'log_level': 'info', 08:30:39 INFO - 'log_name': 'talos', 08:30:39 INFO - 'log_to_console': True, 08:30:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:30:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:30:39 INFO - 'opt_config_files': (), 08:30:39 INFO - 'pip_index': False, 08:30:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:39 INFO - 'cmd': ('bash', 08:30:39 INFO - '-c', 08:30:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:39 INFO - 'enabled': True, 08:30:39 INFO - 'halt_on_failure': False, 08:30:39 INFO - 'name': 'check_screen_resolution'},), 08:30:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:39 INFO - 'cmd': ('bash', 08:30:39 INFO - '-c', 08:30:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:39 INFO - 'enabled': True, 08:30:39 INFO - 'halt_on_failure': False, 08:30:39 INFO - 'name': 'check_screen_resolution'},), 08:30:39 INFO - 'run_cmd_checks_enabled': True, 08:30:39 INFO - 'sps_profile': False, 08:30:39 INFO - 'sps_profile_interval': 0, 08:30:39 INFO - 'suite': 'dromaeojs-e10s', 08:30:39 INFO - 'system_bits': '32', 08:30:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:39 INFO - 'title': 't-yosemite-r7-0015', 08:30:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:39 INFO - 'use_talos_json': True, 08:30:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:39 INFO - 'work_dir': 'build'} 08:30:39 INFO - [mozharness: 2016-12-21 16:30:39.066286Z] Running clobber step. 08:30:39 INFO - Running pre-action listener: _resource_record_pre_action 08:30:39 INFO - Running main action method: clobber 08:30:39 INFO - rmtree: /builds/slave/test/build 08:30:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:42 INFO - Running post-action listener: _resource_record_post_action 08:30:42 INFO - [mozharness: 2016-12-21 16:30:42.020818Z] Finished clobber step (success) 08:30:42 INFO - [mozharness: 2016-12-21 16:30:42.020949Z] Running read-buildbot-config step. 08:30:42 INFO - Running pre-action listener: _resource_record_pre_action 08:30:42 INFO - Running main action method: read_buildbot_config 08:30:42 INFO - Using buildbot properties: 08:30:42 INFO - { 08:30:42 INFO - "project": "", 08:30:42 INFO - "product": "firefox", 08:30:42 INFO - "script_repo_revision": "production", 08:30:42 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:30:42 INFO - "repository": "", 08:30:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:30:42 INFO - "buildid": "20161221061453", 08:30:42 INFO - "pgo_build": "False", 08:30:42 INFO - "basedir": "/builds/slave/test", 08:30:42 INFO - "buildnumber": 55, 08:30:42 INFO - "slavename": "t-yosemite-r7-0015", 08:30:42 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:30:42 INFO - "platform": "yosemite_r7", 08:30:42 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:30:42 INFO - "branch": "mozilla-aurora", 08:30:42 INFO - "repo_path": "releases/mozilla-aurora", 08:30:42 INFO - "revision": "3e82bc16778aa475bc537506df9c0ae6c44518da", 08:30:42 INFO - "stage_platform": "macosx64", 08:30:42 INFO - "builduid": "c51e07f878bb468f8d28bff96353b9d0", 08:30:42 INFO - "slavebuilddir": "test" 08:30:42 INFO - } 08:30:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:30:42 INFO - Running post-action listener: _resource_record_post_action 08:30:42 INFO - [mozharness: 2016-12-21 16:30:42.024075Z] Finished read-buildbot-config step (success) 08:30:42 INFO - [mozharness: 2016-12-21 16:30:42.024206Z] Running download-and-extract step. 08:30:42 INFO - Running pre-action listener: _resource_record_pre_action 08:30:42 INFO - Running main action method: download_and_extract 08:30:42 INFO - mkdir: /builds/slave/test/build/tests 08:30:42 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')]} 08:30:42 INFO - trying https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:30:42 INFO - Downloading https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:30:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:30:43 INFO - Downloaded 1199 bytes. 08:30:43 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:30:43 INFO - Using the following test package requirements: 08:30:43 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:30:43 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:43 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:30:43 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:43 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:30:43 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:43 INFO - u'jsshell-mac.zip'], 08:30:43 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:43 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:30:43 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:30:43 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:43 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:30:43 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:43 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:30:43 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:43 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:30:43 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:43 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:30:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:30:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:30:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:30:43 INFO - Fetch https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:30:43 INFO - Http code: 200 08:30:43 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 08:30:43 INFO - x-amz-cf-id: wUWOZF-zPtDH0Zp1iXDP5JsmoPmokb5R4nvai5AltFzlrxz3WsEunw== 08:30:43 INFO - x-amz-version-id: 158i8kZVz6pewoX5..VL9vOxMjhN0u4L 08:30:43 INFO - Expected file size: 16267629 08:30:43 INFO - Obtained file size: 16267629 08:30:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:30:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:30:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:30:50 INFO - Fetch https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:30:51 INFO - Http code: 200 08:30:51 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 08:30:51 INFO - x-amz-cf-id: BkvLdyFbh1bqnRoCLGd56EXw3x37-DCfgYRlR9nisB4Xm9kP-3frUg== 08:30:51 INFO - x-amz-version-id: 158i8kZVz6pewoX5..VL9vOxMjhN0u4L 08:30:51 INFO - Expected file size: 16267629 08:30:51 INFO - Obtained file size: 16267629 08:30:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:30:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:30:57 INFO - Fetch https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:30:58 INFO - Http code: 200 08:30:58 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 08:30:58 INFO - x-amz-cf-id: RgqUrBWviJ7MKbBf6fVvxxbGxoVf4OsQOz4-Qhw7neWcidIfI1-MTA== 08:30:58 INFO - x-amz-version-id: c1ZRDZZGwkH.HWe25Tc5qe5Qtz7IgKTL 08:30:59 INFO - Expected file size: 14409038 08:30:59 INFO - Obtained file size: 14409038 08:30:59 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')]} 08:30:59 INFO - trying https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:30:59 INFO - Downloading https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:30:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:31:02 INFO - Downloaded 109771162 bytes. 08:31:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:31:02 INFO - mkdir: /builds/slave/test/properties 08:31:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:31:02 INFO - Writing to file /builds/slave/test/properties/build_url 08:31:02 INFO - Contents: 08:31:02 INFO - build_url:https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:31:03 INFO - Running post-action listener: _resource_record_post_action 08:31:03 INFO - Running post-action listener: set_extra_try_arguments 08:31:03 INFO - [mozharness: 2016-12-21 16:31:03.489901Z] Finished download-and-extract step (success) 08:31:03 INFO - [mozharness: 2016-12-21 16:31:03.490029Z] Running populate-webroot step. 08:31:03 INFO - Running pre-action listener: _resource_record_pre_action 08:31:03 INFO - Running main action method: populate_webroot 08:31:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:31:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:31:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:31:03 INFO - '--tppagecycles', 08:31:03 INFO - '7'], 08:31:03 INFO - 'tests': ['tsvgm']}}, 08:31:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:31:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:31:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:31:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:31:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:03 INFO - 'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:03 INFO - 'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['damp', 'tps']}, 08:31:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:03 INFO - 'tests': ['damp', 'tps']}, 08:31:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['dromaeo_dom']}, 08:31:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:31:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['basic_compositor_video']}, 08:31:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:31:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['a11yr', 08:31:03 INFO - 'ts_paint', 08:31:03 INFO - 'tpaint', 08:31:03 INFO - 'sessionrestore', 08:31:03 INFO - 'sessionrestore_no_auto_restore', 08:31:03 INFO - 'tabpaint']}, 08:31:03 INFO - 'other-e10s': {'tests': ['a11yr', 08:31:03 INFO - 'ts_paint', 08:31:03 INFO - 'tpaint', 08:31:03 INFO - 'sessionrestore', 08:31:03 INFO - 'sessionrestore_no_auto_restore', 08:31:03 INFO - 'tabpaint']}, 08:31:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:31:03 INFO - 'ts_paint', 08:31:03 INFO - 'tpaint', 08:31:03 INFO - 'sessionrestore', 08:31:03 INFO - 'sessionrestore_no_auto_restore', 08:31:03 INFO - 'tabpaint']}, 08:31:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:31:03 INFO - 'ts_paint', 08:31:03 INFO - 'tpaint', 08:31:03 INFO - 'sessionrestore', 08:31:03 INFO - 'sessionrestore_no_auto_restore', 08:31:03 INFO - 'tabpaint']}, 08:31:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['a11yr', 08:31:03 INFO - 'ts_paint', 08:31:03 INFO - 'tpaint', 08:31:03 INFO - 'sessionrestore', 08:31:03 INFO - 'sessionrestore_no_auto_restore', 08:31:03 INFO - 'tabpaint']}, 08:31:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['a11yr', 08:31:03 INFO - 'ts_paint', 08:31:03 INFO - 'tpaint', 08:31:03 INFO - 'sessionrestore', 08:31:03 INFO - 'sessionrestore_no_auto_restore', 08:31:03 INFO - 'tabpaint']}, 08:31:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['tsvgx', 08:31:03 INFO - 'tsvgr_opacity', 08:31:03 INFO - 'tart', 08:31:03 INFO - 'tscrollx', 08:31:03 INFO - 'cart']}, 08:31:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:31:03 INFO - 'tsvgr_opacity', 08:31:03 INFO - 'tart', 08:31:03 INFO - 'tscrollx', 08:31:03 INFO - 'cart']}, 08:31:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:03 INFO - 'talos_options': ['--disable-e10s'], 08:31:03 INFO - 'tests': ['tp5o']}, 08:31:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:03 INFO - 'tests': ['tp5o']}, 08:31:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:03 INFO - 'talos_options': ['--disable-e10s', 08:31:03 INFO - '--xperf_path', 08:31:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:03 INFO - 'tests': ['tp5n']}, 08:31:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:03 INFO - 'talos_options': ['--xperf_path', 08:31:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:03 INFO - 'tests': ['tp5n']}}, 08:31:03 INFO - 'talos.zip': {'path': '', 08:31:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:31:03 INFO - Running post-action listener: _resource_record_post_action 08:31:03 INFO - [mozharness: 2016-12-21 16:31:03.508552Z] Finished populate-webroot step (success) 08:31:03 INFO - [mozharness: 2016-12-21 16:31:03.508656Z] Running create-virtualenv step. 08:31:03 INFO - Running pre-action listener: _resource_record_pre_action 08:31:03 INFO - Running main action method: create_virtualenv 08:31:03 INFO - Creating virtualenv /builds/slave/test/build/venv 08:31:03 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 08:31:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:31:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:31:03 INFO - Using real prefix '/tools/python27' 08:31:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:31:04 INFO - Installing distribute.............................................................................................................................................................................................done. 08:31:07 INFO - Installing pip.................done. 08:31:07 INFO - Return code: 0 08:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:31:07 INFO - Reading from file tmpfile_stdout 08:31:07 INFO - Output received: 08:31:07 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) 08:31:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:31:07 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')]} 08:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:07 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')]} 08:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31: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 08:31: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 08:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:07 INFO - 'HOME': '/Users/cltbld', 08:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:07 INFO - 'LOGNAME': 'cltbld', 08:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:07 INFO - 'MOZ_NO_REMOTE': '1', 08:31:07 INFO - 'NO_EM_RESTART': '1', 08:31:07 INFO - 'PAGER': '/bin/cat', 08:31:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:07 INFO - 'PWD': '/builds/slave/test', 08:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:07 INFO - 'SHELL': '/bin/bash', 08:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:07 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:07 INFO - 'USER': 'cltbld', 08:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:07 INFO - 'XPC_FLAGS': '0x0', 08:31:07 INFO - 'XPC_SERVICE_NAME': '0', 08:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:07 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 08:31:07 INFO - Cleaning up... 08:31:07 INFO - Return code: 0 08:31:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:31:07 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')]} 08:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:07 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')]} 08:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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', '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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:31: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 psutil>=3.1.1 08:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:07 INFO - 'HOME': '/Users/cltbld', 08:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:07 INFO - 'LOGNAME': 'cltbld', 08:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:07 INFO - 'MOZ_NO_REMOTE': '1', 08:31:07 INFO - 'NO_EM_RESTART': '1', 08:31:07 INFO - 'PAGER': '/bin/cat', 08:31:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:07 INFO - 'PWD': '/builds/slave/test', 08:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:07 INFO - 'SHELL': '/bin/bash', 08:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:07 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:07 INFO - 'USER': 'cltbld', 08:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:07 INFO - 'XPC_FLAGS': '0x0', 08:31:07 INFO - 'XPC_SERVICE_NAME': '0', 08:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:08 INFO - Downloading/unpacking psutil>=3.1.1 08:31:08 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 08:31:08 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 08:31:08 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 08:31:08 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 08:31:08 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 08:31:08 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 08:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:31:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:11 INFO - Installing collected packages: psutil 08:31:11 INFO - Running setup.py install for psutil 08:31:11 INFO - building 'psutil._psutil_osx' extension 08:31:11 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 08:31:12 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 08:31:12 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 08:31:12 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 08:31:12 INFO - building 'psutil._psutil_posix' extension 08:31:12 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 08:31:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:31:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:31:12 INFO - ^ 08:31:12 INFO - 1 warning generated. 08:31:12 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 08:31:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:12 INFO - Successfully installed psutil 08:31:12 INFO - Cleaning up... 08:31:12 INFO - Return code: 0 08:31:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:31: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')]} 08:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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')]} 08:31:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:12 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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31:12 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 08:31:12 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 08:31:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:12 INFO - 'HOME': '/Users/cltbld', 08:31:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:12 INFO - 'LOGNAME': 'cltbld', 08:31:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:12 INFO - 'MOZ_NO_REMOTE': '1', 08:31:12 INFO - 'NO_EM_RESTART': '1', 08:31:12 INFO - 'PAGER': '/bin/cat', 08:31:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:12 INFO - 'PWD': '/builds/slave/test', 08:31:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:12 INFO - 'SHELL': '/bin/bash', 08:31:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:12 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:12 INFO - 'USER': 'cltbld', 08:31:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:12 INFO - 'XPC_FLAGS': '0x0', 08:31:12 INFO - 'XPC_SERVICE_NAME': '0', 08:31:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:31:12 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 08:31:12 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 08:31:12 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 08:31:12 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 08:31:12 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 08:31:12 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 08:31:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:31:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:31:15 INFO - Installing collected packages: mozsystemmonitor 08:31:15 INFO - Running setup.py install for mozsystemmonitor 08:31:15 INFO - Successfully installed mozsystemmonitor 08:31:15 INFO - Cleaning up... 08:31:15 INFO - Return code: 0 08:31:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:31:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:15 INFO - 'HOME': '/Users/cltbld', 08:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:15 INFO - 'LOGNAME': 'cltbld', 08:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:15 INFO - 'MOZ_NO_REMOTE': '1', 08:31:15 INFO - 'NO_EM_RESTART': '1', 08:31:15 INFO - 'PAGER': '/bin/cat', 08:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:15 INFO - 'PWD': '/builds/slave/test', 08:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:15 INFO - 'SHELL': '/bin/bash', 08:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:15 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:15 INFO - 'USER': 'cltbld', 08:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:15 INFO - 'XPC_FLAGS': '0x0', 08:31:15 INFO - 'XPC_SERVICE_NAME': '0', 08:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:16 INFO - Downloading/unpacking jsonschema==2.5.1 08:31:16 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 08:31:16 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 08:31:16 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 08:31:16 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 08:31:16 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 08:31:16 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 08:31:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:31:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:31:18 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 08:31:18 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 08:31:18 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 08:31:18 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 08:31:18 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 08:31:18 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 08:31:19 INFO - Downloading functools32-3.2.3-2.tar.gz 08:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:31:19 INFO - warning: no files found matching '*.txt' 08:31:19 INFO - no previously-included directories found matching 'build' 08:31:19 INFO - no previously-included directories found matching 'dist' 08:31:19 INFO - no previously-included directories found matching '.git*' 08:31:19 INFO - Installing collected packages: jsonschema, functools32 08:31:19 INFO - Running setup.py install for functools32 08:31:19 INFO - warning: no files found matching '*.txt' 08:31:19 INFO - no previously-included directories found matching 'build' 08:31:19 INFO - no previously-included directories found matching 'dist' 08:31:19 INFO - no previously-included directories found matching '.git*' 08:31:19 INFO - Successfully installed jsonschema functools32 08:31:19 INFO - Cleaning up... 08:31:19 INFO - Return code: 0 08:31:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:31: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')]} 08:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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')]} 08:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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', '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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:31: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 functools32==3.2.3-2 08:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:19 INFO - 'HOME': '/Users/cltbld', 08:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:19 INFO - 'LOGNAME': 'cltbld', 08:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:19 INFO - 'MOZ_NO_REMOTE': '1', 08:31:19 INFO - 'NO_EM_RESTART': '1', 08:31:19 INFO - 'PAGER': '/bin/cat', 08:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:19 INFO - 'PWD': '/builds/slave/test', 08:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:19 INFO - 'SHELL': '/bin/bash', 08:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:19 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:19 INFO - 'USER': 'cltbld', 08:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:19 INFO - 'XPC_FLAGS': '0x0', 08:31:19 INFO - 'XPC_SERVICE_NAME': '0', 08:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:31:19 INFO - Cleaning up... 08:31:19 INFO - Return code: 0 08:31:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:31: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')]} 08:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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')]} 08:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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', '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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:31: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 blobuploader==1.2.4 08:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:19 INFO - 'HOME': '/Users/cltbld', 08:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:19 INFO - 'LOGNAME': 'cltbld', 08:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:19 INFO - 'MOZ_NO_REMOTE': '1', 08:31:19 INFO - 'NO_EM_RESTART': '1', 08:31:19 INFO - 'PAGER': '/bin/cat', 08:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:19 INFO - 'PWD': '/builds/slave/test', 08:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:19 INFO - 'SHELL': '/bin/bash', 08:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:19 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:19 INFO - 'USER': 'cltbld', 08:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:19 INFO - 'XPC_FLAGS': '0x0', 08:31:19 INFO - 'XPC_SERVICE_NAME': '0', 08:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:20 INFO - Downloading/unpacking blobuploader==1.2.4 08:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:22 INFO - Downloading blobuploader-1.2.4.tar.gz 08:31:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:31:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:31:22 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 08:31:22 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 08:31:22 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 08:31:22 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 08:31:22 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 08:31:22 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 08:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:31:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:31: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 08:31: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 08:31: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 08:31: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 08:31: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 08:31: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 08:31:23 INFO - Downloading docopt-0.6.1.tar.gz 08:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:31:24 INFO - Installing collected packages: blobuploader, requests, docopt 08:31:24 INFO - Running setup.py install for blobuploader 08:31:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:31:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:31:24 INFO - Running setup.py install for requests 08:31:24 INFO - Running setup.py install for docopt 08:31:24 INFO - Successfully installed blobuploader requests docopt 08:31:24 INFO - Cleaning up... 08:31:24 INFO - Return code: 0 08:31:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:24 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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31:24 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 08:31:24 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 08:31:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:24 INFO - 'HOME': '/Users/cltbld', 08:31:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:24 INFO - 'LOGNAME': 'cltbld', 08:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:24 INFO - 'MOZ_NO_REMOTE': '1', 08:31:24 INFO - 'NO_EM_RESTART': '1', 08:31:24 INFO - 'PAGER': '/bin/cat', 08:31:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:24 INFO - 'PWD': '/builds/slave/test', 08:31:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:24 INFO - 'SHELL': '/bin/bash', 08:31:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:24 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:24 INFO - 'USER': 'cltbld', 08:31:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:24 INFO - 'XPC_FLAGS': '0x0', 08:31:24 INFO - 'XPC_SERVICE_NAME': '0', 08:31:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:25 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-3nZdks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:25 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-8W78mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:25 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-zSKgTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:25 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-HtoLz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:25 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-N2_Sen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:25 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-tro01L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:25 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Y3odhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:25 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-pLodYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-bfocfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-jrnHmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-fMJD00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-_QM6Ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-__66wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-i2jDAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-FpRciN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-YkwJyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-nnEm5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:31:27 INFO - Running setup.py install for manifestparser 08:31:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:31:27 INFO - Running setup.py install for mozcrash 08:31:27 INFO - Running setup.py install for mozdebug 08:31:27 INFO - Running setup.py install for mozdevice 08:31:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:31:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:31:27 INFO - Running setup.py install for mozfile 08:31:27 INFO - Running setup.py install for mozhttpd 08:31:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:31:27 INFO - Running setup.py install for mozinfo 08:31:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:31:28 INFO - Running setup.py install for mozInstall 08:31:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:31:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:31:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:31:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:31:28 INFO - Running setup.py install for mozleak 08:31:28 INFO - Running setup.py install for mozlog 08:31:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:31:28 INFO - Running setup.py install for moznetwork 08:31:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:31:28 INFO - Running setup.py install for mozprocess 08:31:28 INFO - Running setup.py install for mozprofile 08:31:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:31:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:31:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:31:29 INFO - Running setup.py install for mozrunner 08:31:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:31:29 INFO - Running setup.py install for mozscreenshot 08:31:29 INFO - Running setup.py install for moztest 08:31:29 INFO - Running setup.py install for mozversion 08:31:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:31:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:31:29 INFO - Cleaning up... 08:31:29 INFO - Return code: 0 08:31:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:29 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')]} 08:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:29 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')]} 08:31:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:29 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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31:29 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 08:31:29 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 08:31:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:29 INFO - 'HOME': '/Users/cltbld', 08:31:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:29 INFO - 'LOGNAME': 'cltbld', 08:31:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:29 INFO - 'MOZ_NO_REMOTE': '1', 08:31:29 INFO - 'NO_EM_RESTART': '1', 08:31:29 INFO - 'PAGER': '/bin/cat', 08:31:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:29 INFO - 'PWD': '/builds/slave/test', 08:31:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:29 INFO - 'SHELL': '/bin/bash', 08:31:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:29 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:29 INFO - 'USER': 'cltbld', 08:31:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:29 INFO - 'XPC_FLAGS': '0x0', 08:31:29 INFO - 'XPC_SERVICE_NAME': '0', 08:31:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:29 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-m4nKUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-6WWGXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-deL8BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-bMdbF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-pOW0nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-OS8gae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-_qV3oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-HF8d6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-9KYTx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-xen0hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:30 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)) 08:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-A9k1S6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:31 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)) 08:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-VOa_VU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-3UBcDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:31 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)) 08:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-g2wsFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:31 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)) 08:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-ER2cEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:31 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)) 08:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-5mAWuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 08:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-wwYYBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:31 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)) 08:31:31 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)) 08:31:31 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)) 08:31:31 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)) 08:31:31 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:31:31 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:31:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31: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 08:31: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 08:31: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 08:31: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 08:31: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 08:31: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 08:31:34 INFO - Downloading blessings-1.6.tar.gz 08:31:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:31:34 INFO - Installing collected packages: blessings 08:31:34 INFO - Running setup.py install for blessings 08:31:34 INFO - Successfully installed blessings 08:31:34 INFO - Cleaning up... 08:31:34 INFO - Return code: 0 08:31:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:31:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:35 INFO - Reading from file tmpfile_stdout 08:31:35 INFO - Current package versions: 08:31:35 INFO - blessings == 1.6 08:31:35 INFO - blobuploader == 1.2.4 08:31:35 INFO - docopt == 0.6.1 08:31:35 INFO - functools32 == 3.2.3-2 08:31:35 INFO - jsonschema == 2.5.1 08:31:35 INFO - manifestparser == 1.1 08:31:35 INFO - mozInstall == 1.12 08:31:35 INFO - mozcrash == 1.0 08:31:35 INFO - mozdebug == 0.1 08:31:35 INFO - mozdevice == 0.48 08:31:35 INFO - mozfile == 1.2 08:31:35 INFO - mozhttpd == 0.7 08:31:35 INFO - mozinfo == 0.9 08:31:35 INFO - mozleak == 0.1 08:31:35 INFO - mozlog == 3.4 08:31:35 INFO - moznetwork == 0.27 08:31:35 INFO - mozprocess == 0.23 08:31:35 INFO - mozprofile == 0.28 08:31:35 INFO - mozrunner == 6.13 08:31:35 INFO - mozscreenshot == 0.1 08:31:35 INFO - mozsystemmonitor == 0.3 08:31:35 INFO - moztest == 0.7 08:31:35 INFO - mozversion == 1.4 08:31:35 INFO - psutil == 3.1.1 08:31:35 INFO - requests == 1.2.3 08:31:35 INFO - wsgiref == 0.1.2 08:31:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:35 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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31:35 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 08:31:35 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 08:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:35 INFO - 'HOME': '/Users/cltbld', 08:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:35 INFO - 'LOGNAME': 'cltbld', 08:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:35 INFO - 'MOZ_NO_REMOTE': '1', 08:31:35 INFO - 'NO_EM_RESTART': '1', 08:31:35 INFO - 'PAGER': '/bin/cat', 08:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:35 INFO - 'PWD': '/builds/slave/test', 08:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:35 INFO - 'SHELL': '/bin/bash', 08:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:35 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:35 INFO - 'USER': 'cltbld', 08:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:35 INFO - 'XPC_FLAGS': '0x0', 08:31:35 INFO - 'XPC_SERVICE_NAME': '0', 08:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 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)) 08:31:36 INFO - Cleaning up... 08:31:36 INFO - Return code: 0 08:31:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:31:36 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')]} 08:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:36 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')]} 08:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:36 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 0x10eb7c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a0d737d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, '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.fJiQNmSlfC/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.d3qb8whAeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 08:31:36 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 08:31:36 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 08:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:36 INFO - 'HOME': '/Users/cltbld', 08:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:36 INFO - 'LOGNAME': 'cltbld', 08:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:36 INFO - 'MOZ_NO_REMOTE': '1', 08:31:36 INFO - 'NO_EM_RESTART': '1', 08:31:36 INFO - 'PAGER': '/bin/cat', 08:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:36 INFO - 'PWD': '/builds/slave/test', 08:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:36 INFO - 'SHELL': '/bin/bash', 08:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:31:36 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:36 INFO - 'USER': 'cltbld', 08:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:36 INFO - 'XPC_FLAGS': '0x0', 08:31:36 INFO - 'XPC_SERVICE_NAME': '0', 08:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:31:36 INFO - Cleaning up... 08:31:36 INFO - Return code: 0 08:31:36 INFO - Running post-action listener: _resource_record_post_action 08:31:36 INFO - Running post-action listener: _start_resource_monitoring 08:31:36 INFO - Starting resource monitoring. 08:31:36 INFO - [mozharness: 2016-12-21 16:31:36.781203Z] Finished create-virtualenv step (success) 08:31:36 INFO - [mozharness: 2016-12-21 16:31:36.782163Z] Running install step. 08:31:36 INFO - Running pre-action listener: _resource_record_pre_action 08:31:36 INFO - Running main action method: install 08:31:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:37 INFO - Reading from file tmpfile_stdout 08:31:37 INFO - Detecting whether we're running mozinstall >=1.0... 08:31:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:31:37 INFO - Reading from file tmpfile_stdout 08:31:37 INFO - Output received: 08:31:37 INFO - Usage: mozinstall [options] installer 08:31:37 INFO - Options: 08:31:37 INFO - -h, --help show this help message and exit 08:31:37 INFO - -d DEST, --destination=DEST 08:31:37 INFO - Directory to install application into. [default: 08:31:37 INFO - "/builds/slave/test"] 08:31:37 INFO - --app=APP Application being installed. [default: firefox] 08:31:37 INFO - mkdir: /builds/slave/test/build/application 08:31:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:32:08 INFO - Reading from file tmpfile_stdout 08:32:08 INFO - Output received: 08:32:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:32:08 INFO - Running post-action listener: _resource_record_post_action 08:32:08 INFO - [mozharness: 2016-12-21 16:32:08.961435Z] Finished install step (success) 08:32:08 INFO - [mozharness: 2016-12-21 16:32:08.961595Z] Running run-tests step. 08:32:08 INFO - Running pre-action listener: _resource_record_pre_action 08:32:08 INFO - Running main action method: run_tests 08:32:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:32:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:32:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:32:08 INFO - 2016-12-21 08:32:08.976 screenresolution[1630:7398] starting screenresolution argv=screenresolution get 08:32:08 INFO - 2016-12-21 08:32:08.978 screenresolution[1630:7398] Display 0: 1600x1200x32@60 08:32:08 INFO - 2016-12-21 08:32:08.984 screenresolution[1631:7400] starting screenresolution argv=screenresolution list 08:32:08 INFO - Available Modes on Display 0 08:32:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:32:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:32:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:32:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:32:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:32:09 INFO - Intel Iris: 08:32:09 INFO - Chipset Model: Intel Iris 08:32:09 INFO - Type: GPU 08:32:09 INFO - Bus: Built-In 08:32:09 INFO - VRAM (Dynamic, Max): 1536 MB 08:32:09 INFO - Vendor: Intel (0x8086) 08:32:09 INFO - Device ID: 0x0a2e 08:32:09 INFO - Revision ID: 0x0009 08:32:09 INFO - Displays: 08:32:09 INFO - Display: 08:32:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:32:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:32:09 INFO - Display Serial Number: 1600x1200 60 08:32:09 INFO - Main Display: Yes 08:32:09 INFO - Mirror: Off 08:32:09 INFO - Online: Yes 08:32:09 INFO - Rotation: Supported 08:32:09 INFO - Return code: 0 08:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:32:09 INFO - Python 2.7.3 08:32:09 INFO - Return code: 0 08:32:09 INFO - grabbing minidump binary from tooltool 08:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:09 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 0x10e990d30>, '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 0x10e7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e988b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:32:09 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 08:32:09 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 08:32:09 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 08:32:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:32:09 INFO - Return code: 0 08:32:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:32:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:32:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:32:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:32:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:32:09 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/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 08:32:09 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/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0015 --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 08:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJiQNmSlfC/Render', 08:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:09 INFO - 'HOME': '/Users/cltbld', 08:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:09 INFO - 'LOGNAME': 'cltbld', 08:32:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:32:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:09 INFO - 'MOZ_NO_REMOTE': '1', 08:32:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:32:09 INFO - 'NO_EM_RESTART': '1', 08:32:09 INFO - 'PAGER': '/bin/cat', 08:32:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:09 INFO - 'PWD': '/builds/slave/test', 08:32:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:09 INFO - 'SHELL': '/bin/bash', 08:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3qb8whAeX/Listeners', 08:32:09 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 08:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:09 INFO - 'USER': 'cltbld', 08:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:09 INFO - 'XPC_FLAGS': '0x0', 08:32:09 INFO - 'XPC_SERVICE_NAME': '0', 08:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:09 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/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 08:32:09 INFO - mozversion application_buildid: 20161221061453 08:32:09 INFO - mozversion application_changeset: 3e82bc16778aa475bc537506df9c0ae6c44518da 08:32:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:32:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:32:09 INFO - mozversion application_name: Firefox 08:32:09 INFO - mozversion application_remotingname: firefox-dev 08:32:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:32:09 INFO - mozversion application_vendor: Mozilla 08:32:09 INFO - mozversion application_version: 52.0a2 08:32:09 INFO - mozversion platform_buildid: 20161221061453 08:32:09 INFO - mozversion platform_changeset: 3e82bc16778aa475bc537506df9c0ae6c44518da 08:32:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:32:09 INFO - mozversion platform_version: 52.0a2 08:32:09 INFO - using testdate: 1482337929 08:32:09 INFO - actual date: 1482337929 08:32:09 INFO - starting webserver on 'localhost:49241' 08:32:09 INFO - SUITE-START | Running 2 tests 08:32:09 INFO - TEST-START | dromaeo_css 08:32:09 INFO - operating with platform_type : mac_ 08:32:09 INFO - Initialising browser for dromaeo_css test... 08:32:09 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpRF1uWf/profile http://localhost:49241/getInfo.html) 08:32:12 INFO - PROCESS | 1641 | 2016-12-21 08:32:12.547 plugin-container[1643:7578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 08:32:12 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:32:12 INFO - PROCESS | 1641 | 2016-12-21 08:32:12.552 plugin-container[1643:7578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x472b, name = 'com.apple.CFPasteboardClient' 08:32:12 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:32:12 INFO - PROCESS | 1641 | 2016-12-21 08:32:12.552 plugin-container[1643:7578] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:32:12 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 08:32:12 INFO - PROCESS | 1641 | colorDepth:24 08:32:12 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 08:32:12 INFO - PROCESS | 1641 | __metrics 08:32:12 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:32:13 INFO - TEST-INFO | 1641: exit 0 08:32:13 INFO - Browser initialized. 08:32:13 INFO - Running cycle 1/1 for dromaeo_css test... 08:32:13 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpRF1uWf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:32:16 INFO - PROCESS | 1644 | 2016-12-21 08:32:16.285 plugin-container[1645:7707] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8437, name = 'com.apple.tsm.portname' 08:32:16 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:32:16 INFO - PROCESS | 1644 | 2016-12-21 08:32:16.285 plugin-container[1645:7707] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 08:32:16 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:32:16 INFO - PROCESS | 1644 | 2016-12-21 08:32:16.286 plugin-container[1645:7707] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:32:25 INFO - PROCESS | 1644 | RSS: Main: 229527552 08:32:25 INFO - PROCESS | 1644 | 08:33:15 INFO - PROCESS | 1644 | 1482337995621 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:35:29 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 08:35:31 INFO - PROCESS | 1644 | RSS: Main: 231743488 08:35:31 INFO - PROCESS | 1644 | 08:38:33 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 08:38:34 INFO - PROCESS | 1644 | RSS: Main: 243183616 08:38:34 INFO - PROCESS | 1644 | 08:41:36 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 08:41:37 INFO - PROCESS | 1644 | RSS: Main: 246091776 08:41:37 INFO - PROCESS | 1644 | 08:44:39 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:44:39 INFO - PROCESS | 1644 | RSS: Main: 246751232 08:44:39 INFO - PROCESS | 1644 | 08:47:42 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:47:43 INFO - PROCESS | 1644 | RSS: Main: 249798656 08:47:43 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:50:45 INFO - PROCESS | 1644 | RSS: Main: 251166720 08:50:45 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | __start_tp_report 08:50:45 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 08:50:45 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 08:50:45 INFO - PROCESS | 1644 | |i|pagename|runs| 08:50:45 INFO - PROCESS | 1644 | |0;dojo.html;2593.988344545627;3198.216436972941;3312.403767321884;3324.4680851063836;3344.364570731561;5456.8090116845915;5505.889882202358;5518.806841760535;5527.447255274471;5552.167174923763;4495.595396414322;5525.198846167304;5539.778408863651;5558.277423934893;5573.247611572437;5008.549230569248;5059.266406371074;5104.5405913467785;5123.641345105844;5171.560417364529;5123.462036486171;5148.485151484841;5163.3545806774155;5166.457521960197;5201.973990130044;4999.875003124915;5052.898942021157;5131.153359695646;5144;5147.588192944556;5103.8468845934685;5106.106431374495;5122.436531981479;5127.2052831811125;5138.691678499302;4952.108620448354;5073.923891141599;5090.440051594322;5090.490950904917;5094.1594636884865;4612.930806037912;5535.58483113768;5540.556755459554;5550.972245138789;5623;5889.941100588982;6001.849953751147;6062.787802426894;6108.554560543837;6125.479334256594;6127.519179552418;6160.6919654017565;6182.783602573933;6186.7525298987985;6197.03945888388;5485.451454854483;5596.300462442195;5654.46282603152;5655.830325090254;5658.91511627326;6172.598781079216;6211.720472578745;6212;6272.18461599989;6284.528660350492;5900.380460051632;5938.020226662552;5940.6435613863305;5984.820455386345;6027.819165425044;6148.754049838046;6152.107944348045;6155.353687862799;6171.84570385735;6193.81418557444;4333.934990975138;4905.705657660552;5312.309399778082;5822.243108395966;6042.244719410074;5967.701614919237;6037.366076561985;6044.697765111727;6112.449879510866;6137.202163718688;4792.113459010095;4813.542713442217;4838.854834354975;4860.9513904861205;4873.902521949542;4226.598473145046;4251.574842515785;4252.638525725348;4302.161078589645;4353.912921741611;4752.95247047532;5754.683492407874;5790.10253410722;5861.032929566659;5877.52979761618;6188.195534580475;6191.876162476815;6199.442050215503;6223.190985171898;6236.282827474898;5743;5822.621529600562;5835.212246346774;5882.999890018707;5911.9704401478575;5188.610854185951;5262;5265.289185959847;5278.656887302313;5303.628745987744;6185.072239164251;6211.503079753609;6274.780382686584;6345.587536810092;6376.202974628171;6019.51843852482;6061.212042434456;6099;6129.267100814889;6141.07883817431;6198.659073750987;6227.0970709246;6268.4671802897265;6325.7153428094925;6331.9366806331345;5679.971600141973;6052.334243233153;6070.1501789750355;6072.969635151796;6092.878142437038;4665.813367465399;6053.334133245251;6055.939440605537;6113.385090541831;6133.79375043651;5716.199732037435;5924.496417804535;5966.403359663999;6008.819735407945;6100.603460775035;5983.401659833982;5999.610025348338;6004.549658775697;6015.4586087252355;6015.518758499397;5620.634658747168;5755.65466072037;5767.913481297868;5867.061270196748;5874.060150375919;5798.82603521895;5811.56413269015;5833.416658334133;5907.054871220584;5939.46544810969;5713.971430142823;5753.367129615655;5766.769329226784;5768.567357448124;5850.034744267148 08:50:45 INFO - PROCESS | 1644 | |1;ext.html;453.19557784931743;481.4607639443821;516.3313509005837;519.4234399816204;532.2654736463679;37707.99999999996;39358.60641393585;39390.81827545171;40844.38733419001;41048.99999999993;40177.799111004475;40631.79684101583;40647.79676101623;40722.38916416256;40911.795441022754;9337.019612940663;9539.761505962371;9540.236781057534;9541.618335266581;9732.65935692251;9524.190443812284;9548.331616786827;9570.28222883283;9573.13841754242;9585.37695049823;9576.80846383072;9595.856062159039;9611.615535378576;9630.518474076303;9640.228781697448;9511.429314241168;9585.616575337013;9593.42439453635;9596.376235544667;9643.08390702882;9400.811983760286;9482.95258523703;9505.477198754066;9514.429134251966;9518.238540916711;38809.61190388088;39036;39165.41251881224;39203.60796392028;39405.40891886624;3196.8081915085168;3488.145404375944;3660.396034654279;3669.027707657473;3915.334393153171;21936.122555097776;28225.435491290264;29359.972400965864;30033.399332013236;30566.930157444604;3054.938901221963;3130.233092892256;3228.725558327545;3238.724708399789;3261.037993791837;371525;372286;396496;454402;455079;65580;66156.33843661501;66387;66388.33611663918;66529.33470665329;67022;68293.3170668297;69112;69167;69183;16484.505464836075;16715.582110447092;16718.41485548024;16719.49841504757;16767.329306827833;12612.117151799286;12658.303793291458;12691.238525688488;12714.554990575285;12744.107912446225;17939.744217904623;18186.090695465173;19979.400617981482;20015.899125548236;20022.89988550048;3302.85137168828;8106.959465202636;8394.748157555161;8438.198371154731;8680.8697869532;828.3414685325181;902.7427183252742;928.2898582584328;938.216589148069;1031.664708969573;29486.410271794874;29536.704632953824;29814.701852981627;30116.09651710452;30128.69871301303;901.693424235763;953.9236861051099;1053.7154968158554;1056.5668076088766;1113.1150735165563;13974.930125349309;14994.400223990919;15381.615459613598;15562.833274526416;15631.609209769847;584857;628179;686525;687435;687825;56349.71825140848;57163.714181428826;57748;57778.7111064442;58031;1362.8023936529148;1369.1511263016794;1382.5644921849787;1387.1330418488444;1391.5268808604922;23631.905265046524;25828.225153245432;25976.8701156493;29702;30037.098887033422;49143.26285105655;49453.76365590889;49455.75272123616;49465;49526.50473495219;554656;615457;623140;627203;627752;26975.190744277705;27272.454550909268;27335.179944601692;27363.17910462689;27371.589426159022;44903.32645010262;45505;46007.53992460033;46417;47096.529034709216;37855;38298.23403531858;38322;38714;39692.41999270038;38145.61854381532;38709;38891.02772430712;38941.80529097337;39468.21063578655;36387.63612363948;37132.814335928146;37755.05612359713;37786.811065944494;37834.62165378311;32521;33450.49824252687;34573.65426345704;34619.78830740912;34796.304073917876;23311.53376932486;24837;24940.75059249384;25691.100811471508;25835.870820645778 08:50:45 INFO - PROCESS | 1644 | |2;jquery.html;333.58968468783394;367.34979087015984;369.3462571248891;378.97157713171487;398.4998826472778;18213.544661383472;18744;19637.214511419563;19722.408327750156;19988.90005549974;18618;18898.433047008613;19387;19548.02259887007;19638.901805490954;5500.779968801243;5734.311882574076;5737.483626473617;5748.281464816898;5757.596968212227;5361.651492652965;5626.352969408509;5689.374168841424;5701.258836351269;5715.257016587838;4858.1983972644475;5657.2928383952;5659.4340565943485;5665.603407761459;5665.830025099253;4865.586425153866;5449.264349312852;5663.89554036963;5793.7392817323325;5833.38749431312;5457.863553411157;5558;5605.131204663284;5642.746076426571;5643;12770.680732981657;12828.871711282862;12876.871231287661;12911;13042.282674452901;2222.9888850555803;2276.499170182574;2279.6922415474032;2314.155333303349;2358.1982126077214;9513.904860951441;9738.9513052435;9747.658831940918;10062.547185376676;10147.644832430904;1780.9020503872282;1797.191263931236;1877.342929974498;1971.1327016112864;1984.7022946557988;114405.42797286043;115128;120717;124344;126032;10683.252172348017;11317.49071291794;11353.659390218307;11481.0815134789;11514.76970460603;13861.792073118912;14200.147991120566;14201.289935503184;14201.431942722176;14238.85761142396;7972.960135199287;8254.339652827759;8256.669733210605;8353.749387518385;8389.622466988878;8164.877526837102;8185.427020108655;8206.22040906113;8308.584570771438;8689.957205135424;4311;4482.372467854503;4513.323001549794;4569.977150114295;4571.6799824012;1570.2933679844116;1687.9028631389695;1771.6722406354866;1817.963640727178;1914.258025197064;858.8110615664543;927.7799693403153;939.957701903416;957.5595226195888;963.7976025034822;18792.624147516974;19458.31895883637;20222.59554808925;20267.189312427337;20553.280635177696;905.23055402907;907.6369452219033;953.8092381523584;963.3545524498603;964.4695417520336;9219.861702074475;9526.523673816282;10659.733506662396;10661.626843060456;10677.466126693635;619941;620757;630782;631081;637166;81705.59147204345;81802.59098704468;119004.40497797455;134874.6512534862;137250;932.6036434515304;963.6386355116831;972.3922548407245;975.205207755691;981.9509024548744;17889.642207155524;18184.818151818312;18518.44444666662;18729.30350215864;18824;50554.74722626364;53658;56796.716016419654;58993.705031474565;61199.16402507932;71062.64468677624;82573;86735;88079;89401.65897511413;7901.841963160819;7959.522428654068;8003.6798528058225;8069.475484093515;8101.716439924574;12046.457909393923;12520.624381268577;12521.311327877054;12547.24716517012;12551.309677967434;8805.031446541003;10355.689329320132;10778.407187604444;10893.019628233496;11154.72113197144;10881.510332035235;10904.291221070605;11134.498947547541;11368.261063030877;11381.829272561085;9453.763655908657;10626.628068017582;10785.514651840844;11213.71965700864;11349.37578433161;6776.728930842711;7910.5649189295145;8030.598470076356;8094.595270236347;8132.87800683002;8014.559199244098;10376.325538839952;10960;10961.945190273998;11219.943900280447 08:50:45 INFO - PROCESS | 1644 | |3;mootools.html;1446.9493567725128;1449.1450044473759;1622.6592415592725;1739.6781595404843;1762.3038899634653;7470.887936680941;7702.845943081149;7954.164812694663;8449.61976711048;8457.619407126671;7933.603319834014;8222.218889205516;8235.670573177065;8309.667613295476;8317.334613230942;2303.1133013789727;2321.895514701834;2326.162581470669;2329.173143534038;2333.3000099970027;2304.539092181562;2342.16853017179;2352.435415500276;2354.9175778847743;2367.2779802160335;2005.6389849827026;2241.7758224177614;2255.7293124825046;2293.2546922250335;2335.8481698689616;1732.2724455728562;2280;2292.3008482412856;2300.5053913408615;2309.099451214028;2268.8638681679154;2334.241371554235;2338.4738433852417;2339.1695947938406;2340.368100612825;8262.380321475854;8294.792630184233;8374.958125209396;8378.078411374743;8380.287675547586;4344.391785150082;4365.454318210223;4384.6273066789045;4409.713368631029;5061.772220250097;2738.7672047875953;3369.107186595554;3564.3584154852374;3575.6424357564297;3580.4092324766384;917.7843206846386;1028.9279750417475;1085.0722632149477;1087.456271864068;1089.54239219528;8956.373053886231;10206.234532410099;10437.217208709377;10781.676549703521;10982.286151400212;4156.397322388237;4198.538160802309;4375.5405682403525;4493.932591011137;4508.594226519629;8536.44513106646;8611.827763444697;8631.136886311318;8635.654573817103;8657.740267791976;4848.393950756155;4859.07677541266;4868.2210846264425;4869.050535145612;4878.414590249122;4262.360645903139;4409.382686423904;4539.773011349485;4643.95356046442;4744.928826067612;3632.636736326346;3668.211334563082;3701.9629803702155;3745.9625403745613;3762.5108735864164;1146.1690274550883;1158.9652310430702;1161.7850697620968;1166.3876464856019;1171.1392126786975;756.3608750605729;896.9730908072768;919.117647058831;925.5279807298326;929.9070092990783;5667.263255776723;5704.458076482728;5804.071348584206;5825.126231065289;5894.587378883522;894.4677916639589;900.594732370436;910.3127139010005;915.4736026784625;925.3615005646083;3705.8517659293325;3739.7382183247455;3776.829182953293;3793.1844653399653;3802.923941521154;8644.610992505352;9221.492817894947;10250.846237306447;10256.53845576936;10263.897361026295;10139.847902281472;10247.84628230577;10249.795004099877;10284.2801003929;10316.793664126675;7778.494397864234;7971.40214483916;7974.202579741979;7976.561289129154;7978.122406535276;5379.273798037215;5473.01485732556;5541.445855414588;5635.042042852643;5691.77232910679;9377.765555861159;9443.055694430503;9531.76170595713;9535.856962145712;9536.475493847905;8793.560321983747;9404.213199492075;9491.762705932131;9653.46905920153;9748.366356186825;6284.402981716729;6476.222853257451;6562;6672.165979252593;6781.830454238683;6740.831479213059;7583.165851756413;7831.647575858986;7832.295093441618;7862.056553213422;7052.612106333996;7191.856162876608;7200.459965502503;7230.819229519304;7299.978003079679;6940.305969403019;7048.365647091787;7107.111611048619;7113.608751518716;7148.4281257500315;5744;6650.733970641314;6729.024291477806;6741.764038258638;6745.52781305304;4642.653630447133;4852.2479015752615;4857.53853383928;4880.145974454527;4882.853514394574;4075.8165882534763;4453.309736990772;4527.728336299832;4960.553550180501;5055.469175736642 08:50:45 INFO - PROCESS | 1644 | |4;prototype.html;239.9748026457223;270.84291111155534;299.7347347597376;308.05734452575115;311.5747005337713;4313.35299705044;4863.294822250771;5326.750682083925;5423.810166644168;5515.889682206353;4176.770277634729;4300.4409426774555;5237.973810130944;5286.603504737151;5324.5922609529935;4065.922530529412;4095.5494895561465;4105.486814148231;4114.218298523271;4136.048708796979;3966.3257246268204;4089.734167279117;4114.547399786021;4132.8966775830695;4151.481064866892;4030.4809494066208;4048.817803198863;4049.2913740095364;4052.6555242804247;4083.4691490106243;3482.129467633079;3929.2337994091163;4025.2352053109817;4066.2477441673386;4134.6845766370625;3571.2500374921296;3572.732045096628;3576.910577235564;3582.390993531106;3601.8019008954498;4157.605027522411;4858.659893807435;4899.583535399495;5150.639455238135;5162.354705661793;4378.868633940987;4595.724256544585;4601.677882548223;4617.630589552828;4642.512536183686;4850.514948505156;5543.066670663113;5570.832875013756;5580.693061881595;5603.245825297061;3257.7719559630837;3359.81521016344;3580.7135429165605;3724.9441258380875;3923.941140882889;5874.412558744134;5879.941200587982;5895.4399332063385;5977.462028417463;6029.306629737593;3870;3870.9612903871166;3878.1855810279594;3896.162325100117;3915.667168290727;3284.8850290240207;3478.8086655234206;3821.4267859820693;3947.8618248361167;3961.900952476211;3757.887263382103;4508.774561271924;4510.436195475566;4516.786774480481;4564.908701825945;5292.629515933848;5520.972395137998;5575.829075894027;5613.915791263134;5656.652489217011;1217.3000524698334;1217.6712287682276;1221.1757063981777;1221.3648902570615;1273.8152967819615;719.9280071992758;736.988945165823;862.9007664118707;871.4901782457207;897.5459755196492;4574.794134263966;4634.096351211481;4670.719756814602;5132.743362831843;5318.335208098988;4686.671932964728;5482.698451585121;5538.559974406683;5551.361593416728;5641.774329026793;4812.975935120302;4862.5380588844;5034.06869729094;5049.924251136236;5126.487351264918;2458.963115553268;2647.8878870874278;2754.325190328382;2790.6651201855907;2903.6080129182715;5127.512886275797;5528.917066244009;5752.599261564907;5755.510781583528;5821.282721597139;4367.694261401735;5121.820736274287;5362.839114826562;5504.348695391366;5537.5569954404355;5243.68537887728;5511.834644960658;5702.232307984538;5754.741036653444;5777.468970722762;4456.629761137886;4719.952800471951;5142.357205349332;5188.896222075462;5268.394134674561;4371.229651990949;5345.1447768356875;5351.59863010268;5353.54494867925;5388.973055134699;4917.975410122926;5424.403315635356;5524.867402182463;5744.281964754406;5841.51735512397;3186.4583020886043;3601.9099522512147;3616.649178034299;3624.4382120771324;3640.4539319102346;5362.919556206738;5371.435999220025;5401.432849550741;5408;5437.456254374689;5023.196288593807;5124.680641257432;5208.296879921162;5224.346956630421;5319.787208511616;4828.299896514915;4933.629977751755;5223.52988231061;5228.320318358589;5231.450697676689;4928.926066108939;5139.761949971644;5182.352205974254;5220.556252718409;5371.865703357291;4564.356831540576;4918.82784102569;5222.660527065728;5630.268065151504;5685.573581981285;4575.519570445188;5296.841094767163;5481.205225242397;5516.282883225315;5529.806456773994 08:50:45 INFO - PROCESS | 1644 | |5;yui.html;645.4932877691012;743.5835931878148;749.1833901047856;762.9122650895149;804.2882049386295;14130.929345353272;14844.55466336009;14846.55460336189;14881.776773348394;14883.702325953474;14341.713165736704;14931.776023359658;14950.700985980275;15208.315625796839;15228.847711522882;567.8580354911272;636.834423050008;790.861599220137;805.7220259010631;812.1391325195311;803.4897839871666;806.7862016565615;820.2781552234026;821.3100995164061;822.198356602314;862.6376921692904;863.1368631368631;864.6887120636566;865.965361385547;866.6446756829702;855.1192271959891;857.3784006595168;857.5069335132292;857.536930057768;858.6179150278128;627.6955676496896;633.885900537903;634.8254230086717;636.3445650979452;639.5842702243532;14614.34235459407;14883.851161488356;14901;15038.699226015418;15049.172295523742;13422.53021144265;13473.595792126252;13597.796033059512;13628.727425451436;13876.692598665366;11337.489812958436;11457.942710286396;11733.12001599875;11738.823917641243;11779.293242405483;3597.118705917041;3628.546431696038;3639.8726044588575;3642.817859107034;3688.686461650763;100048.49927251098;100956.49521752418;100981;101720.49139754254;102468.9753102467;8283.544405057779;9409.811803763887;9461.905380946102;10457.058864702061;10600.628977985887;16640;18318.450446486626;20361;20543.79456205449;20702.378928632166;7337.669804858794;7640.121386040676;7673.76978690629;7737.845243095219;7847.058352997563;3676.3015027144756;4152.522459917088;4216.47294088239;4309.935350969738;4310.978445107817;2787.9024234151707;3348.2466445049668;3364.2094107884627;3371.3931492331126;3385.81378024211;619.8574327904607;641.0768493369533;641.916550848396;646.977355792545;658.7595527632379;575.8358867722679;628.4720834499043;637.4772686397109;642.7589653879795;646.7219095788856;8273.481487376324;8711.08533603975;8859.51272679996;8966.68616598416;9051.59267832949;556.2685069134156;606.9908255969348;618.2519151826341;643.5302229372584;648.1282674802384;3969;4056.9857535616097;4128.318827393507;4138.234426631083;4282.379055037002;124041.75958240482;124222.75777242292;124300;124588;127416;104747.95252047382;108247.91752082379;110727;112853;113084.43457782823;64922.67538662277;75273.62363188149;86725.13274867297;94321.0567894326;96871.51564242133;9007.189352958265;9083.318751093562;9434.528273586431;9515.809683806423;9545.522723863918;44566.3315050281;48502.757486212344;49366.50633493763;55654;56129.31612051645;75257.87113193415;79109.60445197737;79524;79729;82990.75513867177;11984.161108722306;12269.141160118706;12327.75344493123;12480.438380272728;12492.188007779465;13029.804552931902;13088.62545584632;13137.277449740355;13203.735925281633;13387.598372048855;12935.741285174432;13167.934160329138;13211.471541138248;13340.3329833506;13423.798643020558;13032.283224422368;13115.475380984653;13126.540571079966;13198.868011320148;13396.26320552379;11821.231619944676;11841.881581184078;11951.462184201559;12093.213941093802;12113.878861211275;10932.88453996177;11132.443377830914;11366.829497557377;11532.596359127725;11635;11111.888881111085;11141.108711303239;11439.199256051996;11506.367149806842;11511.136664750009 08:50:45 INFO - PROCESS | 1644 | __end_tp_report 08:50:45 INFO - PROCESS | 1644 | __start_cc_report 08:50:45 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,370 08:50:45 INFO - PROCESS | 1644 | __end_cc_report 08:50:45 INFO - PROCESS | 1644 | __startTimestamp1482339045692__endTimestamp 08:50:45 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | ------- Summary: start ------- 08:50:45 INFO - PROCESS | 1644 | Number of tests: 6 08:50:45 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5583.01 Median:5798.83 stddev:676.91 (11.7%) stddev-sans-first:637.09 08:50:45 INFO - PROCESS | 1644 | Values: 2594.0 3198.2 3312.4 3324.5 3344.4 5456.8 5505.9 5518.8 5527.4 5552.2 4495.6 5525.2 5539.8 5558.3 5573.2 5008.5 5059.3 5104.5 5123.6 5171.6 5123.5 5148.5 5163.4 5166.5 5202.0 4999.9 5052.9 5131.2 5144.0 5147.6 5103.8 5106.1 5122.4 5127.2 5138.7 4952.1 5073.9 5090.4 5090.5 5094.2 4612.9 5535.6 5540.6 5551.0 5623.0 5889.9 6001.8 6062.8 6108.6 6125.5 6127.5 6160.7 6182.8 6186.8 6197.0 5485.5 5596.3 5654.5 5655.8 5658.9 6172.6 6211.7 6212.0 6272.2 6284.5 5900.4 5938.0 5940.6 5984.8 6027.8 6148.8 6152.1 6155.4 6171.8 6193.8 4333.9 4905.7 5312.3 5822.2 6042.2 5967.7 6037.4 6044.7 6112.4 6137.2 4792.1 4813.5 4838.9 4861.0 4873.9 4226.6 4251.6 4252.6 4302.2 4353.9 4753.0 5754.7 5790.1 5861.0 5877.5 6188.2 6191.9 6199.4 6223.2 6236.3 5743.0 5822.6 5835.2 5883.0 5912.0 5188.6 5262.0 5265.3 5278.7 5303.6 6185.1 6211.5 6274.8 6345.6 6376.2 6019.5 6061.2 6099.0 6129.3 6141.1 6198.7 6227.1 6268.5 6325.7 6331.9 5680.0 6052.3 6070.2 6073.0 6092.9 4665.8 6053.3 6055.9 6113.4 6133.8 5716.2 5924.5 5966.4 6008.8 6100.6 5983.4 5999.6 6004.5 6015.5 6015.5 5620.6 5755.7 5767.9 5867.1 5874.1 5798.8 5811.6 5833.4 5907.1 5939.5 5714.0 5753.4 5766.8 5768.6 5850.0 08:50:45 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:69356.88 Median:39374.71 stddev:152455.62 (387.2%) stddev-sans-first:152795.52 08:50:45 INFO - PROCESS | 1644 | Values: 453.2 481.5 516.3 519.4 532.3 37708.0 39358.6 39390.8 40844.4 41049.0 40177.8 40631.8 40647.8 40722.4 40911.8 9337.0 9539.8 9540.2 9541.6 9732.7 9524.2 9548.3 9570.3 9573.1 9585.4 9576.8 9595.9 9611.6 9630.5 9640.2 9511.4 9585.6 9593.4 9596.4 9643.1 9400.8 9483.0 9505.5 9514.4 9518.2 38809.6 39036.0 39165.4 39203.6 39405.4 3196.8 3488.1 3660.4 3669.0 3915.3 21936.1 28225.4 29360.0 30033.4 30566.9 3054.9 3130.2 3228.7 3238.7 3261.0 371525.0 372286.0 396496.0 454402.0 455079.0 65580.0 66156.3 66387.0 66388.3 66529.3 67022.0 68293.3 69112.0 69167.0 69183.0 16484.5 16715.6 16718.4 16719.5 16767.3 12612.1 12658.3 12691.2 12714.6 12744.1 17939.7 18186.1 19979.4 20015.9 20022.9 3302.9 8107.0 8394.7 8438.2 8680.9 828.3 902.7 928.3 938.2 1031.7 29486.4 29536.7 29814.7 30116.1 30128.7 901.7 953.9 1053.7 1056.6 1113.1 13974.9 14994.4 15381.6 15562.8 15631.6 584857.0 628179.0 686525.0 687435.0 687825.0 56349.7 57163.7 57748.0 57778.7 58031.0 1362.8 1369.2 1382.6 1387.1 1391.5 23631.9 25828.2 25976.9 29702.0 30037.1 49143.3 49453.8 49455.8 49465.0 49526.5 554656.0 615457.0 623140.0 627203.0 627752.0 26975.2 27272.5 27335.2 27363.2 27371.6 44903.3 45505.0 46007.5 46417.0 47096.5 37855.0 38298.2 38322.0 38714.0 39692.4 38145.6 38709.0 38891.0 38941.8 39468.2 36387.6 37132.8 37755.1 37786.8 37834.6 32521.0 33450.5 34573.7 34619.8 34796.3 23311.5 24837.0 24940.8 25691.1 25835.9 08:50:45 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:35075.39 Median:4396.69 stddev:104490.04 (2376.6%) stddev-sans-first:104750.60 08:50:45 INFO - PROCESS | 1644 | Values: 333.6 367.3 369.3 379.0 398.5 18213.5 18744.0 19637.2 19722.4 19988.9 18618.0 18898.4 19387.0 19548.0 19638.9 5500.8 5734.3 5737.5 5748.3 5757.6 5361.7 5626.4 5689.4 5701.3 5715.3 4858.2 5657.3 5659.4 5665.6 5665.8 4865.6 5449.3 5663.9 5793.7 5833.4 5457.9 5558.0 5605.1 5642.7 5643.0 12770.7 12828.9 12876.9 12911.0 13042.3 2223.0 2276.5 2279.7 2314.2 2358.2 9513.9 9739.0 9747.7 10062.5 10147.6 1780.9 1797.2 1877.3 1971.1 1984.7 114405.4 115128.0 120717.0 124344.0 126032.0 10683.3 11317.5 11353.7 11481.1 11514.8 13861.8 14200.1 14201.3 14201.4 14238.9 7973.0 8254.3 8256.7 8353.7 8389.6 8164.9 8185.4 8206.2 8308.6 8690.0 4311.0 4482.4 4513.3 4570.0 4571.7 1570.3 1687.9 1771.7 1818.0 1914.3 858.8 927.8 940.0 957.6 963.8 18792.6 19458.3 20222.6 20267.2 20553.3 905.2 907.6 953.8 963.4 964.5 9219.9 9526.5 10659.7 10661.6 10677.5 619941.0 620757.0 630782.0 631081.0 637166.0 81705.6 81802.6 119004.4 134874.7 137250.0 932.6 963.6 972.4 975.2 982.0 17889.6 18184.8 18518.4 18729.3 18824.0 50554.7 53658.0 56796.7 58993.7 61199.2 71062.6 82573.0 86735.0 88079.0 89401.7 7901.8 7959.5 8003.7 8069.5 8101.7 12046.5 12520.6 12521.3 12547.2 12551.3 8805.0 10355.7 10778.4 10893.0 11154.7 10881.5 10904.3 11134.5 11368.3 11381.8 9453.8 10626.6 10785.5 11213.7 11349.4 6776.7 7910.6 8030.6 8094.6 8132.9 8014.6 10376.3 10960.0 10961.9 11219.9 08:50:45 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5333.44 Median:4879.28 stddev:2935.62 (60.2%) stddev-sans-first:2929.33 08:50:45 INFO - PROCESS | 1644 | Values: 1446.9 1449.1 1622.7 1739.7 1762.3 7470.9 7702.8 7954.2 8449.6 8457.6 7933.6 8222.2 8235.7 8309.7 8317.3 2303.1 2321.9 2326.2 2329.2 2333.3 2304.5 2342.2 2352.4 2354.9 2367.3 2005.6 2241.8 2255.7 2293.3 2335.8 1732.3 2280.0 2292.3 2300.5 2309.1 2268.9 2334.2 2338.5 2339.2 2340.4 8262.4 8294.8 8375.0 8378.1 8380.3 4344.4 4365.5 4384.6 4409.7 5061.8 2738.8 3369.1 3564.4 3575.6 3580.4 917.8 1028.9 1085.1 1087.5 1089.5 8956.4 10206.2 10437.2 10781.7 10982.3 4156.4 4198.5 4375.5 4493.9 4508.6 8536.4 8611.8 8631.1 8635.7 8657.7 4848.4 4859.1 4868.2 4869.1 4878.4 4262.4 4409.4 4539.8 4644.0 4744.9 3632.6 3668.2 3702.0 3746.0 3762.5 1146.2 1159.0 1161.8 1166.4 1171.1 756.4 897.0 919.1 925.5 929.9 5667.3 5704.5 5804.1 5825.1 5894.6 894.5 900.6 910.3 915.5 925.4 3705.9 3739.7 3776.8 3793.2 3802.9 8644.6 9221.5 10250.8 10256.5 10263.9 10139.8 10247.8 10249.8 10284.3 10316.8 7778.5 7971.4 7974.2 7976.6 7978.1 5379.3 5473.0 5541.4 5635.0 5691.8 9377.8 9443.1 9531.8 9535.9 9536.5 8793.6 9404.2 9491.8 9653.5 9748.4 6284.4 6476.2 6562.0 6672.2 6781.8 6740.8 7583.2 7831.6 7832.3 7862.1 7052.6 7191.9 7200.5 7230.8 7300.0 6940.3 7048.4 7107.1 7113.6 7148.4 5744.0 6650.7 6729.0 6741.8 6745.5 4642.7 4852.2 4857.5 4880.1 4882.9 4075.8 4453.3 4527.7 4960.6 5055.5 08:50:45 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4406.81 Median:4908.78 stddev:1348.64 (27.5%) stddev-sans-first:1315.66 08:50:45 INFO - PROCESS | 1644 | Values: 240.0 270.8 299.7 308.1 311.6 4313.4 4863.3 5326.8 5423.8 5515.9 4176.8 4300.4 5238.0 5286.6 5324.6 4065.9 4095.5 4105.5 4114.2 4136.0 3966.3 4089.7 4114.5 4132.9 4151.5 4030.5 4048.8 4049.3 4052.7 4083.5 3482.1 3929.2 4025.2 4066.2 4134.7 3571.3 3572.7 3576.9 3582.4 3601.8 4157.6 4858.7 4899.6 5150.6 5162.4 4378.9 4595.7 4601.7 4617.6 4642.5 4850.5 5543.1 5570.8 5580.7 5603.2 3257.8 3359.8 3580.7 3724.9 3923.9 5874.4 5879.9 5895.4 5977.5 6029.3 3870.0 3871.0 3878.2 3896.2 3915.7 3284.9 3478.8 3821.4 3947.9 3961.9 3757.9 4508.8 4510.4 4516.8 4564.9 5292.6 5521.0 5575.8 5613.9 5656.7 1217.3 1217.7 1221.2 1221.4 1273.8 719.9 737.0 862.9 871.5 897.5 4574.8 4634.1 4670.7 5132.7 5318.3 4686.7 5482.7 5538.6 5551.4 5641.8 4813.0 4862.5 5034.1 5049.9 5126.5 2459.0 2647.9 2754.3 2790.7 2903.6 5127.5 5528.9 5752.6 5755.5 5821.3 4367.7 5121.8 5362.8 5504.3 5537.6 5243.7 5511.8 5702.2 5754.7 5777.5 4456.6 4720.0 5142.4 5188.9 5268.4 4371.2 5345.1 5351.6 5353.5 5389.0 4918.0 5424.4 5524.9 5744.3 5841.5 3186.5 3601.9 3616.6 3624.4 3640.5 5362.9 5371.4 5401.4 5408.0 5437.5 5023.2 5124.7 5208.3 5224.3 5319.8 4828.3 4933.6 5223.5 5228.3 5231.5 4928.9 5139.8 5182.4 5220.6 5371.9 4564.4 4918.8 5222.7 5630.3 5685.6 4575.5 5296.8 5481.2 5516.3 5529.8 08:50:45 INFO - PROCESS | 1644 | 08:50:45 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:21735.32 Median:3659.56 stddev:33338.61 (911.0%) stddev-sans-first:33394.53 08:50:45 INFO - PROCESS | 1644 | Values: 645.5 743.6 749.2 762.9 804.3 14130.9 14844.6 14846.6 14881.8 14883.7 14341.7 14931.8 14950.7 15208.3 15228.8 567.9 636.8 790.9 805.7 812.1 803.5 806.8 820.3 821.3 822.2 862.6 863.1 864.7 866.0 866.6 855.1 857.4 857.5 857.5 858.6 627.7 633.9 634.8 636.3 639.6 14614.3 14883.9 14901.0 15038.7 15049.2 13422.5 13473.6 13597.8 13628.7 13876.7 11337.5 11457.9 11733.1 11738.8 11779.3 3597.1 3628.5 3639.9 3642.8 3688.7 100048.5 100956.5 100981.0 101720.5 102469.0 8283.5 9409.8 9461.9 10457.1 10600.6 16640.0 18318.5 20361.0 20543.8 20702.4 7337.7 7640.1 7673.8 7737.8 7847.1 3676.3 4152.5 4216.5 4309.9 4311.0 2787.9 3348.2 3364.2 3371.4 3385.8 619.9 641.1 641.9 647.0 658.8 575.8 628.5 637.5 642.8 646.7 8273.5 8711.1 8859.5 8966.7 9051.6 556.3 607.0 618.3 643.5 648.1 3969.0 4057.0 4128.3 4138.2 4282.4 124041.8 124222.8 124300.0 124588.0 127416.0 104748.0 108247.9 110727.0 112853.0 113084.4 64922.7 75273.6 86725.1 94321.1 96871.5 9007.2 9083.3 9434.5 9515.8 9545.5 44566.3 48502.8 49366.5 55654.0 56129.3 75257.9 79109.6 79524.0 79729.0 82990.8 11984.2 12269.1 12327.8 12480.4 12492.2 13029.8 13088.6 13137.3 13203.7 13387.6 12935.7 13167.9 13211.5 13340.3 13423.8 13032.3 13115.5 13126.5 13198.9 13396.3 11821.2 11841.9 11951.5 12093.2 12113.9 10932.9 11132.4 11366.8 11532.6 11635.0 11111.9 11141.1 11439.2 11506.4 11511.1 08:50:45 INFO - PROCESS | 1644 | -------- Summary: end -------- 08:50:45 INFO - PROCESS | 1644 | 08:50:46 INFO - TEST-INFO | 1644: exit 0 08:50:46 INFO - TEST-OK | dromaeo_css | took 1116785ms 08:50:46 INFO - TEST-START | kraken 08:50:46 INFO - operating with platform_type : mac_ 08:50:46 INFO - Initialising browser for kraken test... 08:50:46 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpElcosZ/profile http://localhost:49241/getInfo.html) 08:50:49 INFO - PROCESS | 1657 | 2016-12-21 08:50:49.306 plugin-container[1658:10128] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 08:50:49 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:49 INFO - PROCESS | 1657 | 2016-12-21 08:50:49.309 plugin-container[1658:10128] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.CFPasteboardClient' 08:50:49 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:49 INFO - PROCESS | 1657 | 2016-12-21 08:50:49.309 plugin-container[1658:10128] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:49 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 08:50:49 INFO - PROCESS | 1657 | colorDepth:24 08:50:49 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 08:50:49 INFO - PROCESS | 1657 | __metrics 08:50:49 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:50:50 INFO - TEST-INFO | 1657: exit 0 08:50:50 INFO - Browser initialized. 08:50:50 INFO - Running cycle 1/1 for kraken test... 08:50:50 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpElcosZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:52 INFO - PROCESS | 1659 | 2016-12-21 08:50:52.906 plugin-container[1660:10254] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 08:50:52 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:52 INFO - PROCESS | 1659 | 2016-12-21 08:50:52.907 plugin-container[1660:10254] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882b, name = 'com.apple.CFPasteboardClient' 08:50:52 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:52 INFO - PROCESS | 1659 | 2016-12-21 08:50:52.907 plugin-container[1660:10254] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:51:02 INFO - PROCESS | 1659 | RSS: Main: 229621760 08:51:02 INFO - PROCESS | 1659 | 08:51:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 08:51:06 INFO - PROCESS | 1659 | RSS: Main: 241516544 08:51:06 INFO - PROCESS | 1659 | 08:51:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 08:51:09 INFO - PROCESS | 1659 | RSS: Main: 249028608 08:51:09 INFO - PROCESS | 1659 | 08:51:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 08:51:13 INFO - PROCESS | 1659 | RSS: Main: 252805120 08:51:13 INFO - PROCESS | 1659 | 08:51:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 08:51:16 INFO - PROCESS | 1659 | RSS: Main: 253071360 08:51:16 INFO - PROCESS | 1659 | 08:51:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:51:18 INFO - PROCESS | 1659 | RSS: Main: 254382080 08:51:18 INFO - PROCESS | 1659 | 08:51:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 08:51:22 INFO - PROCESS | 1659 | RSS: Main: 258789376 08:51:22 INFO - PROCESS | 1659 | 08:51:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 08:51:26 INFO - PROCESS | 1659 | RSS: Main: 257515520 08:51:26 INFO - PROCESS | 1659 | 08:51:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 08:51:30 INFO - PROCESS | 1659 | RSS: Main: 257814528 08:51:30 INFO - PROCESS | 1659 | 08:51:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:51:32 INFO - PROCESS | 1659 | RSS: Main: 256954368 08:51:32 INFO - PROCESS | 1659 | 08:51:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:51:34 INFO - PROCESS | 1659 | RSS: Main: 256995328 08:51:34 INFO - PROCESS | 1659 | 08:51:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:51:37 INFO - PROCESS | 1659 | RSS: Main: 257056768 08:51:37 INFO - PROCESS | 1659 | 08:51:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:51:41 INFO - PROCESS | 1659 | RSS: Main: 257257472 08:51:41 INFO - PROCESS | 1659 | 08:51:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:45 INFO - PROCESS | 1659 | RSS: Main: 257097728 08:51:45 INFO - PROCESS | 1659 | 08:51:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 08:51:47 INFO - PROCESS | 1659 | RSS: Main: 258953216 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | __start_tp_report 08:51:47 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 08:51:47 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 08:51:47 INFO - PROCESS | 1659 | |i|pagename|runs| 08:51:47 INFO - PROCESS | 1659 | |0;ai-astar;120;124;118;119;119;121;122;120;119;122 08:51:47 INFO - PROCESS | 1659 | |1;audio-beat-detection;142;112;112;141;115;129;142;113;144;112 08:51:47 INFO - PROCESS | 1659 | |2;audio-dft;199;197;198;200;226;192;167;261;272;213 08:51:47 INFO - PROCESS | 1659 | |3;audio-fft;79;58;61;83;61;62;61;64;109;82 08:51:47 INFO - PROCESS | 1659 | |4;audio-oscillator;80;71;90;72;71;71;87;71;73;92 08:51:47 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;92;90;92;89;93;105;95;131;91;89 08:51:47 INFO - PROCESS | 1659 | |6;imaging-darkroom;113;102;101;99;101;110;166;167;171;167 08:51:47 INFO - PROCESS | 1659 | |7;imaging-desaturate;89;130;134;91;73;135;140;90;130;87 08:51:47 INFO - PROCESS | 1659 | |8;json-parse-financial;53;57;59;61;57;58;60;62;61;60 08:51:47 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;70;75;78;77;76;71;86;91;105;105 08:51:47 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;87;85;96;100;75;98;105;105;85;85 08:51:47 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;151;163;145;139;149;142;142;148;227;160 08:51:47 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;185;229;199;167;169;171;173;169;167;191 08:51:47 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;69;71;67;68;68;68;68;70;69;67 08:51:47 INFO - PROCESS | 1659 | __end_tp_report 08:51:47 INFO - PROCESS | 1659 | __start_cc_report 08:51:47 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,966 08:51:47 INFO - PROCESS | 1659 | __end_cc_report 08:51:47 INFO - PROCESS | 1659 | __startTimestamp1482339107148__endTimestamp 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | ------- Summary: start ------- 08:51:47 INFO - PROCESS | 1659 | Number of tests: 14 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:120.40 Median:120.50 stddev:1.84 (1.5%) stddev-sans-first:1.94 08:51:47 INFO - PROCESS | 1659 | Values: 120.0 124.0 118.0 119.0 119.0 121.0 122.0 120.0 119.0 122.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:126.20 Median:135.00 stddev:14.71 (10.9%) stddev-sans-first:14.45 08:51:47 INFO - PROCESS | 1659 | Values: 142.0 112.0 112.0 141.0 115.0 129.0 142.0 113.0 144.0 112.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:212.50 Median:206.50 stddev:32.24 (15.6%) stddev-sans-first:33.82 08:51:47 INFO - PROCESS | 1659 | Values: 199.0 197.0 198.0 200.0 226.0 192.0 167.0 261.0 272.0 213.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:72.00 Median:63.00 stddev:16.20 (25.7%) stddev-sans-first:16.98 08:51:47 INFO - PROCESS | 1659 | Values: 79.0 58.0 61.0 83.0 61.0 62.0 61.0 64.0 109.0 82.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:76.50 stddev:8.70 (11.4%) stddev-sans-first:9.19 08:51:47 INFO - PROCESS | 1659 | Values: 80.0 71.0 90.0 72.0 71.0 71.0 87.0 71.0 73.0 92.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:91.50 stddev:12.92 (14.1%) stddev-sans-first:13.59 08:51:47 INFO - PROCESS | 1659 | Values: 92.0 90.0 92.0 89.0 93.0 105.0 95.0 131.0 91.0 89.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:129.70 Median:166.50 stddev:33.05 (19.8%) stddev-sans-first:34.50 08:51:47 INFO - PROCESS | 1659 | Values: 113.0 102.0 101.0 99.0 101.0 110.0 166.0 167.0 171.0 167.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:109.90 Median:80.00 stddev:25.82 (32.3%) stddev-sans-first:26.26 08:51:47 INFO - PROCESS | 1659 | Values: 89.0 130.0 134.0 91.0 73.0 135.0 140.0 90.0 130.0 87.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:60.00 stddev:2.66 (4.4%) stddev-sans-first:1.81 08:51:47 INFO - PROCESS | 1659 | Values: 53.0 57.0 59.0 61.0 57.0 58.0 60.0 62.0 61.0 60.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:76.50 stddev:13.02 (17.0%) stddev-sans-first:12.88 08:51:47 INFO - PROCESS | 1659 | Values: 70.0 75.0 78.0 77.0 76.0 71.0 86.0 91.0 105.0 105.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:92.10 Median:85.00 stddev:10.08 (11.9%) stddev-sans-first:10.52 08:51:47 INFO - PROCESS | 1659 | Values: 87.0 85.0 96.0 100.0 75.0 98.0 105.0 105.0 85.0 85.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:156.60 Median:150.00 stddev:25.91 (17.3%) stddev-sans-first:27.40 08:51:47 INFO - PROCESS | 1659 | Values: 151.0 163.0 145.0 139.0 149.0 142.0 142.0 148.0 227.0 160.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:179.00 stddev:19.94 (11.1%) stddev-sans-first:21.12 08:51:47 INFO - PROCESS | 1659 | Values: 185.0 229.0 199.0 167.0 169.0 171.0 173.0 169.0 167.0 191.0 08:51:47 INFO - PROCESS | 1659 | 08:51:47 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:1.27 (1.9%) stddev-sans-first:1.33 08:51:47 INFO - PROCESS | 1659 | Values: 69.0 71.0 67.0 68.0 68.0 68.0 68.0 70.0 69.0 67.0 08:51:47 INFO - PROCESS | 1659 | -------- Summary: end -------- 08:51:47 INFO - PROCESS | 1659 | 08:51:48 INFO - TEST-INFO | 1659: exit 0 08:51:48 INFO - TEST-OK | kraken | took 61867ms 08:51:48 INFO - SUITE-END | took 1178s 08:51:48 INFO - Completed test suite (00:19:38) 08:51:48 INFO - javascript benchmark 08:51:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7080.434616554539, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2593.988344545627, 3198.216436972941, 3312.403767321884, 3324.4680851063836, 3344.364570731561, 5456.8090116845915, 5505.889882202358, 5518.806841760535, 5527.447255274471, 5552.167174923763, 4495.595396414322, 5525.198846167304, 5539.778408863651, 5558.277423934893, 5573.247611572437, 5008.549230569248, 5059.266406371074, 5104.5405913467785, 5123.641345105844, 5171.560417364529, 5123.462036486171, 5148.485151484841, 5163.3545806774155, 5166.457521960197, 5201.973990130044, 4999.875003124915, 5052.898942021157, 5131.153359695646, 5144.0, 5147.588192944556, 5103.8468845934685, 5106.106431374495, 5122.436531981479, 5127.2052831811125, 5138.691678499302, 4952.108620448354, 5073.923891141599, 5090.440051594322, 5090.490950904917, 5094.1594636884865, 4612.930806037912, 5535.58483113768, 5540.556755459554, 5550.972245138789, 5623.0, 5889.941100588982, 6001.849953751147, 6062.787802426894, 6108.554560543837, 6125.479334256594, 6127.519179552418, 6160.6919654017565, 6182.783602573933, 6186.7525298987985, 6197.03945888388, 5485.451454854483, 5596.300462442195, 5654.46282603152, 5655.830325090254, 5658.91511627326, 6172.598781079216, 6211.720472578745, 6212.0, 6272.18461599989, 6284.528660350492, 5900.380460051632, 5938.020226662552, 5940.6435613863305, 5984.820455386345, 6027.819165425044, 6148.754049838046, 6152.107944348045, 6155.353687862799, 6171.84570385735, 6193.81418557444, 4333.934990975138, 4905.705657660552, 5312.309399778082, 5822.243108395966, 6042.244719410074, 5967.701614919237, 6037.366076561985, 6044.697765111727, 6112.449879510866, 6137.202163718688, 4792.113459010095, 4813.542713442217, 4838.854834354975, 4860.9513904861205, 4873.902521949542, 4226.598473145046, 4251.574842515785, 4252.638525725348, 4302.161078589645, 4353.912921741611, 4752.95247047532, 5754.683492407874, 5790.10253410722, 5861.032929566659, 5877.52979761618, 6188.195534580475, 6191.876162476815, 6199.442050215503, 6223.190985171898, 6236.282827474898, 5743.0, 5822.621529600562, 5835.212246346774, 5882.999890018707, 5911.9704401478575, 5188.610854185951, 5262.0, 5265.289185959847, 5278.656887302313, 5303.628745987744, 6185.072239164251, 6211.503079753609, 6274.780382686584, 6345.587536810092, 6376.202974628171, 6019.51843852482, 6061.212042434456, 6099.0, 6129.267100814889, 6141.07883817431, 6198.659073750987, 6227.0970709246, 6268.4671802897265, 6325.7153428094925, 6331.9366806331345, 5679.971600141973, 6052.334243233153, 6070.1501789750355, 6072.969635151796, 6092.878142437038, 4665.813367465399, 6053.334133245251, 6055.939440605537, 6113.385090541831, 6133.79375043651, 5716.199732037435, 5924.496417804535, 5966.403359663999, 6008.819735407945, 6100.603460775035, 5983.401659833982, 5999.610025348338, 6004.549658775697, 6015.4586087252355, 6015.518758499397, 5620.634658747168, 5755.65466072037, 5767.913481297868, 5867.061270196748, 5874.060150375919, 5798.82603521895, 5811.56413269015, 5833.416658334133, 5907.054871220584, 5939.46544810969, 5713.971430142823, 5753.367129615655, 5766.769329226784, 5768.567357448124, 5850.034744267148], "value": 5538.646174626626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.19557784931743, 481.4607639443821, 516.3313509005837, 519.4234399816204, 532.2654736463679, 37707.99999999996, 39358.60641393585, 39390.81827545171, 40844.38733419001, 41048.99999999993, 40177.799111004475, 40631.79684101583, 40647.79676101623, 40722.38916416256, 40911.795441022754, 9337.019612940663, 9539.761505962371, 9540.236781057534, 9541.618335266581, 9732.65935692251, 9524.190443812284, 9548.331616786827, 9570.28222883283, 9573.13841754242, 9585.37695049823, 9576.80846383072, 9595.856062159039, 9611.615535378576, 9630.518474076303, 9640.228781697448, 9511.429314241168, 9585.616575337013, 9593.42439453635, 9596.376235544667, 9643.08390702882, 9400.811983760286, 9482.95258523703, 9505.477198754066, 9514.429134251966, 9518.238540916711, 38809.61190388088, 39036.0, 39165.41251881224, 39203.60796392028, 39405.40891886624, 3196.8081915085168, 3488.145404375944, 3660.396034654279, 3669.027707657473, 3915.334393153171, 21936.122555097776, 28225.435491290264, 29359.972400965864, 30033.399332013236, 30566.930157444604, 3054.938901221963, 3130.233092892256, 3228.725558327545, 3238.724708399789, 3261.037993791837, 371525.0, 372286.0, 396496.0, 454402.0, 455079.0, 65580.0, 66156.33843661501, 66387.0, 66388.33611663918, 66529.33470665329, 67022.0, 68293.3170668297, 69112.0, 69167.0, 69183.0, 16484.505464836075, 16715.582110447092, 16718.41485548024, 16719.49841504757, 16767.329306827833, 12612.117151799286, 12658.303793291458, 12691.238525688488, 12714.554990575285, 12744.107912446225, 17939.744217904623, 18186.090695465173, 19979.400617981482, 20015.899125548236, 20022.89988550048, 3302.85137168828, 8106.959465202636, 8394.748157555161, 8438.198371154731, 8680.8697869532, 828.3414685325181, 902.7427183252742, 928.2898582584328, 938.216589148069, 1031.664708969573, 29486.410271794874, 29536.704632953824, 29814.701852981627, 30116.09651710452, 30128.69871301303, 901.693424235763, 953.9236861051099, 1053.7154968158554, 1056.5668076088766, 1113.1150735165563, 13974.930125349309, 14994.400223990919, 15381.615459613598, 15562.833274526416, 15631.609209769847, 584857.0, 628179.0, 686525.0, 687435.0, 687825.0, 56349.71825140848, 57163.714181428826, 57748.0, 57778.7111064442, 58031.0, 1362.8023936529148, 1369.1511263016794, 1382.5644921849787, 1387.1330418488444, 1391.5268808604922, 23631.905265046524, 25828.225153245432, 25976.8701156493, 29702.0, 30037.098887033422, 49143.26285105655, 49453.76365590889, 49455.75272123616, 49465.0, 49526.50473495219, 554656.0, 615457.0, 623140.0, 627203.0, 627752.0, 26975.190744277705, 27272.454550909268, 27335.179944601692, 27363.17910462689, 27371.589426159022, 44903.32645010262, 45505.0, 46007.53992460033, 46417.0, 47096.529034709216, 37855.0, 38298.23403531858, 38322.0, 38714.0, 39692.41999270038, 38145.61854381532, 38709.0, 38891.02772430712, 38941.80529097337, 39468.21063578655, 36387.63612363948, 37132.814335928146, 37755.05612359713, 37786.811065944494, 37834.62165378311, 32521.0, 33450.49824252687, 34573.65426345704, 34619.78830740912, 34796.304073917876, 23311.53376932486, 24837.0, 24940.75059249384, 25691.100811471508, 25835.870820645778], "value": 19802.38229639891, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.58968468783394, 367.34979087015984, 369.3462571248891, 378.97157713171487, 398.4998826472778, 18213.544661383472, 18744.0, 19637.214511419563, 19722.408327750156, 19988.90005549974, 18618.0, 18898.433047008613, 19387.0, 19548.02259887007, 19638.901805490954, 5500.779968801243, 5734.311882574076, 5737.483626473617, 5748.281464816898, 5757.596968212227, 5361.651492652965, 5626.352969408509, 5689.374168841424, 5701.258836351269, 5715.257016587838, 4858.1983972644475, 5657.2928383952, 5659.4340565943485, 5665.603407761459, 5665.830025099253, 4865.586425153866, 5449.264349312852, 5663.89554036963, 5793.7392817323325, 5833.38749431312, 5457.863553411157, 5558.0, 5605.131204663284, 5642.746076426571, 5643.0, 12770.680732981657, 12828.871711282862, 12876.871231287661, 12911.0, 13042.282674452901, 2222.9888850555803, 2276.499170182574, 2279.6922415474032, 2314.155333303349, 2358.1982126077214, 9513.904860951441, 9738.9513052435, 9747.658831940918, 10062.547185376676, 10147.644832430904, 1780.9020503872282, 1797.191263931236, 1877.342929974498, 1971.1327016112864, 1984.7022946557988, 114405.42797286043, 115128.0, 120717.0, 124344.0, 126032.0, 10683.252172348017, 11317.49071291794, 11353.659390218307, 11481.0815134789, 11514.76970460603, 13861.792073118912, 14200.147991120566, 14201.289935503184, 14201.431942722176, 14238.85761142396, 7972.960135199287, 8254.339652827759, 8256.669733210605, 8353.749387518385, 8389.622466988878, 8164.877526837102, 8185.427020108655, 8206.22040906113, 8308.584570771438, 8689.957205135424, 4311.0, 4482.372467854503, 4513.323001549794, 4569.977150114295, 4571.6799824012, 1570.2933679844116, 1687.9028631389695, 1771.6722406354866, 1817.963640727178, 1914.258025197064, 858.8110615664543, 927.7799693403153, 939.957701903416, 957.5595226195888, 963.7976025034822, 18792.624147516974, 19458.31895883637, 20222.59554808925, 20267.189312427337, 20553.280635177696, 905.23055402907, 907.6369452219033, 953.8092381523584, 963.3545524498603, 964.4695417520336, 9219.861702074475, 9526.523673816282, 10659.733506662396, 10661.626843060456, 10677.466126693635, 619941.0, 620757.0, 630782.0, 631081.0, 637166.0, 81705.59147204345, 81802.59098704468, 119004.40497797455, 134874.6512534862, 137250.0, 932.6036434515304, 963.6386355116831, 972.3922548407245, 975.205207755691, 981.9509024548744, 17889.642207155524, 18184.818151818312, 18518.44444666662, 18729.30350215864, 18824.0, 50554.74722626364, 53658.0, 56796.716016419654, 58993.705031474565, 61199.16402507932, 71062.64468677624, 82573.0, 86735.0, 88079.0, 89401.65897511413, 7901.841963160819, 7959.522428654068, 8003.6798528058225, 8069.475484093515, 8101.716439924574, 12046.457909393923, 12520.624381268577, 12521.311327877054, 12547.24716517012, 12551.309677967434, 8805.031446541003, 10355.689329320132, 10778.407187604444, 10893.019628233496, 11154.72113197144, 10881.510332035235, 10904.291221070605, 11134.498947547541, 11368.261063030877, 11381.829272561085, 9453.763655908657, 10626.628068017582, 10785.514651840844, 11213.71965700864, 11349.37578433161, 6776.728930842711, 7910.5649189295145, 8030.598470076356, 8094.595270236347, 8132.87800683002, 8014.559199244098, 10376.325538839952, 10960.0, 10961.945190273998, 11219.943900280447], "value": 9161.258358979103, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.9493567725128, 1449.1450044473759, 1622.6592415592725, 1739.6781595404843, 1762.3038899634653, 7470.887936680941, 7702.845943081149, 7954.164812694663, 8449.61976711048, 8457.619407126671, 7933.603319834014, 8222.218889205516, 8235.670573177065, 8309.667613295476, 8317.334613230942, 2303.1133013789727, 2321.895514701834, 2326.162581470669, 2329.173143534038, 2333.3000099970027, 2304.539092181562, 2342.16853017179, 2352.435415500276, 2354.9175778847743, 2367.2779802160335, 2005.6389849827026, 2241.7758224177614, 2255.7293124825046, 2293.2546922250335, 2335.8481698689616, 1732.2724455728562, 2280.0, 2292.3008482412856, 2300.5053913408615, 2309.099451214028, 2268.8638681679154, 2334.241371554235, 2338.4738433852417, 2339.1695947938406, 2340.368100612825, 8262.380321475854, 8294.792630184233, 8374.958125209396, 8378.078411374743, 8380.287675547586, 4344.391785150082, 4365.454318210223, 4384.6273066789045, 4409.713368631029, 5061.772220250097, 2738.7672047875953, 3369.107186595554, 3564.3584154852374, 3575.6424357564297, 3580.4092324766384, 917.7843206846386, 1028.9279750417475, 1085.0722632149477, 1087.456271864068, 1089.54239219528, 8956.373053886231, 10206.234532410099, 10437.217208709377, 10781.676549703521, 10982.286151400212, 4156.397322388237, 4198.538160802309, 4375.5405682403525, 4493.932591011137, 4508.594226519629, 8536.44513106646, 8611.827763444697, 8631.136886311318, 8635.654573817103, 8657.740267791976, 4848.393950756155, 4859.07677541266, 4868.2210846264425, 4869.050535145612, 4878.414590249122, 4262.360645903139, 4409.382686423904, 4539.773011349485, 4643.95356046442, 4744.928826067612, 3632.636736326346, 3668.211334563082, 3701.9629803702155, 3745.9625403745613, 3762.5108735864164, 1146.1690274550883, 1158.9652310430702, 1161.7850697620968, 1166.3876464856019, 1171.1392126786975, 756.3608750605729, 896.9730908072768, 919.117647058831, 925.5279807298326, 929.9070092990783, 5667.263255776723, 5704.458076482728, 5804.071348584206, 5825.126231065289, 5894.587378883522, 894.4677916639589, 900.594732370436, 910.3127139010005, 915.4736026784625, 925.3615005646083, 3705.8517659293325, 3739.7382183247455, 3776.829182953293, 3793.1844653399653, 3802.923941521154, 8644.610992505352, 9221.492817894947, 10250.846237306447, 10256.53845576936, 10263.897361026295, 10139.847902281472, 10247.84628230577, 10249.795004099877, 10284.2801003929, 10316.793664126675, 7778.494397864234, 7971.40214483916, 7974.202579741979, 7976.561289129154, 7978.122406535276, 5379.273798037215, 5473.01485732556, 5541.445855414588, 5635.042042852643, 5691.77232910679, 9377.765555861159, 9443.055694430503, 9531.76170595713, 9535.856962145712, 9536.475493847905, 8793.560321983747, 9404.213199492075, 9491.762705932131, 9653.46905920153, 9748.366356186825, 6284.402981716729, 6476.222853257451, 6562.0, 6672.165979252593, 6781.830454238683, 6740.831479213059, 7583.165851756413, 7831.647575858986, 7832.295093441618, 7862.056553213422, 7052.612106333996, 7191.856162876608, 7200.459965502503, 7230.819229519304, 7299.978003079679, 6940.305969403019, 7048.365647091787, 7107.111611048619, 7113.608751518716, 7148.4281257500315, 5744.0, 6650.733970641314, 6729.024291477806, 6741.764038258638, 6745.52781305304, 4642.653630447133, 4852.2479015752615, 4857.53853383928, 4880.145974454527, 4882.853514394574, 4075.8165882534763, 4453.309736990772, 4527.728336299832, 4960.553550180501, 5055.469175736642], "value": 4324.676925849129, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [239.9748026457223, 270.84291111155534, 299.7347347597376, 308.05734452575115, 311.5747005337713, 4313.35299705044, 4863.294822250771, 5326.750682083925, 5423.810166644168, 5515.889682206353, 4176.770277634729, 4300.4409426774555, 5237.973810130944, 5286.603504737151, 5324.5922609529935, 4065.922530529412, 4095.5494895561465, 4105.486814148231, 4114.218298523271, 4136.048708796979, 3966.3257246268204, 4089.734167279117, 4114.547399786021, 4132.8966775830695, 4151.481064866892, 4030.4809494066208, 4048.817803198863, 4049.2913740095364, 4052.6555242804247, 4083.4691490106243, 3482.129467633079, 3929.2337994091163, 4025.2352053109817, 4066.2477441673386, 4134.6845766370625, 3571.2500374921296, 3572.732045096628, 3576.910577235564, 3582.390993531106, 3601.8019008954498, 4157.605027522411, 4858.659893807435, 4899.583535399495, 5150.639455238135, 5162.354705661793, 4378.868633940987, 4595.724256544585, 4601.677882548223, 4617.630589552828, 4642.512536183686, 4850.514948505156, 5543.066670663113, 5570.832875013756, 5580.693061881595, 5603.245825297061, 3257.7719559630837, 3359.81521016344, 3580.7135429165605, 3724.9441258380875, 3923.941140882889, 5874.412558744134, 5879.941200587982, 5895.4399332063385, 5977.462028417463, 6029.306629737593, 3870.0, 3870.9612903871166, 3878.1855810279594, 3896.162325100117, 3915.667168290727, 3284.8850290240207, 3478.8086655234206, 3821.4267859820693, 3947.8618248361167, 3961.900952476211, 3757.887263382103, 4508.774561271924, 4510.436195475566, 4516.786774480481, 4564.908701825945, 5292.629515933848, 5520.972395137998, 5575.829075894027, 5613.915791263134, 5656.652489217011, 1217.3000524698334, 1217.6712287682276, 1221.1757063981777, 1221.3648902570615, 1273.8152967819615, 719.9280071992758, 736.988945165823, 862.9007664118707, 871.4901782457207, 897.5459755196492, 4574.794134263966, 4634.096351211481, 4670.719756814602, 5132.743362831843, 5318.335208098988, 4686.671932964728, 5482.698451585121, 5538.559974406683, 5551.361593416728, 5641.774329026793, 4812.975935120302, 4862.5380588844, 5034.06869729094, 5049.924251136236, 5126.487351264918, 2458.963115553268, 2647.8878870874278, 2754.325190328382, 2790.6651201855907, 2903.6080129182715, 5127.512886275797, 5528.917066244009, 5752.599261564907, 5755.510781583528, 5821.282721597139, 4367.694261401735, 5121.820736274287, 5362.839114826562, 5504.348695391366, 5537.5569954404355, 5243.68537887728, 5511.834644960658, 5702.232307984538, 5754.741036653444, 5777.468970722762, 4456.629761137886, 4719.952800471951, 5142.357205349332, 5188.896222075462, 5268.394134674561, 4371.229651990949, 5345.1447768356875, 5351.59863010268, 5353.54494867925, 5388.973055134699, 4917.975410122926, 5424.403315635356, 5524.867402182463, 5744.281964754406, 5841.51735512397, 3186.4583020886043, 3601.9099522512147, 3616.649178034299, 3624.4382120771324, 3640.4539319102346, 5362.919556206738, 5371.435999220025, 5401.432849550741, 5408.0, 5437.456254374689, 5023.196288593807, 5124.680641257432, 5208.296879921162, 5224.346956630421, 5319.787208511616, 4828.299896514915, 4933.629977751755, 5223.52988231061, 5228.320318358589, 5231.450697676689, 4928.926066108939, 5139.761949971644, 5182.352205974254, 5220.556252718409, 5371.865703357291, 4564.356831540576, 4918.82784102569, 5222.660527065728, 5630.268065151504, 5685.573581981285, 4575.519570445188, 5296.841094767163, 5481.205225242397, 5516.282883225315, 5529.806456773994], "value": 3966.19357073477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.4932877691012, 743.5835931878148, 749.1833901047856, 762.9122650895149, 804.2882049386295, 14130.929345353272, 14844.55466336009, 14846.55460336189, 14881.776773348394, 14883.702325953474, 14341.713165736704, 14931.776023359658, 14950.700985980275, 15208.315625796839, 15228.847711522882, 567.8580354911272, 636.834423050008, 790.861599220137, 805.7220259010631, 812.1391325195311, 803.4897839871666, 806.7862016565615, 820.2781552234026, 821.3100995164061, 822.198356602314, 862.6376921692904, 863.1368631368631, 864.6887120636566, 865.965361385547, 866.6446756829702, 855.1192271959891, 857.3784006595168, 857.5069335132292, 857.536930057768, 858.6179150278128, 627.6955676496896, 633.885900537903, 634.8254230086717, 636.3445650979452, 639.5842702243532, 14614.34235459407, 14883.851161488356, 14901.0, 15038.699226015418, 15049.172295523742, 13422.53021144265, 13473.595792126252, 13597.796033059512, 13628.727425451436, 13876.692598665366, 11337.489812958436, 11457.942710286396, 11733.12001599875, 11738.823917641243, 11779.293242405483, 3597.118705917041, 3628.546431696038, 3639.8726044588575, 3642.817859107034, 3688.686461650763, 100048.49927251098, 100956.49521752418, 100981.0, 101720.49139754254, 102468.9753102467, 8283.544405057779, 9409.811803763887, 9461.905380946102, 10457.058864702061, 10600.628977985887, 16640.0, 18318.450446486626, 20361.0, 20543.79456205449, 20702.378928632166, 7337.669804858794, 7640.121386040676, 7673.76978690629, 7737.845243095219, 7847.058352997563, 3676.3015027144756, 4152.522459917088, 4216.47294088239, 4309.935350969738, 4310.978445107817, 2787.9024234151707, 3348.2466445049668, 3364.2094107884627, 3371.3931492331126, 3385.81378024211, 619.8574327904607, 641.0768493369533, 641.916550848396, 646.977355792545, 658.7595527632379, 575.8358867722679, 628.4720834499043, 637.4772686397109, 642.7589653879795, 646.7219095788856, 8273.481487376324, 8711.08533603975, 8859.51272679996, 8966.68616598416, 9051.59267832949, 556.2685069134156, 606.9908255969348, 618.2519151826341, 643.5302229372584, 648.1282674802384, 3969.0, 4056.9857535616097, 4128.318827393507, 4138.234426631083, 4282.379055037002, 124041.75958240482, 124222.75777242292, 124300.0, 124588.0, 127416.0, 104747.95252047382, 108247.91752082379, 110727.0, 112853.0, 113084.43457782823, 64922.67538662277, 75273.62363188149, 86725.13274867297, 94321.0567894326, 96871.51564242133, 9007.189352958265, 9083.318751093562, 9434.528273586431, 9515.809683806423, 9545.522723863918, 44566.3315050281, 48502.757486212344, 49366.50633493763, 55654.0, 56129.31612051645, 75257.87113193415, 79109.60445197737, 79524.0, 79729.0, 82990.75513867177, 11984.161108722306, 12269.141160118706, 12327.75344493123, 12480.438380272728, 12492.188007779465, 13029.804552931902, 13088.62545584632, 13137.277449740355, 13203.735925281633, 13387.598372048855, 12935.741285174432, 13167.934160329138, 13211.471541138248, 13340.3329833506, 13423.798643020558, 13032.283224422368, 13115.475380984653, 13126.540571079966, 13198.868011320148, 13396.26320552379, 11821.231619944676, 11841.881581184078, 11951.462184201559, 12093.213941093802, 12113.878861211275, 10932.88453996177, 11132.443377830914, 11366.829497557377, 11532.596359127725, 11635.0, 11111.888881111085, 11141.108711303239, 11439.199256051996, 11506.367149806842, 11511.136664750009], "value": 7309.820053716199, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 124.0, 118.0, 119.0, 119.0, 121.0, 122.0, 120.0, 119.0, 122.0], "value": 120.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 112.0, 141.0, 115.0, 129.0, 142.0, 113.0, 144.0, 112.0], "value": 126.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 197.0, 198.0, 200.0, 226.0, 192.0, 167.0, 261.0, 272.0, 213.0], "value": 212.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 61.0, 83.0, 61.0, 62.0, 61.0, 64.0, 109.0, 82.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 71.0, 90.0, 72.0, 71.0, 71.0, 87.0, 71.0, 73.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 92.0, 89.0, 93.0, 105.0, 95.0, 131.0, 91.0, 89.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 102.0, 101.0, 99.0, 101.0, 110.0, 166.0, 167.0, 171.0, 167.0], "value": 129.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 130.0, 134.0, 91.0, 73.0, 135.0, 140.0, 90.0, 130.0, 87.0], "value": 109.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 57.0, 59.0, 61.0, 57.0, 58.0, 60.0, 62.0, 61.0, 60.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 78.0, 77.0, 76.0, 71.0, 86.0, 91.0, 105.0, 105.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 96.0, 100.0, 75.0, 98.0, 105.0, 105.0, 85.0, 85.0], "value": 92.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 163.0, 145.0, 139.0, 149.0, 142.0, 142.0, 148.0, 227.0, 160.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 229.0, 199.0, 167.0, 169.0, 171.0, 173.0, 169.0, 167.0, 191.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 67.0, 68.0, 68.0, 68.0, 68.0, 70.0, 69.0, 67.0], "value": 68.5, "unit": "score"}]}]} 08:51:48 INFO - Return code: 0 08:51:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:51:48 ERROR - # TBPL SUCCESS # 08:51:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:48 INFO - 2016-12-21 08:51:48.500 screenresolution[1664:10642] starting screenresolution argv=screenresolution get 08:51:48 INFO - 2016-12-21 08:51:48.504 screenresolution[1664:10642] Display 0: 1600x1200x32@60 08:51:48 INFO - 2016-12-21 08:51:48.510 screenresolution[1665:10645] starting screenresolution argv=screenresolution list 08:51:48 INFO - Available Modes on Display 0 08:51:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:48 INFO - Intel Iris: 08:51:48 INFO - Chipset Model: Intel Iris 08:51:48 INFO - Type: GPU 08:51:48 INFO - Bus: Built-In 08:51:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:48 INFO - Vendor: Intel (0x8086) 08:51:48 INFO - Device ID: 0x0a2e 08:51:48 INFO - Revision ID: 0x0009 08:51:48 INFO - Displays: 08:51:48 INFO - Display: 08:51:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:48 INFO - Display Serial Number: 1600x1200 60 08:51:48 INFO - Main Display: Yes 08:51:48 INFO - Mirror: Off 08:51:48 INFO - Online: Yes 08:51:48 INFO - Rotation: Supported 08:51:48 INFO - Return code: 0 08:51:48 INFO - Running post-action listener: _resource_record_post_action 08:51:48 INFO - [mozharness: 2016-12-21 16:51:48.751209Z] Finished run-tests step (success) 08:51:48 INFO - Running post-run listener: _resource_record_post_run 08:51:48 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 39897600; Write bytes: 660148224; Read time: 388; Write time: 2058 08:51:48 INFO - TinderboxPrint: CPU usage
54.5% 08:51:48 INFO - TinderboxPrint: I/O read bytes / time
39,897,600 / 388 08:51:48 INFO - TinderboxPrint: I/O write bytes / time
660,148,224 / 2,058 08:51:48 INFO - TinderboxPrint: CPU idle
2,203.6 (45.5%) 08:51:48 INFO - TinderboxPrint: CPU system
977.7 (20.2%) 08:51:48 INFO - TinderboxPrint: CPU user
1,664.4 (34.3%) 08:51:48 INFO - TinderboxPrint: Swap in / out
388,784,128 / 0 08:51:48 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348039680; Write bytes: 344995840; Read time: 27923; Write time: 688 08:51:48 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 35461632; Write bytes: 307853312; Read time: 355; Write time: 1342 08:51:49 INFO - Running post-run listener: _upload_blobber_files 08:51:49 INFO - Blob upload gear active. 08:51:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:51:49 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 08:51:49 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'] 08:51:49 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 08:51:49 INFO - (blobuploader) - INFO - Open directory for files ... 08:51:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:51:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:51:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:50 INFO - (blobuploader) - INFO - Done attempting. 08:51:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:51:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:51:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:51 INFO - (blobuploader) - INFO - Done attempting. 08:51:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:51:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:51:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:53 INFO - (blobuploader) - INFO - Done attempting. 08:51:53 INFO - (blobuploader) - INFO - Iteration through files over. 08:51:53 INFO - Return code: 0 08:51:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:51:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:51:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c93f19747036c09567cecdeced3ed7c95f031bf57572ae94787d2ccff7d3db68c6f0e5aa01235f06b6ff8e135fde5b984a7d9a4a8c0efb41014c9655eaec09c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5cb6554f54f0f2af88da74fc33409881d9d626d6e84518755e1bf2af403ee2cd96263354ed57eeb588104e795ffdb1c9c73340ccc3c930061b4a6632f4b80192", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffc398476244d56f8a9417c8ae53c0860acfdf3fb5bfd2b0e5c62319a2748f2fd11947eb1bbae426ee7f7bc77e92543e099a5584886cf9f044f79f0abe3c7263"} 08:51:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:51:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:51:53 INFO - Contents: 08:51:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c93f19747036c09567cecdeced3ed7c95f031bf57572ae94787d2ccff7d3db68c6f0e5aa01235f06b6ff8e135fde5b984a7d9a4a8c0efb41014c9655eaec09c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5cb6554f54f0f2af88da74fc33409881d9d626d6e84518755e1bf2af403ee2cd96263354ed57eeb588104e795ffdb1c9c73340ccc3c930061b4a6632f4b80192", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffc398476244d56f8a9417c8ae53c0860acfdf3fb5bfd2b0e5c62319a2748f2fd11947eb1bbae426ee7f7bc77e92543e099a5584886cf9f044f79f0abe3c7263"} 08:51:53 INFO - Running post-run listener: copy_logs_to_upload_dir 08:51:53 INFO - Copying logs to upload dir... 08:51:53 INFO - mkdir: /builds/slave/test/build/upload/logs 08:51:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.145343 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-21 08:51:54.039287) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 08:51:54.042886) ========= 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.fJiQNmSlfC/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.d3qb8whAeX/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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/5c93f19747036c09567cecdeced3ed7c95f031bf57572ae94787d2ccff7d3db68c6f0e5aa01235f06b6ff8e135fde5b984a7d9a4a8c0efb41014c9655eaec09c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5cb6554f54f0f2af88da74fc33409881d9d626d6e84518755e1bf2af403ee2cd96263354ed57eeb588104e795ffdb1c9c73340ccc3c930061b4a6632f4b80192", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffc398476244d56f8a9417c8ae53c0860acfdf3fb5bfd2b0e5c62319a2748f2fd11947eb1bbae426ee7f7bc77e92543e099a5584886cf9f044f79f0abe3c7263"} build_url:https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/OdFJLa8ySPulDePM6tboag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c93f19747036c09567cecdeced3ed7c95f031bf57572ae94787d2ccff7d3db68c6f0e5aa01235f06b6ff8e135fde5b984a7d9a4a8c0efb41014c9655eaec09c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5cb6554f54f0f2af88da74fc33409881d9d626d6e84518755e1bf2af403ee2cd96263354ed57eeb588104e795ffdb1c9c73340ccc3c930061b4a6632f4b80192", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffc398476244d56f8a9417c8ae53c0860acfdf3fb5bfd2b0e5c62319a2748f2fd11947eb1bbae426ee7f7bc77e92543e099a5584886cf9f044f79f0abe3c7263"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 08:51:54.078834) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 08:51:54.079192) ========= 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.fJiQNmSlfC/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.d3qb8whAeX/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.005090 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 08:51:54.128448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 08:51:54.128773) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 08:51:54.129083) ========= ========= Total master_lag: 0.18 =========