builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0096 starttime: 1481827949.06 results: success (0) buildid: 20161215080001 builduid: a5a9e261874c4c6c93b4e1338acb9858 revision: 1b23c1469bb9059b11e573a621844df55257396d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:29.055908) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:29.056359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:29.056661) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:29.084776) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:29.085267) ========= 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.3jv91dWzjz/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.SgzGZ1SOap/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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-15 10:52:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.60M=0.001s 2016-12-15 10:52:29 (8.60 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117377 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:29.291884) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:29.292222) ========= 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.3jv91dWzjz/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.SgzGZ1SOap/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.019286 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:29.334045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 10:52:29.334400) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1b23c1469bb9059b11e573a621844df55257396d --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 1b23c1469bb9059b11e573a621844df55257396d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3jv91dWzjz/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.SgzGZ1SOap/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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-15 10:52:29,414 truncating revision to first 12 chars 2016-12-15 10:52:29,414 Setting DEBUG logging. 2016-12-15 10:52:29,414 attempt 1/10 2016-12-15 10:52:29,414 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1b23c1469bb9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 10:52:30,557 unpacking tar archive at: mozilla-aurora-1b23c1469bb9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.400624 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 10:52:30.764796) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:30.765179) ========= script_repo_revision: 1b23c1469bb9059b11e573a621844df55257396d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:30.765630) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:30.765967) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 10:52:30.786111) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-15 10:52:30.786554) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.3jv91dWzjz/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.SgzGZ1SOap/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:52:30 INFO - MultiFileLogger online at 20161215 10:52:30 in /builds/slave/test 10:52:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:52:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:52:30 INFO - {'append_to_log': False, 10:52:30 INFO - 'base_work_dir': '/builds/slave/test', 10:52:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:52:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:52:30 INFO - 'branch': 'Mozilla-Aurora', 10:52:30 INFO - 'buildbot_json_path': 'buildprops.json', 10:52:30 INFO - 'config_files': ('talos/mac_config.py',), 10:52:30 INFO - 'default_actions': ('clobber', 10:52:30 INFO - 'read-buildbot-config', 10:52:30 INFO - 'download-and-extract', 10:52:30 INFO - 'populate-webroot', 10:52:30 INFO - 'create-virtualenv', 10:52:30 INFO - 'install', 10:52:30 INFO - 'run-tests'), 10:52:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:52:30 INFO - 'download_minidump_stackwalk': True, 10:52:30 INFO - 'download_symbols': 'ondemand', 10:52:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:52:30 INFO - 'tooltool.py': '/tools/tooltool.py', 10:52:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:52:30 INFO - '/tools/misc-python/virtualenv.py')}, 10:52:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:52:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:52:30 INFO - 'installer_path': 'installer.exe', 10:52:30 INFO - 'log_level': 'info', 10:52:30 INFO - 'log_name': 'talos', 10:52:30 INFO - 'log_to_console': True, 10:52:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:52:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:52:30 INFO - 'opt_config_files': (), 10:52:30 INFO - 'pip_index': False, 10:52:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:52:30 INFO - 'cmd': ('bash', 10:52:30 INFO - '-c', 10:52:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:52:30 INFO - 'enabled': True, 10:52:30 INFO - 'halt_on_failure': False, 10:52:30 INFO - 'name': 'check_screen_resolution'},), 10:52:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:52:30 INFO - 'cmd': ('bash', 10:52:30 INFO - '-c', 10:52:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:52:30 INFO - 'enabled': True, 10:52:30 INFO - 'halt_on_failure': False, 10:52:30 INFO - 'name': 'check_screen_resolution'},), 10:52:30 INFO - 'run_cmd_checks_enabled': True, 10:52:30 INFO - 'sps_profile': False, 10:52:30 INFO - 'sps_profile_interval': 0, 10:52:30 INFO - 'suite': 'dromaeojs', 10:52:30 INFO - 'system_bits': '32', 10:52:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:52:30 INFO - 'title': 't-yosemite-r7-0096', 10:52:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:52:30 INFO - 'use_talos_json': True, 10:52:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:52:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:52:30 INFO - 'work_dir': 'build'} 10:52:30 INFO - [mozharness: 2016-12-15 18:52:30.985988Z] Running clobber step. 10:52:30 INFO - Running pre-action listener: _resource_record_pre_action 10:52:30 INFO - Running main action method: clobber 10:52:30 INFO - rmtree: /builds/slave/test/build 10:52:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:52:32 INFO - Running post-action listener: _resource_record_post_action 10:52:32 INFO - [mozharness: 2016-12-15 18:52:32.518228Z] Finished clobber step (success) 10:52:32 INFO - [mozharness: 2016-12-15 18:52:32.518360Z] Running read-buildbot-config step. 10:52:32 INFO - Running pre-action listener: _resource_record_pre_action 10:52:32 INFO - Running main action method: read_buildbot_config 10:52:32 INFO - Using buildbot properties: 10:52:32 INFO - { 10:52:32 INFO - "project": "", 10:52:32 INFO - "product": "firefox", 10:52:32 INFO - "script_repo_revision": "production", 10:52:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:52:32 INFO - "repository": "", 10:52:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:52:32 INFO - "buildid": "20161215080001", 10:52:32 INFO - "pgo_build": "False", 10:52:32 INFO - "basedir": "/builds/slave/test", 10:52:32 INFO - "buildnumber": 43, 10:52:32 INFO - "slavename": "t-yosemite-r7-0096", 10:52:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:52:32 INFO - "platform": "yosemite_r7", 10:52:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:52:32 INFO - "branch": "mozilla-aurora", 10:52:32 INFO - "repo_path": "releases/mozilla-aurora", 10:52:32 INFO - "revision": "1b23c1469bb9059b11e573a621844df55257396d", 10:52:32 INFO - "stage_platform": "macosx64", 10:52:32 INFO - "builduid": "a5a9e261874c4c6c93b4e1338acb9858", 10:52:32 INFO - "slavebuilddir": "test" 10:52:32 INFO - } 10:52:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:52:32 INFO - Running post-action listener: _resource_record_post_action 10:52:32 INFO - [mozharness: 2016-12-15 18:52:32.521825Z] Finished read-buildbot-config step (success) 10:52:32 INFO - [mozharness: 2016-12-15 18:52:32.521954Z] Running download-and-extract step. 10:52:32 INFO - Running pre-action listener: _resource_record_pre_action 10:52:32 INFO - Running main action method: download_and_extract 10:52:32 INFO - mkdir: /builds/slave/test/build/tests 10:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:32 INFO - trying https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:52:32 INFO - Downloading https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/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 10:52:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/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 10:52:33 INFO - Downloaded 1199 bytes. 10:52:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:52:33 INFO - Using the following test package requirements: 10:52:33 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:52:33 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:52:33 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:52:33 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:52:33 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:52:33 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:52:33 INFO - u'jsshell-mac.zip'], 10:52:33 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:52:33 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:52:33 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:52:33 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:52:33 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:52:33 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:52:33 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:52:33 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:52:33 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:52:33 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:52:33 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:52:33 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:52:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:52:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:52:33 INFO - Fetch https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:52:34 INFO - Http code: 200 10:52:34 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 10:52:34 INFO - x-amz-cf-id: PPxQP8gMJDBzLOdMqSTSXKfaiaUYWyHuwX4O9D6iIUh9nOoSmvugpw== 10:52:34 INFO - x-amz-version-id: 8C2R4kV3MwPUW3ay9uarllzGG.cYVKwt 10:52:34 INFO - Expected file size: 16281107 10:52:34 INFO - Obtained file size: 16281107 10:52:41 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 10:52:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:52:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:52:41 INFO - Fetch https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:52:41 INFO - Http code: 200 10:52:41 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 10:52:41 INFO - x-amz-cf-id: SploBJC-KEZkqWT3aC5zg4jdTzRlrZEj4PbEHCSULHwJXwpQeGOAWw== 10:52:41 INFO - x-amz-version-id: 8C2R4kV3MwPUW3ay9uarllzGG.cYVKwt 10:52:42 INFO - Expected file size: 16281107 10:52:42 INFO - Obtained file size: 16281107 10:52:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:52:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:52:48 INFO - Fetch https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:52:49 INFO - Http code: 200 10:52:49 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 10:52:49 INFO - x-amz-cf-id: ES7Nw162jNUMmLLLMz_SJoAKhBmh38u2BcCpprqx83Dtvca6hjgg8Q== 10:52:49 INFO - x-amz-version-id: ORwUL4fS_V5yVzuTbUm.F3VYVSbArBI6 10:52:49 INFO - Expected file size: 14409038 10:52:49 INFO - Obtained file size: 14409038 10:52:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:50 INFO - trying https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:52:50 INFO - Downloading https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:52:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/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 10:52:52 INFO - Downloaded 109725950 bytes. 10:52:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:52:52 INFO - mkdir: /builds/slave/test/properties 10:52:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:52:52 INFO - Writing to file /builds/slave/test/properties/build_url 10:52:52 INFO - Contents: 10:52:52 INFO - build_url:https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:52:53 INFO - Running post-action listener: _resource_record_post_action 10:52:53 INFO - Running post-action listener: set_extra_try_arguments 10:52:53 INFO - [mozharness: 2016-12-15 18:52:53.675128Z] Finished download-and-extract step (success) 10:52:53 INFO - [mozharness: 2016-12-15 18:52:53.675279Z] Running populate-webroot step. 10:52:53 INFO - Running pre-action listener: _resource_record_pre_action 10:52:53 INFO - Running main action method: populate_webroot 10:52:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:52:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:52:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:52:53 INFO - '--tppagecycles', 10:52:53 INFO - '7'], 10:52:53 INFO - 'tests': ['tsvgm']}}, 10:52:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:52:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:52:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:52:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:52:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:53 INFO - 'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:53 INFO - 'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['damp', 'tps']}, 10:52:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:53 INFO - 'tests': ['damp', 'tps']}, 10:52:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['dromaeo_dom']}, 10:52:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:52:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['basic_compositor_video']}, 10:52:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:52:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['a11yr', 10:52:53 INFO - 'ts_paint', 10:52:53 INFO - 'tpaint', 10:52:53 INFO - 'sessionrestore', 10:52:53 INFO - 'sessionrestore_no_auto_restore', 10:52:53 INFO - 'tabpaint']}, 10:52:53 INFO - 'other-e10s': {'tests': ['a11yr', 10:52:53 INFO - 'ts_paint', 10:52:53 INFO - 'tpaint', 10:52:53 INFO - 'sessionrestore', 10:52:53 INFO - 'sessionrestore_no_auto_restore', 10:52:53 INFO - 'tabpaint']}, 10:52:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:52:53 INFO - 'ts_paint', 10:52:53 INFO - 'tpaint', 10:52:53 INFO - 'sessionrestore', 10:52:53 INFO - 'sessionrestore_no_auto_restore', 10:52:53 INFO - 'tabpaint']}, 10:52:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:52:53 INFO - 'ts_paint', 10:52:53 INFO - 'tpaint', 10:52:53 INFO - 'sessionrestore', 10:52:53 INFO - 'sessionrestore_no_auto_restore', 10:52:53 INFO - 'tabpaint']}, 10:52:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['a11yr', 10:52:53 INFO - 'ts_paint', 10:52:53 INFO - 'tpaint', 10:52:53 INFO - 'sessionrestore', 10:52:53 INFO - 'sessionrestore_no_auto_restore', 10:52:53 INFO - 'tabpaint']}, 10:52:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['a11yr', 10:52:53 INFO - 'ts_paint', 10:52:53 INFO - 'tpaint', 10:52:53 INFO - 'sessionrestore', 10:52:53 INFO - 'sessionrestore_no_auto_restore', 10:52:53 INFO - 'tabpaint']}, 10:52:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['tsvgx', 10:52:53 INFO - 'tsvgr_opacity', 10:52:53 INFO - 'tart', 10:52:53 INFO - 'tscrollx', 10:52:53 INFO - 'cart']}, 10:52:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:52:53 INFO - 'tsvgr_opacity', 10:52:53 INFO - 'tart', 10:52:53 INFO - 'tscrollx', 10:52:53 INFO - 'cart']}, 10:52:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:53 INFO - 'talos_options': ['--disable-e10s'], 10:52:53 INFO - 'tests': ['tp5o']}, 10:52:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:53 INFO - 'tests': ['tp5o']}, 10:52:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:53 INFO - 'talos_options': ['--disable-e10s', 10:52:53 INFO - '--xperf_path', 10:52:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:53 INFO - 'tests': ['tp5n']}, 10:52:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:53 INFO - 'talos_options': ['--xperf_path', 10:52:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:53 INFO - 'tests': ['tp5n']}}, 10:52:53 INFO - 'talos.zip': {'path': '', 10:52:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:52:53 INFO - Running post-action listener: _resource_record_post_action 10:52:53 INFO - [mozharness: 2016-12-15 18:52:53.694194Z] Finished populate-webroot step (success) 10:52:53 INFO - [mozharness: 2016-12-15 18:52:53.694328Z] Running create-virtualenv step. 10:52:53 INFO - Running pre-action listener: _resource_record_pre_action 10:52:53 INFO - Running main action method: create_virtualenv 10:52:53 INFO - Creating virtualenv /builds/slave/test/build/venv 10:52:53 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 10:52:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:52:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:52:53 INFO - Using real prefix '/tools/python27' 10:52:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:52:54 INFO - Installing distribute.............................................................................................................................................................................................done. 10:52:57 INFO - Installing pip.................done. 10:52:57 INFO - Return code: 0 10:52:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:52:57 INFO - Reading from file tmpfile_stdout 10:52:57 INFO - Output received: 10:52:57 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) 10:52:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:57 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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:52:57 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 10:52:57 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 10:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:57 INFO - 'HOME': '/Users/cltbld', 10:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:57 INFO - 'LOGNAME': 'cltbld', 10:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:57 INFO - 'MOZ_NO_REMOTE': '1', 10:52:57 INFO - 'NO_EM_RESTART': '1', 10:52:57 INFO - 'PAGER': '/bin/cat', 10:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:57 INFO - 'PWD': '/builds/slave/test', 10:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:57 INFO - 'SHELL': '/bin/bash', 10:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:52:57 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:57 INFO - 'USER': 'cltbld', 10:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:57 INFO - 'XPC_FLAGS': '0x0', 10:52:57 INFO - 'XPC_SERVICE_NAME': '0', 10:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:57 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 10:52:57 INFO - Cleaning up... 10:52:57 INFO - Return code: 0 10:52:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:57 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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:52:57 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 10:52:57 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 10:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:57 INFO - 'HOME': '/Users/cltbld', 10:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:57 INFO - 'LOGNAME': 'cltbld', 10:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:57 INFO - 'MOZ_NO_REMOTE': '1', 10:52:57 INFO - 'NO_EM_RESTART': '1', 10:52:57 INFO - 'PAGER': '/bin/cat', 10:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:57 INFO - 'PWD': '/builds/slave/test', 10:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:57 INFO - 'SHELL': '/bin/bash', 10:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:52:57 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:57 INFO - 'USER': 'cltbld', 10:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:57 INFO - 'XPC_FLAGS': '0x0', 10:52:57 INFO - 'XPC_SERVICE_NAME': '0', 10:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:58 INFO - Downloading/unpacking psutil>=3.1.1 10:52:58 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 10:52:58 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 10:52:58 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 10:52:58 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 10:52:58 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 10:52:58 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 10:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:53:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:53:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:53:01 INFO - Installing collected packages: psutil 10:53:01 INFO - Running setup.py install for psutil 10:53:01 INFO - building 'psutil._psutil_osx' extension 10:53:01 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 10:53:02 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 10:53:02 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 10:53:02 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 10:53:02 INFO - building 'psutil._psutil_posix' extension 10:53:02 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 10:53:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:53:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:53:02 INFO - ^ 10:53:02 INFO - 1 warning generated. 10:53:02 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 10:53:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:53:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:53:02 INFO - Successfully installed psutil 10:53:02 INFO - Cleaning up... 10:53:02 INFO - Return code: 0 10:53:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:02 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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:53:02 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 10:53:02 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 10:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:02 INFO - 'HOME': '/Users/cltbld', 10:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:02 INFO - 'LOGNAME': 'cltbld', 10:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:02 INFO - 'MOZ_NO_REMOTE': '1', 10:53:02 INFO - 'NO_EM_RESTART': '1', 10:53:02 INFO - 'PAGER': '/bin/cat', 10:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:02 INFO - 'PWD': '/builds/slave/test', 10:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:02 INFO - 'SHELL': '/bin/bash', 10:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:02 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:02 INFO - 'USER': 'cltbld', 10:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:02 INFO - 'XPC_FLAGS': '0x0', 10:53:02 INFO - 'XPC_SERVICE_NAME': '0', 10:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:53:02 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 10:53:02 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 10:53:02 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 10:53:02 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 10:53:02 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 10:53:02 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 10:53:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:53:05 INFO - Installing collected packages: mozsystemmonitor 10:53:05 INFO - Running setup.py install for mozsystemmonitor 10:53:05 INFO - Successfully installed mozsystemmonitor 10:53:05 INFO - Cleaning up... 10:53:05 INFO - Return code: 0 10:53:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:53:05 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')]} 10:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:05 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')]} 10:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:05 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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:53:05 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 10:53:05 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 10:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:05 INFO - 'HOME': '/Users/cltbld', 10:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:05 INFO - 'LOGNAME': 'cltbld', 10:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:05 INFO - 'MOZ_NO_REMOTE': '1', 10:53:05 INFO - 'NO_EM_RESTART': '1', 10:53:05 INFO - 'PAGER': '/bin/cat', 10:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:05 INFO - 'PWD': '/builds/slave/test', 10:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:05 INFO - 'SHELL': '/bin/bash', 10:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:05 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:05 INFO - 'USER': 'cltbld', 10:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:05 INFO - 'XPC_FLAGS': '0x0', 10:53:05 INFO - 'XPC_SERVICE_NAME': '0', 10:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:05 INFO - Downloading/unpacking jsonschema==2.5.1 10:53:05 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 10:53:05 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 10:53:05 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 10:53:05 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 10:53:05 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 10:53:05 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 10:53:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:53:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:08 INFO - Downloading functools32-3.2.3-2.tar.gz 10:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:53:08 INFO - warning: no files found matching '*.txt' 10:53:08 INFO - no previously-included directories found matching 'build' 10:53:08 INFO - no previously-included directories found matching 'dist' 10:53:08 INFO - no previously-included directories found matching '.git*' 10:53:08 INFO - Installing collected packages: jsonschema, functools32 10:53:09 INFO - Running setup.py install for functools32 10:53:09 INFO - warning: no files found matching '*.txt' 10:53:09 INFO - no previously-included directories found matching 'build' 10:53:09 INFO - no previously-included directories found matching 'dist' 10:53:09 INFO - no previously-included directories found matching '.git*' 10:53:09 INFO - Successfully installed jsonschema functools32 10:53:09 INFO - Cleaning up... 10:53:09 INFO - Return code: 0 10:53:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:53:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:09 INFO - 'HOME': '/Users/cltbld', 10:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:09 INFO - 'LOGNAME': 'cltbld', 10:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:09 INFO - 'MOZ_NO_REMOTE': '1', 10:53:09 INFO - 'NO_EM_RESTART': '1', 10:53:09 INFO - 'PAGER': '/bin/cat', 10:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:09 INFO - 'PWD': '/builds/slave/test', 10:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:09 INFO - 'SHELL': '/bin/bash', 10:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:09 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:09 INFO - 'USER': 'cltbld', 10:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:09 INFO - 'XPC_FLAGS': '0x0', 10:53:09 INFO - 'XPC_SERVICE_NAME': '0', 10:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:53:09 INFO - Cleaning up... 10:53:09 INFO - Return code: 0 10:53:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:53:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:09 INFO - 'HOME': '/Users/cltbld', 10:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:09 INFO - 'LOGNAME': 'cltbld', 10:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:09 INFO - 'MOZ_NO_REMOTE': '1', 10:53:09 INFO - 'NO_EM_RESTART': '1', 10:53:09 INFO - 'PAGER': '/bin/cat', 10:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:09 INFO - 'PWD': '/builds/slave/test', 10:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:09 INFO - 'SHELL': '/bin/bash', 10:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:09 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:09 INFO - 'USER': 'cltbld', 10:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:09 INFO - 'XPC_FLAGS': '0x0', 10:53:09 INFO - 'XPC_SERVICE_NAME': '0', 10:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:09 INFO - Downloading/unpacking blobuploader==1.2.4 10:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:12 INFO - Downloading blobuploader-1.2.4.tar.gz 10:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:53:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:53:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:13 INFO - Downloading docopt-0.6.1.tar.gz 10:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:53:13 INFO - Installing collected packages: blobuploader, requests, docopt 10:53:13 INFO - Running setup.py install for blobuploader 10:53:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:53:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:53:13 INFO - Running setup.py install for requests 10:53:14 INFO - Running setup.py install for docopt 10:53:14 INFO - Successfully installed blobuploader requests docopt 10:53:14 INFO - Cleaning up... 10:53:14 INFO - Return code: 0 10:53:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:14 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')]} 10:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:14 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')]} 10:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:15 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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:53:15 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 10:53:15 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 10:53:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:15 INFO - 'HOME': '/Users/cltbld', 10:53:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:15 INFO - 'LOGNAME': 'cltbld', 10:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:15 INFO - 'MOZ_NO_REMOTE': '1', 10:53:15 INFO - 'NO_EM_RESTART': '1', 10:53:15 INFO - 'PAGER': '/bin/cat', 10:53:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:15 INFO - 'PWD': '/builds/slave/test', 10:53:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:15 INFO - 'SHELL': '/bin/bash', 10:53:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:15 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:15 INFO - 'USER': 'cltbld', 10:53:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:15 INFO - 'XPC_FLAGS': '0x0', 10:53:15 INFO - 'XPC_SERVICE_NAME': '0', 10:53:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:15 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-qv85ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:15 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-a8IvUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:15 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-EvNjlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:15 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-haHYSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:15 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-qeBVkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-8CoFz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Bvrark-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-HPEQun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-vthQBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-1hI5C5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-geYJ_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-24ABTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-BMW8Q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-eDFPFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:16 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-rvLEZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:17 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-jnp7uA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:17 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-mhdsXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:53:17 INFO - Running setup.py install for manifestparser 10:53:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:53:17 INFO - Running setup.py install for mozcrash 10:53:17 INFO - Running setup.py install for mozdebug 10:53:17 INFO - Running setup.py install for mozdevice 10:53:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:53:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:53:17 INFO - Running setup.py install for mozfile 10:53:18 INFO - Running setup.py install for mozhttpd 10:53:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:53:18 INFO - Running setup.py install for mozinfo 10:53:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:53:18 INFO - Running setup.py install for mozInstall 10:53:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:53:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:53:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:53:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:53:18 INFO - Running setup.py install for mozleak 10:53:18 INFO - Running setup.py install for mozlog 10:53:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:53:18 INFO - Running setup.py install for moznetwork 10:53:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:53:18 INFO - Running setup.py install for mozprocess 10:53:19 INFO - Running setup.py install for mozprofile 10:53:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:53:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:53:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:53:19 INFO - Running setup.py install for mozrunner 10:53:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:53:19 INFO - Running setup.py install for mozscreenshot 10:53:19 INFO - Running setup.py install for moztest 10:53:19 INFO - Running setup.py install for mozversion 10:53:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:53:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:53:19 INFO - Cleaning up... 10:53:19 INFO - Return code: 0 10:53:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53: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')]} 10:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53: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')]} 10:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:20 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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:53:20 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 10:53:20 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 10:53:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:20 INFO - 'HOME': '/Users/cltbld', 10:53:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:20 INFO - 'LOGNAME': 'cltbld', 10:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:20 INFO - 'MOZ_NO_REMOTE': '1', 10:53:20 INFO - 'NO_EM_RESTART': '1', 10:53:20 INFO - 'PAGER': '/bin/cat', 10:53:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:20 INFO - 'PWD': '/builds/slave/test', 10:53:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:20 INFO - 'SHELL': '/bin/bash', 10:53:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:20 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:20 INFO - 'USER': 'cltbld', 10:53:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:20 INFO - 'XPC_FLAGS': '0x0', 10:53:20 INFO - 'XPC_SERVICE_NAME': '0', 10:53:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:20 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-TQXCTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:20 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)) 10:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:20 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NOUXY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:20 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)) 10:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:20 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-GD22UO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:20 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)) 10:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:20 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-fc2OCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:20 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)) 10:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:20 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-1iI7QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-WeK_n_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-SKQXAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-zEsCoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-JIhwPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-7D0lmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-lj7k5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-bWWF3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-L4etub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:21 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)) 10:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-YY3llj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:22 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)) 10:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-A6jv26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:22 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)) 10:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-WkcjYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:22 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)) 10:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-y5rZN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:22 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)) 10:53:22 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)) 10:53:22 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)) 10:53:22 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)) 10:53:22 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)) 10:53:22 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)) 10:53:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:25 INFO - Downloading blessings-1.6.tar.gz 10:53:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:53:25 INFO - Installing collected packages: blessings 10:53:25 INFO - Running setup.py install for blessings 10:53:25 INFO - Successfully installed blessings 10:53:25 INFO - Cleaning up... 10:53:25 INFO - Return code: 0 10:53:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:25 INFO - Reading from file tmpfile_stdout 10:53:25 INFO - Current package versions: 10:53:25 INFO - blessings == 1.6 10:53:25 INFO - blobuploader == 1.2.4 10:53:25 INFO - docopt == 0.6.1 10:53:25 INFO - functools32 == 3.2.3-2 10:53:25 INFO - jsonschema == 2.5.1 10:53:25 INFO - manifestparser == 1.1 10:53:25 INFO - mozInstall == 1.12 10:53:25 INFO - mozcrash == 1.0 10:53:25 INFO - mozdebug == 0.1 10:53:25 INFO - mozdevice == 0.48 10:53:25 INFO - mozfile == 1.2 10:53:25 INFO - mozhttpd == 0.7 10:53:25 INFO - mozinfo == 0.9 10:53:25 INFO - mozleak == 0.1 10:53:25 INFO - mozlog == 3.4 10:53:25 INFO - moznetwork == 0.27 10:53:25 INFO - mozprocess == 0.23 10:53:25 INFO - mozprofile == 0.28 10:53:25 INFO - mozrunner == 6.13 10:53:25 INFO - mozscreenshot == 0.1 10:53:25 INFO - mozsystemmonitor == 0.3 10:53:25 INFO - moztest == 0.7 10:53:25 INFO - mozversion == 1.4 10:53:25 INFO - psutil == 3.1.1 10:53:25 INFO - requests == 1.2.3 10:53:25 INFO - wsgiref == 0.1.2 10:53:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:25 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 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:53:25 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 10:53:25 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 10:53:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:25 INFO - 'HOME': '/Users/cltbld', 10:53:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:25 INFO - 'LOGNAME': 'cltbld', 10:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:25 INFO - 'MOZ_NO_REMOTE': '1', 10:53:25 INFO - 'NO_EM_RESTART': '1', 10:53:25 INFO - 'PAGER': '/bin/cat', 10:53:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:25 INFO - 'PWD': '/builds/slave/test', 10:53:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:25 INFO - 'SHELL': '/bin/bash', 10:53:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:25 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:25 INFO - 'USER': 'cltbld', 10:53:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:25 INFO - 'XPC_FLAGS': '0x0', 10:53:25 INFO - 'XPC_SERVICE_NAME': '0', 10:53:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 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)) 10:53:26 INFO - Cleaning up... 10:53:26 INFO - Return code: 0 10:53:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:53:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe5c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec41c914d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, '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.3jv91dWzjz/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.SgzGZ1SOap/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:53:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:53:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:53:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:26 INFO - 'HOME': '/Users/cltbld', 10:53:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:26 INFO - 'LOGNAME': 'cltbld', 10:53:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:26 INFO - 'MOZ_NO_REMOTE': '1', 10:53:26 INFO - 'NO_EM_RESTART': '1', 10:53:26 INFO - 'PAGER': '/bin/cat', 10:53:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:26 INFO - 'PWD': '/builds/slave/test', 10:53:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:26 INFO - 'SHELL': '/bin/bash', 10:53:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:26 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:26 INFO - 'USER': 'cltbld', 10:53:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:26 INFO - 'XPC_FLAGS': '0x0', 10:53:26 INFO - 'XPC_SERVICE_NAME': '0', 10:53:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:53:26 INFO - Cleaning up... 10:53:26 INFO - Return code: 0 10:53:26 INFO - Running post-action listener: _resource_record_post_action 10:53:26 INFO - Running post-action listener: _start_resource_monitoring 10:53:26 INFO - Starting resource monitoring. 10:53:26 INFO - [mozharness: 2016-12-15 18:53:26.537301Z] Finished create-virtualenv step (success) 10:53:26 INFO - [mozharness: 2016-12-15 18:53:26.538318Z] Running install step. 10:53:26 INFO - Running pre-action listener: _resource_record_pre_action 10:53:26 INFO - Running main action method: install 10:53:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:26 INFO - Reading from file tmpfile_stdout 10:53:26 INFO - Detecting whether we're running mozinstall >=1.0... 10:53:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:53:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:53:26 INFO - Reading from file tmpfile_stdout 10:53:26 INFO - Output received: 10:53:26 INFO - Usage: mozinstall [options] installer 10:53:26 INFO - Options: 10:53:26 INFO - -h, --help show this help message and exit 10:53:26 INFO - -d DEST, --destination=DEST 10:53:26 INFO - Directory to install application into. [default: 10:53:26 INFO - "/builds/slave/test"] 10:53:26 INFO - --app=APP Application being installed. [default: firefox] 10:53:26 INFO - mkdir: /builds/slave/test/build/application 10:53:26 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'] 10:53:26 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 10:53:58 INFO - Reading from file tmpfile_stdout 10:53:58 INFO - Output received: 10:53:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:53:58 INFO - Running post-action listener: _resource_record_post_action 10:53:58 INFO - [mozharness: 2016-12-15 18:53:58.506442Z] Finished install step (success) 10:53:58 INFO - [mozharness: 2016-12-15 18:53:58.506612Z] Running run-tests step. 10:53:58 INFO - Running pre-action listener: _resource_record_pre_action 10:53:58 INFO - Running main action method: run_tests 10:53:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:53:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:53:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:53:58 INFO - 2016-12-15 10:53:58.521 screenresolution[2165:22909] starting screenresolution argv=screenresolution get 10:53:58 INFO - 2016-12-15 10:53:58.523 screenresolution[2165:22909] Display 0: 1600x1200x32@60 10:53:58 INFO - 2016-12-15 10:53:58.529 screenresolution[2166:22912] starting screenresolution argv=screenresolution list 10:53:58 INFO - Available Modes on Display 0 10:53:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:53:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:53:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:53:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:53:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:53:58 INFO - Intel Iris: 10:53:58 INFO - Chipset Model: Intel Iris 10:53:58 INFO - Type: GPU 10:53:58 INFO - Bus: Built-In 10:53:58 INFO - VRAM (Dynamic, Max): 1536 MB 10:53:58 INFO - Vendor: Intel (0x8086) 10:53:58 INFO - Device ID: 0x0a2e 10:53:58 INFO - Revision ID: 0x0009 10:53:58 INFO - Displays: 10:53:58 INFO - Display: 10:53:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:53:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:53:58 INFO - Display Serial Number: 1600x1200 60 10:53:58 INFO - Main Display: Yes 10:53:58 INFO - Mirror: Off 10:53:58 INFO - Online: Yes 10:53:58 INFO - Rotation: Supported 10:53:58 INFO - Return code: 0 10:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:53:58 INFO - Python 2.7.3 10:53:58 INFO - Return code: 0 10:53:58 INFO - grabbing minidump binary from tooltool 10:53:58 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')]} 10:53:58 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 0x10fc70d30>, '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 0x10fad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc68b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:53:58 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 10:53:58 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 10:53:58 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 10:53:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:53:58 INFO - Return code: 0 10:53:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:53:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:53:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:53:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:53:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:53:58 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:53:58 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0096 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:53:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3jv91dWzjz/Render', 10:53:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:58 INFO - 'HOME': '/Users/cltbld', 10:53:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:58 INFO - 'LOGNAME': 'cltbld', 10:53:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:53:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:58 INFO - 'MOZ_NO_REMOTE': '1', 10:53:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:53:58 INFO - 'NO_EM_RESTART': '1', 10:53:58 INFO - 'PAGER': '/bin/cat', 10:53:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:58 INFO - 'PWD': '/builds/slave/test', 10:53:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:53:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:58 INFO - 'SHELL': '/bin/bash', 10:53:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgzGZ1SOap/Listeners', 10:53:58 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:53:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:58 INFO - 'USER': 'cltbld', 10:53:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:58 INFO - 'XPC_FLAGS': '0x0', 10:53:58 INFO - 'XPC_SERVICE_NAME': '0', 10:53:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:53:59 INFO - mozversion application_buildid: 20161215080001 10:53:59 INFO - mozversion application_changeset: 1b23c1469bb9059b11e573a621844df55257396d 10:53:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:53:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:53:59 INFO - mozversion application_name: Firefox 10:53:59 INFO - mozversion application_remotingname: firefox-dev 10:53:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:53:59 INFO - mozversion application_vendor: Mozilla 10:53:59 INFO - mozversion application_version: 52.0a2 10:53:59 INFO - mozversion platform_buildid: 20161215080001 10:53:59 INFO - mozversion platform_changeset: 1b23c1469bb9059b11e573a621844df55257396d 10:53:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:53:59 INFO - mozversion platform_version: 52.0a2 10:53:59 INFO - using testdate: 1481828039 10:53:59 INFO - actual date: 1481828039 10:53:59 INFO - starting webserver on 'localhost:54286' 10:53:59 INFO - SUITE-START | Running 2 tests 10:53:59 INFO - TEST-START | dromaeo_css 10:53:59 INFO - operating with platform_type : mac_ 10:53:59 INFO - Initialising browser for dromaeo_css test... 10:53:59 INFO - TEST-INFO | started process 2176 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpSQYToL/profile http://localhost:54286/getInfo.html) 10:54:01 INFO - PROCESS | 2176 | __metrics Screen width/height:1600/1200 10:54:01 INFO - PROCESS | 2176 | colorDepth:24 10:54:01 INFO - PROCESS | 2176 | Browser inner width/height: 1024/689 10:54:01 INFO - PROCESS | 2176 | __metrics 10:54:01 INFO - PROCESS | 2176 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:54:01 INFO - PROCESS | 2176 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:54:02 INFO - TEST-INFO | 2176: exit 0 10:54:02 INFO - Browser initialized. 10:54:02 INFO - Running cycle 1/1 for dromaeo_css test... 10:54:02 INFO - TEST-INFO | started process 2178 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpSQYToL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:54:14 INFO - PROCESS | 2178 | RSS: Main: 222576640 10:54:14 INFO - PROCESS | 2178 | 10:55:06 INFO - PROCESS | 2178 | 1481828106580 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] 10:57:19 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:54286/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54286/tests/dromaeo/cssquery-ext.html) 10:57:21 INFO - PROCESS | 2178 | RSS: Main: 315850752 10:57:21 INFO - PROCESS | 2178 | 11:00:24 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:54286/tests/dromaeo/cssquery-ext.html (next: http://localhost:54286/tests/dromaeo/cssquery-jquery.html) 11:00:24 INFO - PROCESS | 2178 | RSS: Main: 348041216 11:00:24 INFO - PROCESS | 2178 | 11:03:27 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:54286/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54286/tests/dromaeo/cssquery-mootools.html) 11:03:27 INFO - PROCESS | 2178 | RSS: Main: 345264128 11:03:27 INFO - PROCESS | 2178 | 11:06:30 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:54286/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54286/tests/dromaeo/cssquery-prototype.html) 11:06:30 INFO - PROCESS | 2178 | RSS: Main: 321216512 11:06:30 INFO - PROCESS | 2178 | 11:09:34 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:54286/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54286/tests/dromaeo/cssquery-yui.html) 11:09:34 INFO - PROCESS | 2178 | RSS: Main: 311148544 11:09:34 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:54286/tests/dromaeo/cssquery-yui.html (next: http://localhost:54286/tests/dromaeo/cssquery-dojo.html) 11:12:38 INFO - PROCESS | 2178 | RSS: Main: 430579712 11:12:38 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | __start_tp_report 11:12:38 INFO - PROCESS | 2178 | _x_x_mozilla_page_load 11:12:38 INFO - PROCESS | 2178 | _x_x_mozilla_page_load_details 11:12:38 INFO - PROCESS | 2178 | |i|pagename|runs| 11:12:38 INFO - PROCESS | 2178 | |0;dojo.html;3268.297316077043;3283.637290524431;3284.9014529564115;3316.651751566084;3335.7664963452567;5635.66186028838;5698.857528561778;5719.885602287962;5745.569082318827;5758.049921762915;4741.241401375781;5643.661380317184;5715.257016587848;5730.939776141417;5733.971330143354;5115.769790359424;5144.56271216948;5181.844544663647;5184.559312458446;5229.450907654698;5131.255967884655;5142.537171654551;5147.38231412231;5262.92105618416;5280.445553216915;5109.9489005109845;5170.612204084689;5200.739963001834;5211.088059589576;5227.555657769094;5200.245964335188;5279.63042587019;5291.841244762663;5296.205569164617;5320.547753440962;5055.241713742968;5111.182210846247;5190.091733946544;5216.947830521684;5230.503102205284;5562.972185139049;5564.8330550083565;5578.30271216098;5609.5792815539;5609.831705048855;6081.635101893857;6149.631022138686;6163.599366041193;6192.442680158807;6199.380061993765;6182.412670796267;6205.875882482325;6214.968925155391;6232.25212974446;6232.283287421914;4792.808287668524;5653.321601407857;5713.143028545752;5721.799737009268;5726.541876649858;6180.412860778219;6214.564980451415;6217.875642487125;6219.564630475914;6223.688815559294;6082.239720034996;6082.878342433127;6087.1173679816175;6094.933386657406;6097.878042439126;6055.545834062463;6060.606060606046;6080.604760690541;6136.693165341715;6152.16945712332;6103.786367477117;6141.578063794062;6149.3235744068115;6152.230971128609;6155.815325540241;6139.109829074849;6150.7232174552255;6183.319834818167;6200.410960958701;6210.689465526705;4722.24444088951;4833.3958255218095;4870.975645121752;4875.341828853059;4905.969746353235;3664.6885014774043;4339.435873336509;4373.518912919576;4373.934390984138;4430.20256353853;5746.540276777848;5756.884862302731;5762.423757624288;5764.221830052973;5764.798232061941;6078.696065196723;6167.074938759222;6199.039148931833;6202.1937148171355;6204.410580994799;5603.467670571289;5653.208550802891;5710.8001219957105;5741.282339707536;5745.569082318842;5333.386660534088;5382.165764306539;5384.91922621161;5388.568914486832;5407.648502847303;6260.373962603885;6266.310705822447;6266.811995640047;6270.498360131087;6308.747650093945;6016.909746353721;6085.421884920926;6115.483736156459;6124.203853499017;6160.969195153995;6155.076738489262;6277.15258440123;6277.591956522811;6280.811575652738;6293.150424692608;6146.661933593517;6156.384361563808;6158.846028849135;6161.7843375483435;6171.691415429121;6087.208662873798;6140.201773769381;6168.691565421621;6170.3212646607935;6203.255609326729;5940.851478712894;5964.761409543569;6039;6090.299615544269;6124.14262003311;5019.7490125494305;6048.818535443944;6056.3035250946705;6058.091286307089;6075.149479072845;5654.915176272277;5880.500157486662;5897.734601942837;5898.675572843535;5898.94101058984;5883.529317654492;5982.670953097448;5998.400159983967;6039.909401358896;6129.141920131275;5911.793087241926;5914.408559144051;5925.525957923442;5927.911081333697;5955.851103722442 11:12:38 INFO - PROCESS | 2178 | |1;ext.html;461.0019308197752;503.77833753148616;508.4864287070058;508.83462874565777;542.3708498142158;26981;30615;30634.77460901564;30649.693503064962;30722.692773072264;33693;41747;41764.37353439701;43357.34963975535;43440.78279608605;9413.388129771543;9565.378250413702;9568;9578.664746733832;9589.856152157723;9437.386569872971;9531.523423828816;9538.90461095387;9561.665341713042;9574.569144388519;9526.095020972996;9578.760530986712;9589.568469418893;9604.903950960506;9606.039396060372;9421.905780942172;9498.620055197818;9576.712698618983;9577.138057574817;9580.185684216849;9445.527723613792;9456.8581471278;9505.144536991635;9508.239340852715;9525.28560357969;38943.8052809737;39229.41155882664;39286;39315.41026884599;39320.606793931984;3133.263683034485;3272.8527216275324;3323.6177839548277;3354.329134173175;3355.379254837863;26572;27133.050343237894;30355.544666830014;30371.544426833614;31230.687693123233;3170.9682903171133;3217.694319039711;3248.13924310058;3270.5584746059217;3316.535685004101;468245;477095;477186;477982;478572;63752.406189844696;64899.67550162313;64909;64956.75216239169;65015;35182.64817351845;35549.82225088858;36009.63990360115;37015.81492092522;68754.5622718862;15872.20638968047;16015.599610009844;16028.358865645478;16061.11663858499;16075.276612552463;11901.34542600148;12161.452734626962;12205.328706921204;12358.691032724253;12384;16815.495535133967;19999.800001999793;20354.49113772138;20404.79595204029;20425.182992680126;8164.306033987059;8178.672853085929;8224;8231.6417791065;8301.128381519853;817.3747083481142;934.7008957133788;973.8393165127816;982.4547376206132;987.6148302162162;29459.705402946125;29492.410151797274;29628.555571666442;30280.091597252118;30881.919132830244;870.4820631724114;881.167296904432;954.5084281594984;1000.614763316114;1080.897314755097;13362.857898070522;15276.312565934559;15285.465008724641;15552.455664051704;15796.684066318609;659930;665295;666376;667471;668238;45895.082098357845;55562.110652253956;55774;55805;55815.44184558102;1218.5247753376013;1268.543324403214;1339.5512503311304;1385.764420048574;1391.3669280477359;29801.70198298076;30062.549061763653;30504.84747576248;30518.694813052472;31091.689083109784;46186.538134618226;46740.831479213295;46779.53220467888;46792.7660361696;46803.53196467992;522464;524819;525245;527231;650173;34458.483122752674;39969.80015099906;40766;41903.95240119022;42233.36649950192;42863.571364285955;43025;43197.784011081;43568.34647480227;43608.78195609002;35097.64902351046;35595.46606800952;35743;36178;36180;35527.289454211284;35802.820985894905;36101.458478122324;36870.262594747415;37667.43498847574;34984.650153499155;35706.821465892506;35966.280674385845;36306.91079267626;38261.61738382692;23772.762272377055;29905.551416729202;33622.83188584139;34375.434899301814;34414.82792586021;23037.65443518315;25135.74864251334;25286.24141275765;25627.35931601725;25675.35811604725 11:12:38 INFO - PROCESS | 2178 | |2;jquery.html;336.5293666676623;341.3548393536216;349.12892333627605;366.5436531518258;400.3074680802212;14848.554543363689;15114.09315441073;15974.920125399356;19937.800621993774;20327.085281162344;18889;19159.808401915976;19431;19851.900740496276;19887.90056049721;5130.3074084998425;5397.028534863723;5404.027275090482;5412.323459567554;5415.593830462712;4714.363560919266;5002.4997500249865;5350.8127215547465;5871.178035075093;5944.346121926585;5898.7935422260225;5905.43898329658;5909.527237820964;5910.970445147768;5925.437083477084;5609.186667933147;5781.277340332479;5805.100209467497;5825.679587622722;5826.009578371687;4966.528179822911;5178.17149256121;5195.532402083831;5198.142306519419;5211.739413029333;23472.882635586884;23580.29259122229;24086.63870041951;24417.87791061051;24865.751342486525;2411.8552886826847;2586.2887705880844;2656.8973875841502;2660.8935642574274;2673.211402636227;9533.47565883876;9637.421754694671;10088.394696318172;10097.949510252474;10107.747306317327;1746.0920321432911;1834.6881030224895;1839.8804077734903;1909.799471055533;1909.837663798579;109357;115824.42087789507;120777.39611301976;121521;128930.06604900934;17499.800047988392;20618.07218675133;20690.13788759019;20932.686009709865;21259.89370053171;34977;35050.474242886376;35478.113047174025;35685.572577096624;35868.46197307042;10528.420936848393;10624.521896514676;10799.78400431987;10812;10996.890031099587;8697.21725044749;8725.258353040063;8744.213020828156;8885.333599979898;8894.777630559287;8001.919980800118;9552.188064014497;9605.951970240105;9738.805223895482;9760.267979901535;3098.7056229658147;3115.594972653586;3626.603757553361;3644.362236608583;3646.6717995380013;701.4809041309394;818.8403261363977;849.2441726863096;888.7911340834899;930.7812664023949;20120.39638810838;20878.269260575806;21041.36875893695;21062.052207650693;21168;848.1645579104577;853.117023880281;901.9188273055325;948.4546385828177;957.6791774755395;10397.376157430579;11021.83467247976;11083.72290692723;11097.500612472459;11148;604480;604682;613451;622079;622755;73247;73317.26682733212;77075.61462192654;129366.35316823356;135079;954.8567714842691;985.074030411411;1007.6876168387823;1014.6905193915926;1025.707673313102;17814.643707126044;17987.460376188734;18055.819441805415;18553.25786968506;19148.617027659646;48990;50503.24245136399;52515;52976.67558310919;53855.192172116665;63761;73876;77853;79999;81638;7159.749408770668;7265.346118849329;7276.96361518189;7415.369693575891;7699.230076992256;10718.89281107179;12368.319742414255;12424;12661.873381266438;12735.745285094432;10757.838632420677;10954.507047182737;11052.889471105185;11119.555217791198;11264.943675281898;9355.578998944928;10672.252942293964;10888.237823352289;11033.558657653604;11093.112550996062;11077.224594278323;11118;11336.829947550628;11457.312561246352;11619.36093514831;7121.14546254432;7340.486165968331;7359.816004599714;7399.963000184965;7576.166621671501;10060.798784024424;10625;10897.945510272399;11209.887901120885;11234.438278086227 11:12:38 INFO - PROCESS | 2178 | |3;mootools.html;1286.28611120828;1442.6177063078283;1480.0231846980992;1630.2093484659938;1821.772278465192;6715.462762978962;7887.290143887049;8127.4717143385715;8457.02744184419;8469.44948578342;8301.66793328266;8373.539455329954;8395.706150284741;8405.369597280212;8406.621702023409;2293.736220334662;2315.235972129202;2324.384038229862;2325.093213646671;2325.651152327156;2303.6544518322303;2343.496148328109;2355.1521452277166;2355.49356888269;2358.8348815582917;2280.2019293247395;2291.472961218921;2321.361625552978;2325.3024092772184;2328.662343960125;2274.40865375002;2289.2331069091792;2309.1802410144323;2309.6766452696634;2311.6185829338137;2117.9258725944674;2176.749673787519;2284.223364056229;2309.203324852923;2313.34069790109;8098.716544920959;8321.83356332876;8403.957980210122;8407.285380742584;8424.115467875847;4445.333200019991;4703.6942598730975;5185.377754669464;5210.036143313499;5221.503957124067;3343.1307859956587;3406.812625305607;3462.3248466549035;3480.0255928339852;3508.859645614172;1076.4402510694395;1104.089126470665;1714.579927917668;1732.6534693061437;1819.8635102367243;10486.632967846088;10903.672889813319;10972.23194376394;10975.957284058002;10981.231313808037;4338.848140315137;4456.487503937089;4492.663050271242;4499.820007199675;4504.369388285642;8637.784055398666;8638.09300023501;8639.136086391436;8639.352048596254;8648.351373647001;5075.086484432763;5088.796448142033;5094.6688465249645;5101.948980510221;5101.948980510221;3705.9592769366222;4629.189891768927;4778.474367819537;4796.352492413549;4807.399075115611;3599.136207310279;3633.8728144514816;3684.428913518409;3723.1809002019513;3725.70194384454;962.3167551038841;1124.58952482345;1128.966131016071;1133.9659810205708;1138.2544433396138;816.6692489541745;864.8875646165958;898.9640414383482;906.777839429344;910.8432290990537;5483.917741233885;5539.169124631338;5550.86122846924;5570.248016517799;5807.854803629859;881.167296904432;899.739075668062;912.2929729459722;918.3250311021394;931.4969916245287;3478.0783092480515;3670.7430479866152;3698.4452332149854;3786.8674596388996;3793.260314238697;9804;10189.796204075878;10305.793884122275;10311.07200351972;10319.277650564389;7712.421568382281;9667.226621870233;9670.323077384515;10273.229507786797;10281.794364112677;7152.892706609405;7179.461540384492;7417.10994680631;7772.689092436352;7823.726169584037;5239.266502689703;5609.4390560943575;5666.376698563237;5669.574781891292;5961.821145365646;9113.453192808453;9285.396449230779;9289.978102408595;9295.30285228597;9351.906480935375;9505.857412138685;9529;9568.425894446356;9573.521323933915;9598.808023839623;6741.898871516833;7022.964885175541;7100.609466479186;7232.4575656827;7239.819004524929;7748.612569371396;7758.418118641237;7834.451588388986;7843.215678432111;7849.764507064797;7102.502824802421;7132.536385134949;7157.391621712213;7207.810711232798;7264.23725508814;6956.373926346653;7025.367716905503;7036.788896333118;7066.222715501188;7080.008798768074;6543.116679248241;6682.231543372574;6683.331666833278;6689.49828762835;6699.732010719712;4679.508651591534;4868.294097355934;4877.731724755173;4917.729524876023;4933.876653083702;4711.969234428834;4730.905381892274;4739.099571081483;4936.9259461107395;4965.82619608312 11:12:38 INFO - PROCESS | 2178 | |4;prototype.html;297.3369386268621;305.4257994969458;305.6179775280899;310.82283098633775;313.8430784607696;4345.978695006671;4902.950970490294;5068.999999999995;5075.670081444703;5135.383753949522;4964.851054468371;5015.473375295596;5083.44082150964;5113.897722045557;5133.717645529495;3148.740503798476;3771.6039815819354;3840.599625234229;3842.4236364545263;3871.36122539782;3269.8365081745937;3812.4471951567007;3843.2121415109973;3918.7060970427333;3935.4096885467266;3357.446021406465;3807.8286477108595;3818.1863614782897;3846.0769615192403;3863.9227215455676;3224.452262913791;3583.7849729016343;3639.5796795131205;3833.118382771964;3876.263509933118;2662.427578070724;3322.9833850830832;3350.2629421527226;3351.413502637053;3375.4599264117624;4047.514298284225;4346.913061738779;4694.624430045588;4933.432655244621;4949.257611358289;4241;4376.934345984812;4379.912401751979;4408.963893485028;4428.446444194476;5050.646454748169;5227.189785583203;5229.424763276074;5259.447757985395;5309.867253318659;3374.6377535724846;3387.8650652031597;3663.8534458621625;3755.286495565834;3756.3614185588517;5136.717480538568;5200.8959820803375;5609.831705048855;5714.45709658394;5722.2561067061015;3182.570353002315;3482.651734826548;3716.3310604090984;3726.347889119393;3787.753796003251;3767.9434808477895;3791.374423220138;3796.6142358039115;3798.620137986179;3801.752886062397;4356.390105385249;4360.383769738195;4378.10248898975;4411.602955733999;4434.933475997863;4994.981047837347;5222.451642577475;5345.144776835766;5446.91829622556;5453.23654688344;1123.5618108937522;1211.7213041000443;1215.3376409856648;1215.4624400133837;1216.4343580235234;764.747633281016;785.1834092543804;829.8962629671291;837.3845223760532;849.3757088539918;4457.9493433020525;4726.031163611451;4883.804647814048;4885.780139893643;4926.531979461945;4908.877278068006;5249.42256351801;5472.753726082336;5513.283273174542;5519.267962358578;4674.69614475058;4965.627577931669;5008.424031236382;5045.217991211368;5069.340985671913;2678;2682.5841994490884;2691.6003678087286;2709.8509581972785;2720.9047683330987;5324.53575266797;5329.7068661224;5353.544948679328;5361.51746342831;5366.704831234241;4633.142868569325;4644.654449502979;4998.000399920031;5267.499587539178;5329.440408757025;4823.638227132979;5234.371875374979;5340.198970154508;5348.251244825728;5353.625246232727;4244.702870799015;4921.680090794087;5152.201408781645;5169;5204.531592156724;4474.664400170065;4501.819927202875;4702.285194696152;5014.674046186986;5454.15482085137;5039.370078740158;5050.292958985671;5112.156494178419;5210;5730.681943153015;3431.5244444888936;3714.0714821294678;3769.7926614035396;3782.867599634;3788.412796016622;4285.957140428556;5474.370447398441;5526.41972592888;5564.860878478071;5572.387037425799;4541;5368.463153684756;5383.757730902197;5392.05639013179;5401.648892821954;4568.70303430276;4922.310876477224;5387.568994480511;5388.649737767033;5445.891082178414;4642.512536183652;4955.801767929387;5194.999925028967;5247.653285400317;5261.394939581844;5089.262057001788;5262.263283140287;5300.787968481218;5331.573474122139;5408.593765620888;5132.204508301219;5214.8957020860125;5245.737713114406;5246.763895624782;5275.446078161738 11:12:38 INFO - PROCESS | 2178 | |5;yui.html;701.8140192848896;732.2055569706868;734.159387501311;756.5120497279252;762.6072572625103;14231.786523202147;14537.709245815104;14575.198364089983;14580.927095364535;14584.854151458483;12918.870811291885;12943.676408089803;12962.416691248893;12963.48146074156;13029.869701302983;646.85122421843;666.9032990216416;666.9699863506154;673.0644404278033;804.5373910001758;668.6322522612568;669.1568623534333;788.6490511722286;808.7614153824636;809.9838003239931;843.0768308701962;847.6270441005938;853.3983541603186;855.3755758296447;856.9057403685621;848.2662496940164;850.2177996243471;850.4429598612887;851.7742798158492;852.4033176776251;509.08619028843316;525.0155957579539;632.1907957814005;632.5445679111033;634.4004915354991;13105.606831795061;13617.319134043259;13654;13965.511207107702;13967.231802250872;13374.933125334408;13622.863771362358;13834.30828458573;13839;13858.514951976633;10736.516856741466;11165.72085697856;11286.322820630707;11459.713507162303;11502.41422872013;3633.9273214535824;3694.131879008431;3709.962900370989;3830.5020347354944;3831.501904752364;84721.15278847257;87414.56292718559;88238.5588072062;88751;89099;10459.996136567426;10547.84178237327;10613.628523001658;10629.722921914297;10692.83960740574;20296.391108266776;20379.898100509403;20385.898070509553;20468.795312046987;20553.58892822135;7096.964515177391;7209.387202087776;7267.927320726831;7432.925670743223;7496.550206987489;4064.552899181088;4169.812358443877;4193.622573968358;4226.302660061056;4326.935095973563;3356.5468661730843;3405.625381208065;3486.651334866544;3491.94762078569;3495.8601655933476;667.786308381323;686.1663079358603;697.4141720954423;707.0737334092355;708.9361957423856;552.7101583974346;559.194759546252;649.7595889520813;677.1197443323659;679.429279405292;8608.48349099056;8725.912740872636;9133.862992055125;9134.862977055349;9209.539523023823;601.2004034633926;617.3857012272817;619.123939625434;665.4343807763363;671.7447369999369;3618.222082252328;3708.387687834248;3918.3534716771983;4210.148968374133;4550.590446859798;99635.50546741804;104635;106603.3349166277;115991.42004289925;116524;91394;92425;92898.53550732203;95106;95151.52424237835;63708.681456593346;65551;77645;78829;87422;8859.911400885909;9119.544022798966;9444.433333999981;9627.422354658744;9683.903160968583;50865.49134508608;55334.72332638311;55372.44627553834;55709.442905570424;56528;54033.459665404414;54886.72556637191;68990.65504672444;72438.27561724526;74246;13120.409581568661;13161.210327380388;13172.275524846225;13604.04771665974;13682.45270189221;12182.695432614255;12275.508979640714;13297.26865022433;13528.59414217575;14280.928595357373;12307.507699691912;12529.812052819398;12645;13172;13299.401526931504;10654;12504;13047.28239946812;13116.344182791014;13345.599632010666;10983.725406864893;11137.55449782032;11375.715607109889;12176.817347739614;12291.385430728607;9217.907820921973;10279.588816447558;10570.947145264225;10747.516361763895;10981;8912.55437228149;11286.266392684778;11347.943260283646;11615;11713.941430292794 11:12:38 INFO - PROCESS | 2178 | __end_tp_report 11:12:38 INFO - PROCESS | 2178 | __start_cc_report 11:12:38 INFO - PROCESS | 2178 | _x_x_mozilla_cycle_collect,1798 11:12:38 INFO - PROCESS | 2178 | __end_cc_report 11:12:38 INFO - PROCESS | 2178 | __startTimestamp1481829158417__endTimestamp 11:12:38 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | ------- Summary: start ------- 11:12:38 INFO - PROCESS | 2178 | Number of tests: 6 11:12:38 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | [#0] dojo.html Cycles:165 Average:5678.40 Median:5911.79 stddev:650.85 (11.0%) stddev-sans-first:624.78 11:12:38 INFO - PROCESS | 2178 | Values: 3268.3 3283.6 3284.9 3316.7 3335.8 5635.7 5698.9 5719.9 5745.6 5758.0 4741.2 5643.7 5715.3 5730.9 5734.0 5115.8 5144.6 5181.8 5184.6 5229.5 5131.3 5142.5 5147.4 5262.9 5280.4 5109.9 5170.6 5200.7 5211.1 5227.6 5200.2 5279.6 5291.8 5296.2 5320.5 5055.2 5111.2 5190.1 5216.9 5230.5 5563.0 5564.8 5578.3 5609.6 5609.8 6081.6 6149.6 6163.6 6192.4 6199.4 6182.4 6205.9 6215.0 6232.3 6232.3 4792.8 5653.3 5713.1 5721.8 5726.5 6180.4 6214.6 6217.9 6219.6 6223.7 6082.2 6082.9 6087.1 6094.9 6097.9 6055.5 6060.6 6080.6 6136.7 6152.2 6103.8 6141.6 6149.3 6152.2 6155.8 6139.1 6150.7 6183.3 6200.4 6210.7 4722.2 4833.4 4871.0 4875.3 4906.0 3664.7 4339.4 4373.5 4373.9 4430.2 5746.5 5756.9 5762.4 5764.2 5764.8 6078.7 6167.1 6199.0 6202.2 6204.4 5603.5 5653.2 5710.8 5741.3 5745.6 5333.4 5382.2 5384.9 5388.6 5407.6 6260.4 6266.3 6266.8 6270.5 6308.7 6016.9 6085.4 6115.5 6124.2 6161.0 6155.1 6277.2 6277.6 6280.8 6293.2 6146.7 6156.4 6158.8 6161.8 6171.7 6087.2 6140.2 6168.7 6170.3 6203.3 5940.9 5964.8 6039.0 6090.3 6124.1 5019.7 6048.8 6056.3 6058.1 6075.1 5654.9 5880.5 5897.7 5898.7 5898.9 5883.5 5982.7 5998.4 6039.9 6129.1 5911.8 5914.4 5925.5 5927.9 5955.9 11:12:38 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | [#1] ext.html Cycles:180 Average:68750.66 Median:39300.71 stddev:152512.14 (388.1%) stddev-sans-first:152853.78 11:12:38 INFO - PROCESS | 2178 | Values: 461.0 503.8 508.5 508.8 542.4 26981.0 30615.0 30634.8 30649.7 30722.7 33693.0 41747.0 41764.4 43357.3 43440.8 9413.4 9565.4 9568.0 9578.7 9589.9 9437.4 9531.5 9538.9 9561.7 9574.6 9526.1 9578.8 9589.6 9604.9 9606.0 9421.9 9498.6 9576.7 9577.1 9580.2 9445.5 9456.9 9505.1 9508.2 9525.3 38943.8 39229.4 39286.0 39315.4 39320.6 3133.3 3272.9 3323.6 3354.3 3355.4 26572.0 27133.1 30355.5 30371.5 31230.7 3171.0 3217.7 3248.1 3270.6 3316.5 468245.0 477095.0 477186.0 477982.0 478572.0 63752.4 64899.7 64909.0 64956.8 65015.0 35182.6 35549.8 36009.6 37015.8 68754.6 15872.2 16015.6 16028.4 16061.1 16075.3 11901.3 12161.5 12205.3 12358.7 12384.0 16815.5 19999.8 20354.5 20404.8 20425.2 8164.3 8178.7 8224.0 8231.6 8301.1 817.4 934.7 973.8 982.5 987.6 29459.7 29492.4 29628.6 30280.1 30881.9 870.5 881.2 954.5 1000.6 1080.9 13362.9 15276.3 15285.5 15552.5 15796.7 659930.0 665295.0 666376.0 667471.0 668238.0 45895.1 55562.1 55774.0 55805.0 55815.4 1218.5 1268.5 1339.6 1385.8 1391.4 29801.7 30062.5 30504.8 30518.7 31091.7 46186.5 46740.8 46779.5 46792.8 46803.5 522464.0 524819.0 525245.0 527231.0 650173.0 34458.5 39969.8 40766.0 41904.0 42233.4 42863.6 43025.0 43197.8 43568.3 43608.8 35097.6 35595.5 35743.0 36178.0 36180.0 35527.3 35802.8 36101.5 36870.3 37667.4 34984.7 35706.8 35966.3 36306.9 38261.6 23772.8 29905.6 33622.8 34375.4 34414.8 23037.7 25135.7 25286.2 25627.4 25675.4 11:12:38 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | [#2] jquery.html Cycles:180 Average:35334.50 Median:345.24 stddev:101608.66 (29431.2%) stddev-sans-first:101859.72 11:12:38 INFO - PROCESS | 2178 | Values: 336.5 341.4 349.1 366.5 400.3 14848.6 15114.1 15974.9 19937.8 20327.1 18889.0 19159.8 19431.0 19851.9 19887.9 5130.3 5397.0 5404.0 5412.3 5415.6 4714.4 5002.5 5350.8 5871.2 5944.3 5898.8 5905.4 5909.5 5911.0 5925.4 5609.2 5781.3 5805.1 5825.7 5826.0 4966.5 5178.2 5195.5 5198.1 5211.7 23472.9 23580.3 24086.6 24417.9 24865.8 2411.9 2586.3 2656.9 2660.9 2673.2 9533.5 9637.4 10088.4 10097.9 10107.7 1746.1 1834.7 1839.9 1909.8 1909.8 109357.0 115824.4 120777.4 121521.0 128930.1 17499.8 20618.1 20690.1 20932.7 21259.9 34977.0 35050.5 35478.1 35685.6 35868.5 10528.4 10624.5 10799.8 10812.0 10996.9 8697.2 8725.3 8744.2 8885.3 8894.8 8001.9 9552.2 9606.0 9738.8 9760.3 3098.7 3115.6 3626.6 3644.4 3646.7 701.5 818.8 849.2 888.8 930.8 20120.4 20878.3 21041.4 21062.1 21168.0 848.2 853.1 901.9 948.5 957.7 10397.4 11021.8 11083.7 11097.5 11148.0 604480.0 604682.0 613451.0 622079.0 622755.0 73247.0 73317.3 77075.6 129366.4 135079.0 954.9 985.1 1007.7 1014.7 1025.7 17814.6 17987.5 18055.8 18553.3 19148.6 48990.0 50503.2 52515.0 52976.7 53855.2 63761.0 73876.0 77853.0 79999.0 81638.0 7159.7 7265.3 7277.0 7415.4 7699.2 10718.9 12368.3 12424.0 12661.9 12735.7 10757.8 10954.5 11052.9 11119.6 11264.9 9355.6 10672.3 10888.2 11033.6 11093.1 11077.2 11118.0 11336.8 11457.3 11619.4 7121.1 7340.5 7359.8 7400.0 7576.2 10060.8 10625.0 10897.9 11209.9 11234.4 11:12:38 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | [#3] mootools.html Cycles:180 Average:5389.91 Median:5101.95 stddev:2935.16 (57.5%) stddev-sans-first:2927.18 11:12:38 INFO - PROCESS | 2178 | Values: 1286.3 1442.6 1480.0 1630.2 1821.8 6715.5 7887.3 8127.5 8457.0 8469.4 8301.7 8373.5 8395.7 8405.4 8406.6 2293.7 2315.2 2324.4 2325.1 2325.7 2303.7 2343.5 2355.2 2355.5 2358.8 2280.2 2291.5 2321.4 2325.3 2328.7 2274.4 2289.2 2309.2 2309.7 2311.6 2117.9 2176.7 2284.2 2309.2 2313.3 8098.7 8321.8 8404.0 8407.3 8424.1 4445.3 4703.7 5185.4 5210.0 5221.5 3343.1 3406.8 3462.3 3480.0 3508.9 1076.4 1104.1 1714.6 1732.7 1819.9 10486.6 10903.7 10972.2 10976.0 10981.2 4338.8 4456.5 4492.7 4499.8 4504.4 8637.8 8638.1 8639.1 8639.4 8648.4 5075.1 5088.8 5094.7 5101.9 5101.9 3706.0 4629.2 4778.5 4796.4 4807.4 3599.1 3633.9 3684.4 3723.2 3725.7 962.3 1124.6 1129.0 1134.0 1138.3 816.7 864.9 899.0 906.8 910.8 5483.9 5539.2 5550.9 5570.2 5807.9 881.2 899.7 912.3 918.3 931.5 3478.1 3670.7 3698.4 3786.9 3793.3 9804.0 10189.8 10305.8 10311.1 10319.3 7712.4 9667.2 9670.3 10273.2 10281.8 7152.9 7179.5 7417.1 7772.7 7823.7 5239.3 5609.4 5666.4 5669.6 5961.8 9113.5 9285.4 9290.0 9295.3 9351.9 9505.9 9529.0 9568.4 9573.5 9598.8 6741.9 7023.0 7100.6 7232.5 7239.8 7748.6 7758.4 7834.5 7843.2 7849.8 7102.5 7132.5 7157.4 7207.8 7264.2 6956.4 7025.4 7036.8 7066.2 7080.0 6543.1 6682.2 6683.3 6689.5 6699.7 4679.5 4868.3 4877.7 4917.7 4933.9 4712.0 4730.9 4739.1 4936.9 4965.8 11:12:38 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | [#4] prototype.html Cycles:180 Average:4272.52 Median:4905.91 stddev:1307.56 (26.7%) stddev-sans-first:1276.73 11:12:38 INFO - PROCESS | 2178 | Values: 297.3 305.4 305.6 310.8 313.8 4346.0 4903.0 5069.0 5075.7 5135.4 4964.9 5015.5 5083.4 5113.9 5133.7 3148.7 3771.6 3840.6 3842.4 3871.4 3269.8 3812.4 3843.2 3918.7 3935.4 3357.4 3807.8 3818.2 3846.1 3863.9 3224.5 3583.8 3639.6 3833.1 3876.3 2662.4 3323.0 3350.3 3351.4 3375.5 4047.5 4346.9 4694.6 4933.4 4949.3 4241.0 4376.9 4379.9 4409.0 4428.4 5050.6 5227.2 5229.4 5259.4 5309.9 3374.6 3387.9 3663.9 3755.3 3756.4 5136.7 5200.9 5609.8 5714.5 5722.3 3182.6 3482.7 3716.3 3726.3 3787.8 3767.9 3791.4 3796.6 3798.6 3801.8 4356.4 4360.4 4378.1 4411.6 4434.9 4995.0 5222.5 5345.1 5446.9 5453.2 1123.6 1211.7 1215.3 1215.5 1216.4 764.7 785.2 829.9 837.4 849.4 4457.9 4726.0 4883.8 4885.8 4926.5 4908.9 5249.4 5472.8 5513.3 5519.3 4674.7 4965.6 5008.4 5045.2 5069.3 2678.0 2682.6 2691.6 2709.9 2720.9 5324.5 5329.7 5353.5 5361.5 5366.7 4633.1 4644.7 4998.0 5267.5 5329.4 4823.6 5234.4 5340.2 5348.3 5353.6 4244.7 4921.7 5152.2 5169.0 5204.5 4474.7 4501.8 4702.3 5014.7 5454.2 5039.4 5050.3 5112.2 5210.0 5730.7 3431.5 3714.1 3769.8 3782.9 3788.4 4286.0 5474.4 5526.4 5564.9 5572.4 4541.0 5368.5 5383.8 5392.1 5401.6 4568.7 4922.3 5387.6 5388.6 5445.9 4642.5 4955.8 5195.0 5247.7 5261.4 5089.3 5262.3 5300.8 5331.6 5408.6 5132.2 5214.9 5245.7 5246.8 5275.4 11:12:38 INFO - PROCESS | 2178 | 11:12:38 INFO - PROCESS | 2178 | [#5] yui.html Cycles:180 Average:19811.83 Median:4438.76 stddev:28853.61 (650.0%) stddev-sans-first:28898.87 11:12:38 INFO - PROCESS | 2178 | Values: 701.8 732.2 734.2 756.5 762.6 14231.8 14537.7 14575.2 14580.9 14584.9 12918.9 12943.7 12962.4 12963.5 13029.9 646.9 666.9 667.0 673.1 804.5 668.6 669.2 788.6 808.8 810.0 843.1 847.6 853.4 855.4 856.9 848.3 850.2 850.4 851.8 852.4 509.1 525.0 632.2 632.5 634.4 13105.6 13617.3 13654.0 13965.5 13967.2 13374.9 13622.9 13834.3 13839.0 13858.5 10736.5 11165.7 11286.3 11459.7 11502.4 3633.9 3694.1 3710.0 3830.5 3831.5 84721.2 87414.6 88238.6 88751.0 89099.0 10460.0 10547.8 10613.6 10629.7 10692.8 20296.4 20379.9 20385.9 20468.8 20553.6 7097.0 7209.4 7267.9 7432.9 7496.6 4064.6 4169.8 4193.6 4226.3 4326.9 3356.5 3405.6 3486.7 3491.9 3495.9 667.8 686.2 697.4 707.1 708.9 552.7 559.2 649.8 677.1 679.4 8608.5 8725.9 9133.9 9134.9 9209.5 601.2 617.4 619.1 665.4 671.7 3618.2 3708.4 3918.4 4210.1 4550.6 99635.5 104635.0 106603.3 115991.4 116524.0 91394.0 92425.0 92898.5 95106.0 95151.5 63708.7 65551.0 77645.0 78829.0 87422.0 8859.9 9119.5 9444.4 9627.4 9683.9 50865.5 55334.7 55372.4 55709.4 56528.0 54033.5 54886.7 68990.7 72438.3 74246.0 13120.4 13161.2 13172.3 13604.0 13682.5 12182.7 12275.5 13297.3 13528.6 14280.9 12307.5 12529.8 12645.0 13172.0 13299.4 10654.0 12504.0 13047.3 13116.3 13345.6 10983.7 11137.6 11375.7 12176.8 12291.4 9217.9 10279.6 10570.9 10747.5 10981.0 8912.6 11286.3 11347.9 11615.0 11713.9 11:12:38 INFO - PROCESS | 2178 | -------- Summary: end -------- 11:12:38 INFO - PROCESS | 2178 | 11:12:39 INFO - TEST-INFO | 2178: exit 0 11:12:39 INFO - TEST-OK | dromaeo_css | took 1119975ms 11:12:39 INFO - TEST-START | kraken 11:12:39 INFO - operating with platform_type : mac_ 11:12:39 INFO - Initialising browser for kraken test... 11:12:39 INFO - TEST-INFO | started process 2182 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp9N_9wk/profile http://localhost:54286/getInfo.html) 11:12:41 INFO - PROCESS | 2182 | __metrics Screen width/height:1600/1200 11:12:41 INFO - PROCESS | 2182 | colorDepth:24 11:12:41 INFO - PROCESS | 2182 | Browser inner width/height: 1024/689 11:12:41 INFO - PROCESS | 2182 | __metrics 11:12:41 INFO - PROCESS | 2182 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:12:41 INFO - PROCESS | 2182 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:12:42 INFO - TEST-INFO | 2182: exit 0 11:12:42 INFO - Browser initialized. 11:12:42 INFO - Running cycle 1/1 for kraken test... 11:12:42 INFO - TEST-INFO | started process 2183 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp9N_9wk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:54 INFO - PROCESS | 2183 | RSS: Main: 222658560 11:12:54 INFO - PROCESS | 2183 | 11:12:57 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54286/tests/kraken/driver.html?testName=audio-beat-detection) 11:12:58 INFO - PROCESS | 2183 | RSS: Main: 272429056 11:12:58 INFO - PROCESS | 2183 | 11:13:01 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54286/tests/kraken/driver.html?testName=audio-dft) 11:13:01 INFO - PROCESS | 2183 | RSS: Main: 283090944 11:13:01 INFO - PROCESS | 2183 | 11:13:05 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54286/tests/kraken/driver.html?testName=audio-fft) 11:13:05 INFO - PROCESS | 2183 | RSS: Main: 381997056 11:13:05 INFO - PROCESS | 2183 | 11:13:07 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54286/tests/kraken/driver.html?testName=audio-oscillator) 11:13:08 INFO - PROCESS | 2183 | RSS: Main: 283418624 11:13:08 INFO - PROCESS | 2183 | 11:13:10 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54286/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:13:11 INFO - PROCESS | 2183 | RSS: Main: 284405760 11:13:11 INFO - PROCESS | 2183 | 11:13:13 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54286/tests/kraken/driver.html?testName=imaging-darkroom) 11:13:14 INFO - PROCESS | 2183 | RSS: Main: 325402624 11:13:14 INFO - PROCESS | 2183 | 11:13:17 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54286/tests/kraken/driver.html?testName=imaging-desaturate) 11:13:18 INFO - PROCESS | 2183 | RSS: Main: 326344704 11:13:18 INFO - PROCESS | 2183 | 11:13:20 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54286/tests/kraken/driver.html?testName=json-parse-financial) 11:13:21 INFO - PROCESS | 2183 | RSS: Main: 325042176 11:13:21 INFO - PROCESS | 2183 | 11:13:23 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54286/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:13:23 INFO - PROCESS | 2183 | RSS: Main: 300195840 11:13:23 INFO - PROCESS | 2183 | 11:13:25 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54286/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:13:26 INFO - PROCESS | 2183 | RSS: Main: 301359104 11:13:26 INFO - PROCESS | 2183 | 11:13:28 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54286/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:13:29 INFO - PROCESS | 2183 | RSS: Main: 303386624 11:13:29 INFO - PROCESS | 2183 | 11:13:32 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:13:33 INFO - PROCESS | 2183 | RSS: Main: 316686336 11:13:33 INFO - PROCESS | 2183 | 11:13:35 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:13:36 INFO - PROCESS | 2183 | RSS: Main: 316657664 11:13:36 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | Cycle 1(1): loaded http://localhost:54286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54286/tests/kraken/driver.html?testName=ai-astar) 11:13:38 INFO - PROCESS | 2183 | RSS: Main: 360382464 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | __start_tp_report 11:13:38 INFO - PROCESS | 2183 | _x_x_mozilla_page_load 11:13:38 INFO - PROCESS | 2183 | _x_x_mozilla_page_load_details 11:13:38 INFO - PROCESS | 2183 | |i|pagename|runs| 11:13:38 INFO - PROCESS | 2183 | |0;ai-astar;117;122;166;115;115;116;115;116;114;114 11:13:38 INFO - PROCESS | 2183 | |1;audio-beat-detection;147;112;110;144;139;139;109;109;137;134 11:13:38 INFO - PROCESS | 2183 | |2;audio-dft;186;185;186;186;183;183;184;184;185;186 11:13:38 INFO - PROCESS | 2183 | |3;audio-fft;83;61;49;91;79;85;84;82;85;59 11:13:38 INFO - PROCESS | 2183 | |4;audio-oscillator;84;72;81;71;83;99;126;98;67;88 11:13:38 INFO - PROCESS | 2183 | |5;imaging-gaussian-blur;89;85;88;86;87;84;88;84;88;84 11:13:38 INFO - PROCESS | 2183 | |6;imaging-darkroom;137;150;94;94;94;94;94;94;94;95 11:13:38 INFO - PROCESS | 2183 | |7;imaging-desaturate;81;84;95;132;83;83;83;85;84;83 11:13:38 INFO - PROCESS | 2183 | |8;json-parse-financial;55;52;52;80;84;81;82;58;56;57 11:13:38 INFO - PROCESS | 2183 | |9;json-stringify-tinderbox;65;74;73;76;72;71;71;71;71;71 11:13:38 INFO - PROCESS | 2183 | |10;stanford-crypto-aes;85;83;82;82;84;83;82;84;82;82 11:13:38 INFO - PROCESS | 2183 | |11;stanford-crypto-ccm;139;139;137;137;143;143;152;156;259;161 11:13:38 INFO - PROCESS | 2183 | |12;stanford-crypto-pbkdf2;159;160;166;166;165;165;163;165;166;172 11:13:38 INFO - PROCESS | 2183 | |13;stanford-crypto-sha256-iterative;67;68;67;68;64;65;62;67;67;67 11:13:38 INFO - PROCESS | 2183 | __end_tp_report 11:13:38 INFO - PROCESS | 2183 | __start_cc_report 11:13:38 INFO - PROCESS | 2183 | _x_x_mozilla_cycle_collect,2202 11:13:38 INFO - PROCESS | 2183 | __end_cc_report 11:13:38 INFO - PROCESS | 2183 | __startTimestamp1481829218453__endTimestamp 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | ------- Summary: start ------- 11:13:38 INFO - PROCESS | 2183 | Number of tests: 14 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#0] ai-astar Cycles:10 Average:121.00 Median:116.00 stddev:15.98 (13.8%) stddev-sans-first:16.88 11:13:38 INFO - PROCESS | 2183 | Values: 117.0 122.0 166.0 115.0 115.0 116.0 115.0 116.0 114.0 114.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:138.00 stddev:15.91 (11.5%) stddev-sans-first:15.32 11:13:38 INFO - PROCESS | 2183 | Values: 147.0 112.0 110.0 144.0 139.0 139.0 109.0 109.0 137.0 134.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.50 stddev:1.23 (0.7%) stddev-sans-first:1.22 11:13:38 INFO - PROCESS | 2183 | Values: 186.0 185.0 186.0 186.0 183.0 183.0 184.0 184.0 185.0 186.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#3] audio-fft Cycles:10 Average:75.80 Median:83.50 stddev:14.09 (16.9%) stddev-sans-first:14.71 11:13:38 INFO - PROCESS | 2183 | Values: 83.0 61.0 49.0 91.0 79.0 85.0 84.0 82.0 85.0 59.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:83.50 stddev:17.41 (20.9%) stddev-sans-first:18.44 11:13:38 INFO - PROCESS | 2183 | Values: 84.0 72.0 81.0 71.0 83.0 99.0 126.0 98.0 67.0 88.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.50 stddev:1.95 (2.2%) stddev-sans-first:1.80 11:13:38 INFO - PROCESS | 2183 | Values: 89.0 85.0 88.0 86.0 87.0 84.0 88.0 84.0 88.0 84.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:94.00 stddev:21.04 (22.4%) stddev-sans-first:18.63 11:13:38 INFO - PROCESS | 2183 | Values: 137.0 150.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#7] imaging-desaturate Cycles:10 Average:89.30 Median:83.50 stddev:15.49 (18.5%) stddev-sans-first:16.13 11:13:38 INFO - PROCESS | 2183 | Values: 81.0 84.0 95.0 132.0 83.0 83.0 83.0 85.0 84.0 83.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:69.00 stddev:13.98 (20.3%) stddev-sans-first:14.28 11:13:38 INFO - PROCESS | 2183 | Values: 55.0 52.0 52.0 80.0 84.0 81.0 82.0 58.0 56.0 57.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:2.84 (4.0%) stddev-sans-first:1.79 11:13:38 INFO - PROCESS | 2183 | Values: 65.0 74.0 73.0 76.0 72.0 71.0 71.0 71.0 71.0 71.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.00 stddev:1.10 (1.3%) stddev-sans-first:0.87 11:13:38 INFO - PROCESS | 2183 | Values: 85.0 83.0 82.0 82.0 84.0 83.0 82.0 84.0 82.0 82.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#11] stanford-crypto-ccm Cycles:10 Average:156.60 Median:147.50 stddev:36.95 (25.0%) stddev-sans-first:38.63 11:13:38 INFO - PROCESS | 2183 | Values: 139.0 139.0 137.0 137.0 143.0 143.0 152.0 156.0 259.0 161.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:165.50 stddev:3.59 (2.2%) stddev-sans-first:3.16 11:13:38 INFO - PROCESS | 2183 | Values: 159.0 160.0 166.0 166.0 165.0 165.0 163.0 165.0 166.0 172.0 11:13:38 INFO - PROCESS | 2183 | 11:13:38 INFO - PROCESS | 2183 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.93 (2.9%) stddev-sans-first:2.03 11:13:38 INFO - PROCESS | 2183 | Values: 67.0 68.0 67.0 68.0 64.0 65.0 62.0 67.0 67.0 67.0 11:13:38 INFO - PROCESS | 2183 | -------- Summary: end -------- 11:13:38 INFO - PROCESS | 2183 | 11:13:39 INFO - TEST-INFO | 2183: exit 0 11:13:39 INFO - TEST-OK | kraken | took 60471ms 11:13:39 INFO - SUITE-END | took 1180s 11:13:39 INFO - Completed test suite (00:19:40) 11:13:39 INFO - javascript benchmark 11:13:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3268.297316077043, 3283.637290524431, 3284.9014529564115, 3316.651751566084, 3335.7664963452567, 5635.66186028838, 5698.857528561778, 5719.885602287962, 5745.569082318827, 5758.049921762915, 4741.241401375781, 5643.661380317184, 5715.257016587848, 5730.939776141417, 5733.971330143354, 5115.769790359424, 5144.56271216948, 5181.844544663647, 5184.559312458446, 5229.450907654698, 5131.255967884655, 5142.537171654551, 5147.38231412231, 5262.92105618416, 5280.445553216915, 5109.9489005109845, 5170.612204084689, 5200.739963001834, 5211.088059589576, 5227.555657769094, 5200.245964335188, 5279.63042587019, 5291.841244762663, 5296.205569164617, 5320.547753440962, 5055.241713742968, 5111.182210846247, 5190.091733946544, 5216.947830521684, 5230.503102205284, 5562.972185139049, 5564.8330550083565, 5578.30271216098, 5609.5792815539, 5609.831705048855, 6081.635101893857, 6149.631022138686, 6163.599366041193, 6192.442680158807, 6199.380061993765, 6182.412670796267, 6205.875882482325, 6214.968925155391, 6232.25212974446, 6232.283287421914, 4792.808287668524, 5653.321601407857, 5713.143028545752, 5721.799737009268, 5726.541876649858, 6180.412860778219, 6214.564980451415, 6217.875642487125, 6219.564630475914, 6223.688815559294, 6082.239720034996, 6082.878342433127, 6087.1173679816175, 6094.933386657406, 6097.878042439126, 6055.545834062463, 6060.606060606046, 6080.604760690541, 6136.693165341715, 6152.16945712332, 6103.786367477117, 6141.578063794062, 6149.3235744068115, 6152.230971128609, 6155.815325540241, 6139.109829074849, 6150.7232174552255, 6183.319834818167, 6200.410960958701, 6210.689465526705, 4722.24444088951, 4833.3958255218095, 4870.975645121752, 4875.341828853059, 4905.969746353235, 3664.6885014774043, 4339.435873336509, 4373.518912919576, 4373.934390984138, 4430.20256353853, 5746.540276777848, 5756.884862302731, 5762.423757624288, 5764.221830052973, 5764.798232061941, 6078.696065196723, 6167.074938759222, 6199.039148931833, 6202.1937148171355, 6204.410580994799, 5603.467670571289, 5653.208550802891, 5710.8001219957105, 5741.282339707536, 5745.569082318842, 5333.386660534088, 5382.165764306539, 5384.91922621161, 5388.568914486832, 5407.648502847303, 6260.373962603885, 6266.310705822447, 6266.811995640047, 6270.498360131087, 6308.747650093945, 6016.909746353721, 6085.421884920926, 6115.483736156459, 6124.203853499017, 6160.969195153995, 6155.076738489262, 6277.15258440123, 6277.591956522811, 6280.811575652738, 6293.150424692608, 6146.661933593517, 6156.384361563808, 6158.846028849135, 6161.7843375483435, 6171.691415429121, 6087.208662873798, 6140.201773769381, 6168.691565421621, 6170.3212646607935, 6203.255609326729, 5940.851478712894, 5964.761409543569, 6039.0, 6090.299615544269, 6124.14262003311, 5019.7490125494305, 6048.818535443944, 6056.3035250946705, 6058.091286307089, 6075.149479072845, 5654.915176272277, 5880.500157486662, 5897.734601942837, 5898.675572843535, 5898.94101058984, 5883.529317654492, 5982.670953097448, 5998.400159983967, 6039.909401358896, 6129.141920131275, 5911.793087241926, 5914.408559144051, 5925.525957923442, 5927.911081333697, 5955.851103722442], "value": 5635.771938610532, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.0019308197752, 503.77833753148616, 508.4864287070058, 508.83462874565777, 542.3708498142158, 26981.0, 30615.0, 30634.77460901564, 30649.693503064962, 30722.692773072264, 33693.0, 41747.0, 41764.37353439701, 43357.34963975535, 43440.78279608605, 9413.388129771543, 9565.378250413702, 9568.0, 9578.664746733832, 9589.856152157723, 9437.386569872971, 9531.523423828816, 9538.90461095387, 9561.665341713042, 9574.569144388519, 9526.095020972996, 9578.760530986712, 9589.568469418893, 9604.903950960506, 9606.039396060372, 9421.905780942172, 9498.620055197818, 9576.712698618983, 9577.138057574817, 9580.185684216849, 9445.527723613792, 9456.8581471278, 9505.144536991635, 9508.239340852715, 9525.28560357969, 38943.8052809737, 39229.41155882664, 39286.0, 39315.41026884599, 39320.606793931984, 3133.263683034485, 3272.8527216275324, 3323.6177839548277, 3354.329134173175, 3355.379254837863, 26572.0, 27133.050343237894, 30355.544666830014, 30371.544426833614, 31230.687693123233, 3170.9682903171133, 3217.694319039711, 3248.13924310058, 3270.5584746059217, 3316.535685004101, 468245.0, 477095.0, 477186.0, 477982.0, 478572.0, 63752.406189844696, 64899.67550162313, 64909.0, 64956.75216239169, 65015.0, 35182.64817351845, 35549.82225088858, 36009.63990360115, 37015.81492092522, 68754.5622718862, 15872.20638968047, 16015.599610009844, 16028.358865645478, 16061.11663858499, 16075.276612552463, 11901.34542600148, 12161.452734626962, 12205.328706921204, 12358.691032724253, 12384.0, 16815.495535133967, 19999.800001999793, 20354.49113772138, 20404.79595204029, 20425.182992680126, 8164.306033987059, 8178.672853085929, 8224.0, 8231.6417791065, 8301.128381519853, 817.3747083481142, 934.7008957133788, 973.8393165127816, 982.4547376206132, 987.6148302162162, 29459.705402946125, 29492.410151797274, 29628.555571666442, 30280.091597252118, 30881.919132830244, 870.4820631724114, 881.167296904432, 954.5084281594984, 1000.614763316114, 1080.897314755097, 13362.857898070522, 15276.312565934559, 15285.465008724641, 15552.455664051704, 15796.684066318609, 659930.0, 665295.0, 666376.0, 667471.0, 668238.0, 45895.082098357845, 55562.110652253956, 55774.0, 55805.0, 55815.44184558102, 1218.5247753376013, 1268.543324403214, 1339.5512503311304, 1385.764420048574, 1391.3669280477359, 29801.70198298076, 30062.549061763653, 30504.84747576248, 30518.694813052472, 31091.689083109784, 46186.538134618226, 46740.831479213295, 46779.53220467888, 46792.7660361696, 46803.53196467992, 522464.0, 524819.0, 525245.0, 527231.0, 650173.0, 34458.483122752674, 39969.80015099906, 40766.0, 41903.95240119022, 42233.36649950192, 42863.571364285955, 43025.0, 43197.784011081, 43568.34647480227, 43608.78195609002, 35097.64902351046, 35595.46606800952, 35743.0, 36178.0, 36180.0, 35527.289454211284, 35802.820985894905, 36101.458478122324, 36870.262594747415, 37667.43498847574, 34984.650153499155, 35706.821465892506, 35966.280674385845, 36306.91079267626, 38261.61738382692, 23772.762272377055, 29905.551416729202, 33622.83188584139, 34375.434899301814, 34414.82792586021, 23037.65443518315, 25135.74864251334, 25286.24141275765, 25627.35931601725, 25675.35811604725], "value": 19405.84707660532, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.5293666676623, 341.3548393536216, 349.12892333627605, 366.5436531518258, 400.3074680802212, 14848.554543363689, 15114.09315441073, 15974.920125399356, 19937.800621993774, 20327.085281162344, 18889.0, 19159.808401915976, 19431.0, 19851.900740496276, 19887.90056049721, 5130.3074084998425, 5397.028534863723, 5404.027275090482, 5412.323459567554, 5415.593830462712, 4714.363560919266, 5002.4997500249865, 5350.8127215547465, 5871.178035075093, 5944.346121926585, 5898.7935422260225, 5905.43898329658, 5909.527237820964, 5910.970445147768, 5925.437083477084, 5609.186667933147, 5781.277340332479, 5805.100209467497, 5825.679587622722, 5826.009578371687, 4966.528179822911, 5178.17149256121, 5195.532402083831, 5198.142306519419, 5211.739413029333, 23472.882635586884, 23580.29259122229, 24086.63870041951, 24417.87791061051, 24865.751342486525, 2411.8552886826847, 2586.2887705880844, 2656.8973875841502, 2660.8935642574274, 2673.211402636227, 9533.47565883876, 9637.421754694671, 10088.394696318172, 10097.949510252474, 10107.747306317327, 1746.0920321432911, 1834.6881030224895, 1839.8804077734903, 1909.799471055533, 1909.837663798579, 109357.0, 115824.42087789507, 120777.39611301976, 121521.0, 128930.06604900934, 17499.800047988392, 20618.07218675133, 20690.13788759019, 20932.686009709865, 21259.89370053171, 34977.0, 35050.474242886376, 35478.113047174025, 35685.572577096624, 35868.46197307042, 10528.420936848393, 10624.521896514676, 10799.78400431987, 10812.0, 10996.890031099587, 8697.21725044749, 8725.258353040063, 8744.213020828156, 8885.333599979898, 8894.777630559287, 8001.919980800118, 9552.188064014497, 9605.951970240105, 9738.805223895482, 9760.267979901535, 3098.7056229658147, 3115.594972653586, 3626.603757553361, 3644.362236608583, 3646.6717995380013, 701.4809041309394, 818.8403261363977, 849.2441726863096, 888.7911340834899, 930.7812664023949, 20120.39638810838, 20878.269260575806, 21041.36875893695, 21062.052207650693, 21168.0, 848.1645579104577, 853.117023880281, 901.9188273055325, 948.4546385828177, 957.6791774755395, 10397.376157430579, 11021.83467247976, 11083.72290692723, 11097.500612472459, 11148.0, 604480.0, 604682.0, 613451.0, 622079.0, 622755.0, 73247.0, 73317.26682733212, 77075.61462192654, 129366.35316823356, 135079.0, 954.8567714842691, 985.074030411411, 1007.6876168387823, 1014.6905193915926, 1025.707673313102, 17814.643707126044, 17987.460376188734, 18055.819441805415, 18553.25786968506, 19148.617027659646, 48990.0, 50503.24245136399, 52515.0, 52976.67558310919, 53855.192172116665, 63761.0, 73876.0, 77853.0, 79999.0, 81638.0, 7159.749408770668, 7265.346118849329, 7276.96361518189, 7415.369693575891, 7699.230076992256, 10718.89281107179, 12368.319742414255, 12424.0, 12661.873381266438, 12735.745285094432, 10757.838632420677, 10954.507047182737, 11052.889471105185, 11119.555217791198, 11264.943675281898, 9355.578998944928, 10672.252942293964, 10888.237823352289, 11033.558657653604, 11093.112550996062, 11077.224594278323, 11118.0, 11336.829947550628, 11457.312561246352, 11619.36093514831, 7121.14546254432, 7340.486165968331, 7359.816004599714, 7399.963000184965, 7576.166621671501, 10060.798784024424, 10625.0, 10897.945510272399, 11209.887901120885, 11234.438278086227], "value": 10063.56733304194, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.28611120828, 1442.6177063078283, 1480.0231846980992, 1630.2093484659938, 1821.772278465192, 6715.462762978962, 7887.290143887049, 8127.4717143385715, 8457.02744184419, 8469.44948578342, 8301.66793328266, 8373.539455329954, 8395.706150284741, 8405.369597280212, 8406.621702023409, 2293.736220334662, 2315.235972129202, 2324.384038229862, 2325.093213646671, 2325.651152327156, 2303.6544518322303, 2343.496148328109, 2355.1521452277166, 2355.49356888269, 2358.8348815582917, 2280.2019293247395, 2291.472961218921, 2321.361625552978, 2325.3024092772184, 2328.662343960125, 2274.40865375002, 2289.2331069091792, 2309.1802410144323, 2309.6766452696634, 2311.6185829338137, 2117.9258725944674, 2176.749673787519, 2284.223364056229, 2309.203324852923, 2313.34069790109, 8098.716544920959, 8321.83356332876, 8403.957980210122, 8407.285380742584, 8424.115467875847, 4445.333200019991, 4703.6942598730975, 5185.377754669464, 5210.036143313499, 5221.503957124067, 3343.1307859956587, 3406.812625305607, 3462.3248466549035, 3480.0255928339852, 3508.859645614172, 1076.4402510694395, 1104.089126470665, 1714.579927917668, 1732.6534693061437, 1819.8635102367243, 10486.632967846088, 10903.672889813319, 10972.23194376394, 10975.957284058002, 10981.231313808037, 4338.848140315137, 4456.487503937089, 4492.663050271242, 4499.820007199675, 4504.369388285642, 8637.784055398666, 8638.09300023501, 8639.136086391436, 8639.352048596254, 8648.351373647001, 5075.086484432763, 5088.796448142033, 5094.6688465249645, 5101.948980510221, 5101.948980510221, 3705.9592769366222, 4629.189891768927, 4778.474367819537, 4796.352492413549, 4807.399075115611, 3599.136207310279, 3633.8728144514816, 3684.428913518409, 3723.1809002019513, 3725.70194384454, 962.3167551038841, 1124.58952482345, 1128.966131016071, 1133.9659810205708, 1138.2544433396138, 816.6692489541745, 864.8875646165958, 898.9640414383482, 906.777839429344, 910.8432290990537, 5483.917741233885, 5539.169124631338, 5550.86122846924, 5570.248016517799, 5807.854803629859, 881.167296904432, 899.739075668062, 912.2929729459722, 918.3250311021394, 931.4969916245287, 3478.0783092480515, 3670.7430479866152, 3698.4452332149854, 3786.8674596388996, 3793.260314238697, 9804.0, 10189.796204075878, 10305.793884122275, 10311.07200351972, 10319.277650564389, 7712.421568382281, 9667.226621870233, 9670.323077384515, 10273.229507786797, 10281.794364112677, 7152.892706609405, 7179.461540384492, 7417.10994680631, 7772.689092436352, 7823.726169584037, 5239.266502689703, 5609.4390560943575, 5666.376698563237, 5669.574781891292, 5961.821145365646, 9113.453192808453, 9285.396449230779, 9289.978102408595, 9295.30285228597, 9351.906480935375, 9505.857412138685, 9529.0, 9568.425894446356, 9573.521323933915, 9598.808023839623, 6741.898871516833, 7022.964885175541, 7100.609466479186, 7232.4575656827, 7239.819004524929, 7748.612569371396, 7758.418118641237, 7834.451588388986, 7843.215678432111, 7849.764507064797, 7102.502824802421, 7132.536385134949, 7157.391621712213, 7207.810711232798, 7264.23725508814, 6956.373926346653, 7025.367716905503, 7036.788896333118, 7066.222715501188, 7080.008798768074, 6543.116679248241, 6682.231543372574, 6683.331666833278, 6689.49828762835, 6699.732010719712, 4679.508651591534, 4868.294097355934, 4877.731724755173, 4917.729524876023, 4933.876653083702, 4711.969234428834, 4730.905381892274, 4739.099571081483, 4936.9259461107395, 4965.82619608312], "value": 4395.116821413118, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.3369386268621, 305.4257994969458, 305.6179775280899, 310.82283098633775, 313.8430784607696, 4345.978695006671, 4902.950970490294, 5068.999999999995, 5075.670081444703, 5135.383753949522, 4964.851054468371, 5015.473375295596, 5083.44082150964, 5113.897722045557, 5133.717645529495, 3148.740503798476, 3771.6039815819354, 3840.599625234229, 3842.4236364545263, 3871.36122539782, 3269.8365081745937, 3812.4471951567007, 3843.2121415109973, 3918.7060970427333, 3935.4096885467266, 3357.446021406465, 3807.8286477108595, 3818.1863614782897, 3846.0769615192403, 3863.9227215455676, 3224.452262913791, 3583.7849729016343, 3639.5796795131205, 3833.118382771964, 3876.263509933118, 2662.427578070724, 3322.9833850830832, 3350.2629421527226, 3351.413502637053, 3375.4599264117624, 4047.514298284225, 4346.913061738779, 4694.624430045588, 4933.432655244621, 4949.257611358289, 4241.0, 4376.934345984812, 4379.912401751979, 4408.963893485028, 4428.446444194476, 5050.646454748169, 5227.189785583203, 5229.424763276074, 5259.447757985395, 5309.867253318659, 3374.6377535724846, 3387.8650652031597, 3663.8534458621625, 3755.286495565834, 3756.3614185588517, 5136.717480538568, 5200.8959820803375, 5609.831705048855, 5714.45709658394, 5722.2561067061015, 3182.570353002315, 3482.651734826548, 3716.3310604090984, 3726.347889119393, 3787.753796003251, 3767.9434808477895, 3791.374423220138, 3796.6142358039115, 3798.620137986179, 3801.752886062397, 4356.390105385249, 4360.383769738195, 4378.10248898975, 4411.602955733999, 4434.933475997863, 4994.981047837347, 5222.451642577475, 5345.144776835766, 5446.91829622556, 5453.23654688344, 1123.5618108937522, 1211.7213041000443, 1215.3376409856648, 1215.4624400133837, 1216.4343580235234, 764.747633281016, 785.1834092543804, 829.8962629671291, 837.3845223760532, 849.3757088539918, 4457.9493433020525, 4726.031163611451, 4883.804647814048, 4885.780139893643, 4926.531979461945, 4908.877278068006, 5249.42256351801, 5472.753726082336, 5513.283273174542, 5519.267962358578, 4674.69614475058, 4965.627577931669, 5008.424031236382, 5045.217991211368, 5069.340985671913, 2678.0, 2682.5841994490884, 2691.6003678087286, 2709.8509581972785, 2720.9047683330987, 5324.53575266797, 5329.7068661224, 5353.544948679328, 5361.51746342831, 5366.704831234241, 4633.142868569325, 4644.654449502979, 4998.000399920031, 5267.499587539178, 5329.440408757025, 4823.638227132979, 5234.371875374979, 5340.198970154508, 5348.251244825728, 5353.625246232727, 4244.702870799015, 4921.680090794087, 5152.201408781645, 5169.0, 5204.531592156724, 4474.664400170065, 4501.819927202875, 4702.285194696152, 5014.674046186986, 5454.15482085137, 5039.370078740158, 5050.292958985671, 5112.156494178419, 5210.0, 5730.681943153015, 3431.5244444888936, 3714.0714821294678, 3769.7926614035396, 3782.867599634, 3788.412796016622, 4285.957140428556, 5474.370447398441, 5526.41972592888, 5564.860878478071, 5572.387037425799, 4541.0, 5368.463153684756, 5383.757730902197, 5392.05639013179, 5401.648892821954, 4568.70303430276, 4922.310876477224, 5387.568994480511, 5388.649737767033, 5445.891082178414, 4642.512536183652, 4955.801767929387, 5194.999925028967, 5247.653285400317, 5261.394939581844, 5089.262057001788, 5262.263283140287, 5300.787968481218, 5331.573474122139, 5408.593765620888, 5132.204508301219, 5214.8957020860125, 5245.737713114406, 5246.763895624782, 5275.446078161738], "value": 3855.542276180338, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.8140192848896, 732.2055569706868, 734.159387501311, 756.5120497279252, 762.6072572625103, 14231.786523202147, 14537.709245815104, 14575.198364089983, 14580.927095364535, 14584.854151458483, 12918.870811291885, 12943.676408089803, 12962.416691248893, 12963.48146074156, 13029.869701302983, 646.85122421843, 666.9032990216416, 666.9699863506154, 673.0644404278033, 804.5373910001758, 668.6322522612568, 669.1568623534333, 788.6490511722286, 808.7614153824636, 809.9838003239931, 843.0768308701962, 847.6270441005938, 853.3983541603186, 855.3755758296447, 856.9057403685621, 848.2662496940164, 850.2177996243471, 850.4429598612887, 851.7742798158492, 852.4033176776251, 509.08619028843316, 525.0155957579539, 632.1907957814005, 632.5445679111033, 634.4004915354991, 13105.606831795061, 13617.319134043259, 13654.0, 13965.511207107702, 13967.231802250872, 13374.933125334408, 13622.863771362358, 13834.30828458573, 13839.0, 13858.514951976633, 10736.516856741466, 11165.72085697856, 11286.322820630707, 11459.713507162303, 11502.41422872013, 3633.9273214535824, 3694.131879008431, 3709.962900370989, 3830.5020347354944, 3831.501904752364, 84721.15278847257, 87414.56292718559, 88238.5588072062, 88751.0, 89099.0, 10459.996136567426, 10547.84178237327, 10613.628523001658, 10629.722921914297, 10692.83960740574, 20296.391108266776, 20379.898100509403, 20385.898070509553, 20468.795312046987, 20553.58892822135, 7096.964515177391, 7209.387202087776, 7267.927320726831, 7432.925670743223, 7496.550206987489, 4064.552899181088, 4169.812358443877, 4193.622573968358, 4226.302660061056, 4326.935095973563, 3356.5468661730843, 3405.625381208065, 3486.651334866544, 3491.94762078569, 3495.8601655933476, 667.786308381323, 686.1663079358603, 697.4141720954423, 707.0737334092355, 708.9361957423856, 552.7101583974346, 559.194759546252, 649.7595889520813, 677.1197443323659, 679.429279405292, 8608.48349099056, 8725.912740872636, 9133.862992055125, 9134.862977055349, 9209.539523023823, 601.2004034633926, 617.3857012272817, 619.123939625434, 665.4343807763363, 671.7447369999369, 3618.222082252328, 3708.387687834248, 3918.3534716771983, 4210.148968374133, 4550.590446859798, 99635.50546741804, 104635.0, 106603.3349166277, 115991.42004289925, 116524.0, 91394.0, 92425.0, 92898.53550732203, 95106.0, 95151.52424237835, 63708.681456593346, 65551.0, 77645.0, 78829.0, 87422.0, 8859.911400885909, 9119.544022798966, 9444.433333999981, 9627.422354658744, 9683.903160968583, 50865.49134508608, 55334.72332638311, 55372.44627553834, 55709.442905570424, 56528.0, 54033.459665404414, 54886.72556637191, 68990.65504672444, 72438.27561724526, 74246.0, 13120.409581568661, 13161.210327380388, 13172.275524846225, 13604.04771665974, 13682.45270189221, 12182.695432614255, 12275.508979640714, 13297.26865022433, 13528.59414217575, 14280.928595357373, 12307.507699691912, 12529.812052819398, 12645.0, 13172.0, 13299.401526931504, 10654.0, 12504.0, 13047.28239946812, 13116.344182791014, 13345.599632010666, 10983.725406864893, 11137.55449782032, 11375.715607109889, 12176.817347739614, 12291.385430728607, 9217.907820921973, 10279.588816447558, 10570.947145264225, 10747.516361763895, 10981.0, 8912.55437228149, 11286.266392684778, 11347.943260283646, 11615.0, 11713.941430292794], "value": 7101.313855707539, "unit": "score"}], "name": "dromaeo_css", "value": 7139.70825703391, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 122.0, 166.0, 115.0, 115.0, 116.0, 115.0, 116.0, 114.0, 114.0], "value": 121.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 110.0, 144.0, 139.0, 139.0, 109.0, 109.0, 137.0, 134.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 186.0, 186.0, 183.0, 183.0, 184.0, 184.0, 185.0, 186.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 49.0, 91.0, 79.0, 85.0, 84.0, 82.0, 85.0, 59.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 81.0, 71.0, 83.0, 99.0, 126.0, 98.0, 67.0, 88.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 86.0, 87.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 150.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 95.0, 132.0, 83.0, 83.0, 83.0, 85.0, 84.0, 83.0], "value": 89.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 52.0, 80.0, 84.0, 81.0, 82.0, 58.0, 56.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 73.0, 76.0, 72.0, 71.0, 71.0, 71.0, 71.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 82.0, 82.0, 84.0, 83.0, 82.0, 84.0, 82.0, 82.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 137.0, 137.0, 143.0, 143.0, 152.0, 156.0, 259.0, 161.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 166.0, 166.0, 165.0, 165.0, 163.0, 165.0, 166.0, 172.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 64.0, 65.0, 62.0, 67.0, 67.0, 67.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1483.7, "alertThreshold": 2.0}]} 11:13:39 INFO - Return code: 0 11:13:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:13:39 INFO - # TBPL SUCCESS # 11:13:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:13:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:13:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:13:39 INFO - 2016-12-15 11:13:39.787 screenresolution[2187:25038] starting screenresolution argv=screenresolution get 11:13:39 INFO - 2016-12-15 11:13:39.790 screenresolution[2187:25038] Display 0: 1600x1200x32@60 11:13:39 INFO - 2016-12-15 11:13:39.796 screenresolution[2188:25041] starting screenresolution argv=screenresolution list 11:13:39 INFO - Available Modes on Display 0 11:13:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:13:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:13:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:13:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:13:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:13:40 INFO - Intel Iris: 11:13:40 INFO - Chipset Model: Intel Iris 11:13:40 INFO - Type: GPU 11:13:40 INFO - Bus: Built-In 11:13:40 INFO - VRAM (Dynamic, Max): 1536 MB 11:13:40 INFO - Vendor: Intel (0x8086) 11:13:40 INFO - Device ID: 0x0a2e 11:13:40 INFO - Revision ID: 0x0009 11:13:40 INFO - Displays: 11:13:40 INFO - Display: 11:13:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:13:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:13:40 INFO - Display Serial Number: 1600x1200 60 11:13:40 INFO - Main Display: Yes 11:13:40 INFO - Mirror: Off 11:13:40 INFO - Online: Yes 11:13:40 INFO - Rotation: Supported 11:13:40 INFO - Return code: 0 11:13:40 INFO - Running post-action listener: _resource_record_post_action 11:13:40 INFO - [mozharness: 2016-12-15 19:13:40.049682Z] Finished run-tests step (success) 11:13:40 INFO - Running post-run listener: _resource_record_post_run 11:13:40 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1339392; Write bytes: 658393600; Read time: 18; Write time: 2466 11:13:40 INFO - TinderboxPrint: CPU usage
53.6% 11:13:40 INFO - TinderboxPrint: I/O read bytes / time
1,339,392 / 18 11:13:40 INFO - TinderboxPrint: I/O write bytes / time
658,393,600 / 2,466 11:13:40 INFO - TinderboxPrint: CPU idle
2,252.3 (46.4%) 11:13:40 INFO - TinderboxPrint: CPU system
976.4 (20.1%) 11:13:40 INFO - TinderboxPrint: CPU user
1,625.1 (33.5%) 11:13:40 INFO - TinderboxPrint: Swap in / out
349,061,120 / 0 11:13:40 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 333935104; Write bytes: 334873600; Read time: 26771; Write time: 927 11:13:40 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 1339392; Write bytes: 305829376; Read time: 18; Write time: 1486 11:13:40 INFO - Running post-run listener: _upload_blobber_files 11:13:40 INFO - Blob upload gear active. 11:13:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:13:40 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 11:13:40 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'] 11:13:40 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 11:13:40 INFO - (blobuploader) - INFO - Open directory for files ... 11:13:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:13:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:13:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:41 INFO - (blobuploader) - INFO - Done attempting. 11:13:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:13:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:13:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:42 INFO - (blobuploader) - INFO - Done attempting. 11:13:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:13:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:13:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:44 INFO - (blobuploader) - INFO - Done attempting. 11:13:44 INFO - (blobuploader) - INFO - Iteration through files over. 11:13:44 INFO - Return code: 0 11:13:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:13:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:13:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca5b32cb65ff43dc47a5390dbfd063b82a1c112fa4556f4032b6f506274fe3b031736e56be511f0765a0b381579ffd5decc2122771630a585126639f6b06fefe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f5a053fb595de59754f977ffdce5240a541ca23a78e12885293cdbd3226fd424bbb606ee190370f9ec63e20af51af2dca173efb5d6c968648af02ed182a88e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19076ea9d30566d9e88e2f14a05a6c8049e7f93c2b7110567e2585d4bb217042bc8d9c539e83a6129abee865e1c5081166d7990d88a76710733e5af4c52b1cf3"} 11:13:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:13:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:13:44 INFO - Contents: 11:13:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca5b32cb65ff43dc47a5390dbfd063b82a1c112fa4556f4032b6f506274fe3b031736e56be511f0765a0b381579ffd5decc2122771630a585126639f6b06fefe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f5a053fb595de59754f977ffdce5240a541ca23a78e12885293cdbd3226fd424bbb606ee190370f9ec63e20af51af2dca173efb5d6c968648af02ed182a88e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19076ea9d30566d9e88e2f14a05a6c8049e7f93c2b7110567e2585d4bb217042bc8d9c539e83a6129abee865e1c5081166d7990d88a76710733e5af4c52b1cf3"} 11:13:44 INFO - Running post-run listener: copy_logs_to_upload_dir 11:13:44 INFO - Copying logs to upload dir... 11:13:44 INFO - mkdir: /builds/slave/test/build/upload/logs 11:13:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.183797 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-15 11:13:45.019473) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 11:13:45.023245) ========= 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.3jv91dWzjz/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.SgzGZ1SOap/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca5b32cb65ff43dc47a5390dbfd063b82a1c112fa4556f4032b6f506274fe3b031736e56be511f0765a0b381579ffd5decc2122771630a585126639f6b06fefe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f5a053fb595de59754f977ffdce5240a541ca23a78e12885293cdbd3226fd424bbb606ee190370f9ec63e20af51af2dca173efb5d6c968648af02ed182a88e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19076ea9d30566d9e88e2f14a05a6c8049e7f93c2b7110567e2585d4bb217042bc8d9c539e83a6129abee865e1c5081166d7990d88a76710733e5af4c52b1cf3"} build_url:https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010949 build_url: 'https://queue.taskcluster.net/v1/task/PsJhrJ_iS3SxL10RT1NMGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca5b32cb65ff43dc47a5390dbfd063b82a1c112fa4556f4032b6f506274fe3b031736e56be511f0765a0b381579ffd5decc2122771630a585126639f6b06fefe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f5a053fb595de59754f977ffdce5240a541ca23a78e12885293cdbd3226fd424bbb606ee190370f9ec63e20af51af2dca173efb5d6c968648af02ed182a88e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19076ea9d30566d9e88e2f14a05a6c8049e7f93c2b7110567e2585d4bb217042bc8d9c539e83a6129abee865e1c5081166d7990d88a76710733e5af4c52b1cf3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 11:13:45.058535) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 11:13:45.058951) ========= 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.3jv91dWzjz/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.SgzGZ1SOap/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.005086 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 11:13:45.110357) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 11:13:45.111054) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 11:13:45.111717) ========= ========= Total master_lag: 0.26 =========