builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0151 starttime: 1472262095.32 results: success (0) buildid: 20160826163012 builduid: 0285361a8acc43deb247e4e32982182f revision: c724ae8bb7b867b6bc65efa1049c0322457ff4b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.323881) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.324467) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.324826) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.349068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.349791) ========= 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.gIertc1aDo/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.und83cJxz7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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-08-26 18:41:35-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.05M=0.001s 2016-08-26 18:41:35 (8.05 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126612 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.504338) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.504711) ========= 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.gIertc1aDo/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.und83cJxz7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.021307 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.555416) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:35.556241) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c724ae8bb7b867b6bc65efa1049c0322457ff4b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c724ae8bb7b867b6bc65efa1049c0322457ff4b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gIertc1aDo/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.und83cJxz7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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-08-26 18:41:35,633 truncating revision to first 12 chars 2016-08-26 18:41:35,633 Setting DEBUG logging. 2016-08-26 18:41:35,633 attempt 1/10 2016-08-26 18:41:35,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c724ae8bb7b8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 18:41:36,255 unpacking tar archive at: mozilla-central-c724ae8bb7b8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.879896 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:36.463862) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:36.464304) ========= script_repo_revision: c724ae8bb7b867b6bc65efa1049c0322457ff4b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:36.464789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:36.465101) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 18:41:36.484936) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 18:41:36.485264) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gIertc1aDo/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.und83cJxz7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:41:36 INFO - MultiFileLogger online at 20160826 18:41:36 in /builds/slave/test 18:41:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:41:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:41:36 INFO - {'append_to_log': False, 18:41:36 INFO - 'base_work_dir': '/builds/slave/test', 18:41:36 INFO - 'blob_upload_branch': 'Firefox', 18:41:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:41:36 INFO - 'branch': 'Firefox', 18:41:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:41:36 INFO - 'config_files': ('talos/mac_config.py',), 18:41:36 INFO - 'default_actions': ('clobber', 18:41:36 INFO - 'read-buildbot-config', 18:41:36 INFO - 'download-and-extract', 18:41:36 INFO - 'populate-webroot', 18:41:36 INFO - 'create-virtualenv', 18:41:36 INFO - 'install', 18:41:36 INFO - 'run-tests'), 18:41:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:41:36 INFO - 'download_minidump_stackwalk': True, 18:41:36 INFO - 'download_symbols': 'ondemand', 18:41:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:41:36 INFO - 'tooltool.py': '/tools/tooltool.py', 18:41:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:41:36 INFO - '/tools/misc-python/virtualenv.py')}, 18:41:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:41:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:41:36 INFO - 'installer_path': 'installer.exe', 18:41:36 INFO - 'log_level': 'info', 18:41:36 INFO - 'log_name': 'talos', 18:41:36 INFO - 'log_to_console': True, 18:41:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:41:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:41:36 INFO - 'opt_config_files': (), 18:41:36 INFO - 'pip_index': False, 18:41:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:41:36 INFO - 'cmd': ('bash', 18:41:36 INFO - '-c', 18:41:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:41:36 INFO - 'enabled': True, 18:41:36 INFO - 'halt_on_failure': False, 18:41:36 INFO - 'name': 'check_screen_resolution'},), 18:41:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:41:36 INFO - 'cmd': ('bash', 18:41:36 INFO - '-c', 18:41:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:41:36 INFO - 'enabled': True, 18:41:36 INFO - 'halt_on_failure': False, 18:41:36 INFO - 'name': 'check_screen_resolution'},), 18:41:36 INFO - 'run_cmd_checks_enabled': True, 18:41:36 INFO - 'sps_profile': False, 18:41:36 INFO - 'sps_profile_interval': 0, 18:41:36 INFO - 'suite': 'dromaeojs', 18:41:36 INFO - 'system_bits': '32', 18:41:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:41:36 INFO - 'title': 't-yosemite-r7-0151', 18:41:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:41:36 INFO - 'use_talos_json': True, 18:41:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:41:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:41:36 INFO - 'work_dir': 'build'} 18:41:36 INFO - [mozharness: 2016-08-27 01:41:36.679104Z] Running clobber step. 18:41:36 INFO - Running pre-action listener: _resource_record_pre_action 18:41:36 INFO - Running main action method: clobber 18:41:36 INFO - rmtree: /builds/slave/test/build 18:41:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:41:36 INFO - Running post-action listener: _resource_record_post_action 18:41:36 INFO - [mozharness: 2016-08-27 01:41:36.872156Z] Finished clobber step (success) 18:41:36 INFO - [mozharness: 2016-08-27 01:41:36.872279Z] Running read-buildbot-config step. 18:41:36 INFO - Running pre-action listener: _resource_record_pre_action 18:41:36 INFO - Running main action method: read_buildbot_config 18:41:36 INFO - Using buildbot properties: 18:41:36 INFO - { 18:41:36 INFO - "project": "", 18:41:36 INFO - "product": "firefox", 18:41:36 INFO - "script_repo_revision": "production", 18:41:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:41:36 INFO - "repository": "", 18:41:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:41:36 INFO - "buildid": "20160826163012", 18:41:36 INFO - "pgo_build": "False", 18:41:36 INFO - "basedir": "/builds/slave/test", 18:41:36 INFO - "buildnumber": 325, 18:41:36 INFO - "slavename": "t-yosemite-r7-0151", 18:41:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:41:36 INFO - "platform": "yosemite_r7", 18:41:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:41:36 INFO - "branch": "mozilla-central", 18:41:36 INFO - "repo_path": "mozilla-central", 18:41:36 INFO - "revision": "c724ae8bb7b867b6bc65efa1049c0322457ff4b0", 18:41:36 INFO - "stage_platform": "macosx64", 18:41:36 INFO - "builduid": "0285361a8acc43deb247e4e32982182f", 18:41:36 INFO - "slavebuilddir": "test" 18:41:36 INFO - } 18:41:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:41:36 INFO - Running post-action listener: _resource_record_post_action 18:41:36 INFO - [mozharness: 2016-08-27 01:41:36.875653Z] Finished read-buildbot-config step (success) 18:41:36 INFO - [mozharness: 2016-08-27 01:41:36.875785Z] Running download-and-extract step. 18:41:36 INFO - Running pre-action listener: _resource_record_pre_action 18:41:36 INFO - Running main action method: download_and_extract 18:41:36 INFO - mkdir: /builds/slave/test/build/tests 18:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:36 INFO - trying https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:41:36 INFO - Downloading https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:41:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 18:41:38 INFO - Downloaded 1199 bytes. 18:41:38 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:41:38 INFO - Using the following test package requirements: 18:41:38 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:41:38 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:41:38 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:41:38 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:41:38 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:41:38 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:41:38 INFO - u'jsshell-mac.zip'], 18:41:38 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:41:38 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:41:38 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:41:38 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:41:38 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:41:38 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:41:38 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:41:38 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:41:38 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:41:38 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:41:38 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:41:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:38 INFO - trying https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:41:38 INFO - Downloading https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:41:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:41:39 INFO - Downloaded 16369385 bytes. 18:41:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:41:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:41:46 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')]} 18:41:46 INFO - trying https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:41:46 INFO - Downloading https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:41:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:41:47 INFO - Downloaded 16369385 bytes. 18:41:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:41:54 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')]} 18:41:54 INFO - trying https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:41:54 INFO - Downloading https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:41:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:41:55 INFO - Downloaded 14413998 bytes. 18:41:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:56 INFO - trying https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:41:56 INFO - Downloading https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:41:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 18:41:59 INFO - Downloaded 111554788 bytes. 18:41:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:41:59 INFO - mkdir: /builds/slave/test/properties 18:41:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:41:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:41:59 INFO - Contents: 18:41:59 INFO - build_url:https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:42:00 INFO - Running post-action listener: _resource_record_post_action 18:42:00 INFO - Running post-action listener: set_extra_try_arguments 18:42:00 INFO - [mozharness: 2016-08-27 01:42:00.478427Z] Finished download-and-extract step (success) 18:42:00 INFO - [mozharness: 2016-08-27 01:42:00.478571Z] Running populate-webroot step. 18:42:00 INFO - Running pre-action listener: _resource_record_pre_action 18:42:00 INFO - Running main action method: populate_webroot 18:42:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:42:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:42:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:42:00 INFO - '--tppagecycles', 18:42:00 INFO - '7'], 18:42:00 INFO - 'tests': ['tsvgm']}}, 18:42:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:42:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:42:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:42:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:42:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['damp', 'tps']}, 18:42:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'tests': ['damp', 'tps']}, 18:42:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['dromaeo_dom']}, 18:42:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:42:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['basic_compositor_video']}, 18:42:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:42:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other-e10s': {'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['tsvgx', 18:42:00 INFO - 'tsvgr_opacity', 18:42:00 INFO - 'tart', 18:42:00 INFO - 'tscrollx', 18:42:00 INFO - 'cart']}, 18:42:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:42:00 INFO - 'tsvgr_opacity', 18:42:00 INFO - 'tart', 18:42:00 INFO - 'tscrollx', 18:42:00 INFO - 'cart']}, 18:42:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['tp5o']}, 18:42:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'tests': ['tp5o']}, 18:42:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--disable-e10s', 18:42:00 INFO - '--xperf_path', 18:42:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:00 INFO - 'tests': ['tp5n']}, 18:42:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--xperf_path', 18:42:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:00 INFO - 'tests': ['tp5n']}}, 18:42:00 INFO - 'talos.zip': {'path': '', 18:42:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:42:00 INFO - Running post-action listener: _resource_record_post_action 18:42:00 INFO - [mozharness: 2016-08-27 01:42:00.497494Z] Finished populate-webroot step (success) 18:42:00 INFO - [mozharness: 2016-08-27 01:42:00.497605Z] Running create-virtualenv step. 18:42:00 INFO - Running pre-action listener: _resource_record_pre_action 18:42:00 INFO - Running main action method: create_virtualenv 18:42:00 INFO - Creating virtualenv /builds/slave/test/build/venv 18:42:00 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 18:42:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:42:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:42:00 INFO - Using real prefix '/tools/python27' 18:42:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:42:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:42:04 INFO - Installing pip.................done. 18:42:04 INFO - Return code: 0 18:42:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:42:04 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')]} 18:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:04 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')]} 18:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:04 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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:04 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 18:42:04 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 18:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:04 INFO - 'HOME': '/Users/cltbld', 18:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:04 INFO - 'LOGNAME': 'cltbld', 18:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:04 INFO - 'MOZ_NO_REMOTE': '1', 18:42:04 INFO - 'NO_EM_RESTART': '1', 18:42:04 INFO - 'PAGER': '/bin/cat', 18:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:04 INFO - 'PWD': '/builds/slave/test', 18:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:04 INFO - 'SHELL': '/bin/bash', 18:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:04 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:04 INFO - 'USER': 'cltbld', 18:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:04 INFO - 'XPC_FLAGS': '0x0', 18:42:04 INFO - 'XPC_SERVICE_NAME': '0', 18:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:04 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 18:42:04 INFO - Cleaning up... 18:42:04 INFO - Return code: 0 18:42:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:42:04 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')]} 18:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:04 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')]} 18:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:04 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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:04 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 18:42:04 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 18:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:04 INFO - 'HOME': '/Users/cltbld', 18:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:04 INFO - 'LOGNAME': 'cltbld', 18:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:04 INFO - 'MOZ_NO_REMOTE': '1', 18:42:04 INFO - 'NO_EM_RESTART': '1', 18:42:04 INFO - 'PAGER': '/bin/cat', 18:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:04 INFO - 'PWD': '/builds/slave/test', 18:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:04 INFO - 'SHELL': '/bin/bash', 18:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:04 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:04 INFO - 'USER': 'cltbld', 18:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:04 INFO - 'XPC_FLAGS': '0x0', 18:42:04 INFO - 'XPC_SERVICE_NAME': '0', 18:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:04 INFO - Downloading/unpacking psutil>=3.1.1 18:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:42:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:08 INFO - Installing collected packages: psutil 18:42:08 INFO - Running setup.py install for psutil 18:42:08 INFO - building 'psutil._psutil_osx' extension 18:42:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:42:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:42:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:42:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:42:08 INFO - building 'psutil._psutil_posix' extension 18:42:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:42:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:42:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:42:08 INFO - ^ 18:42:08 INFO - 1 warning generated. 18:42:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:42:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:09 INFO - Successfully installed psutil 18:42:09 INFO - Cleaning up... 18:42:09 INFO - Return code: 0 18:42:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:42:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:09 INFO - 'HOME': '/Users/cltbld', 18:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:09 INFO - 'LOGNAME': 'cltbld', 18:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:09 INFO - 'MOZ_NO_REMOTE': '1', 18:42:09 INFO - 'NO_EM_RESTART': '1', 18:42:09 INFO - 'PAGER': '/bin/cat', 18:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:09 INFO - 'PWD': '/builds/slave/test', 18:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:09 INFO - 'SHELL': '/bin/bash', 18:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:09 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:09 INFO - 'USER': 'cltbld', 18:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:09 INFO - 'XPC_FLAGS': '0x0', 18:42:09 INFO - 'XPC_SERVICE_NAME': '0', 18:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:42:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:42:12 INFO - Installing collected packages: mozsystemmonitor 18:42:12 INFO - Running setup.py install for mozsystemmonitor 18:42:12 INFO - Successfully installed mozsystemmonitor 18:42:12 INFO - Cleaning up... 18:42:12 INFO - Return code: 0 18:42:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:42:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:42:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:12 INFO - 'HOME': '/Users/cltbld', 18:42:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:12 INFO - 'LOGNAME': 'cltbld', 18:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:12 INFO - 'MOZ_NO_REMOTE': '1', 18:42:12 INFO - 'NO_EM_RESTART': '1', 18:42:12 INFO - 'PAGER': '/bin/cat', 18:42:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:12 INFO - 'PWD': '/builds/slave/test', 18:42:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:12 INFO - 'SHELL': '/bin/bash', 18:42:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:12 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:12 INFO - 'USER': 'cltbld', 18:42:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:12 INFO - 'XPC_FLAGS': '0x0', 18:42:12 INFO - 'XPC_SERVICE_NAME': '0', 18:42:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:12 INFO - Downloading/unpacking jsonschema==2.5.1 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:42:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:42:14 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 18:42:14 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 18:42:14 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 18:42:14 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 18:42:14 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 18:42:14 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 18:42:15 INFO - Downloading functools32-3.2.3-2.tar.gz 18:42:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:42:15 INFO - warning: no files found matching '*.txt' 18:42:15 INFO - no previously-included directories found matching 'build' 18:42:15 INFO - no previously-included directories found matching 'dist' 18:42:15 INFO - no previously-included directories found matching '.git*' 18:42:15 INFO - Installing collected packages: jsonschema, functools32 18:42:15 INFO - Running setup.py install for functools32 18:42:15 INFO - warning: no files found matching '*.txt' 18:42:15 INFO - no previously-included directories found matching 'build' 18:42:15 INFO - no previously-included directories found matching 'dist' 18:42:15 INFO - no previously-included directories found matching '.git*' 18:42:15 INFO - Successfully installed jsonschema functools32 18:42:15 INFO - Cleaning up... 18:42:15 INFO - Return code: 0 18:42:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:15 INFO - 'HOME': '/Users/cltbld', 18:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:15 INFO - 'LOGNAME': 'cltbld', 18:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:15 INFO - 'MOZ_NO_REMOTE': '1', 18:42:15 INFO - 'NO_EM_RESTART': '1', 18:42:15 INFO - 'PAGER': '/bin/cat', 18:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:15 INFO - 'PWD': '/builds/slave/test', 18:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:15 INFO - 'SHELL': '/bin/bash', 18:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:15 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:15 INFO - 'USER': 'cltbld', 18:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:15 INFO - 'XPC_FLAGS': '0x0', 18:42:15 INFO - 'XPC_SERVICE_NAME': '0', 18:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:42:15 INFO - Cleaning up... 18:42:15 INFO - Return code: 0 18:42:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:15 INFO - 'HOME': '/Users/cltbld', 18:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:15 INFO - 'LOGNAME': 'cltbld', 18:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:15 INFO - 'MOZ_NO_REMOTE': '1', 18:42:15 INFO - 'NO_EM_RESTART': '1', 18:42:15 INFO - 'PAGER': '/bin/cat', 18:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:15 INFO - 'PWD': '/builds/slave/test', 18:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:15 INFO - 'SHELL': '/bin/bash', 18:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:15 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:15 INFO - 'USER': 'cltbld', 18:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:15 INFO - 'XPC_FLAGS': '0x0', 18:42:15 INFO - 'XPC_SERVICE_NAME': '0', 18:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:16 INFO - Downloading/unpacking blobuploader==1.2.4 18:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:18 INFO - Downloading blobuploader-1.2.4.tar.gz 18:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:42:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:42:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:19 INFO - Downloading docopt-0.6.1.tar.gz 18:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:42:19 INFO - Installing collected packages: blobuploader, requests, docopt 18:42:19 INFO - Running setup.py install for blobuploader 18:42:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:42:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:42:20 INFO - Running setup.py install for requests 18:42:20 INFO - Running setup.py install for docopt 18:42:20 INFO - Successfully installed blobuploader requests docopt 18:42:20 INFO - Cleaning up... 18:42:20 INFO - Return code: 0 18:42:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:20 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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:20 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 18:42:20 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 18:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:20 INFO - 'HOME': '/Users/cltbld', 18:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:20 INFO - 'LOGNAME': 'cltbld', 18:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:20 INFO - 'MOZ_NO_REMOTE': '1', 18:42:20 INFO - 'NO_EM_RESTART': '1', 18:42:20 INFO - 'PAGER': '/bin/cat', 18:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:20 INFO - 'PWD': '/builds/slave/test', 18:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:20 INFO - 'SHELL': '/bin/bash', 18:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:20 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:20 INFO - 'USER': 'cltbld', 18:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:20 INFO - 'XPC_FLAGS': '0x0', 18:42:20 INFO - 'XPC_SERVICE_NAME': '0', 18:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-TLYM3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-RT6UT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ANQA0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-LdyDdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vkPqbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-kyEYLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-On5Hd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-J25JIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-SQz7_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ROlYQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-p_aO1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yIHHpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-uu9ZTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-86DmYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-aEA2pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-eX0Yk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-L7CGfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:42:22 INFO - Running setup.py install for manifestparser 18:42:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:42:23 INFO - Running setup.py install for mozcrash 18:42:23 INFO - Running setup.py install for mozdebug 18:42:23 INFO - Running setup.py install for mozdevice 18:42:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:42:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:42:23 INFO - Running setup.py install for mozfile 18:42:23 INFO - Running setup.py install for mozhttpd 18:42:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:42:23 INFO - Running setup.py install for mozinfo 18:42:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:42:23 INFO - Running setup.py install for mozInstall 18:42:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Running setup.py install for mozleak 18:42:24 INFO - Running setup.py install for mozlog 18:42:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Running setup.py install for moznetwork 18:42:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Running setup.py install for mozprocess 18:42:24 INFO - Running setup.py install for mozprofile 18:42:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:42:24 INFO - Running setup.py install for mozrunner 18:42:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:42:25 INFO - Running setup.py install for mozscreenshot 18:42:25 INFO - Running setup.py install for moztest 18:42:25 INFO - Running setup.py install for mozversion 18:42:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:42:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:42:25 INFO - Cleaning up... 18:42:25 INFO - Return code: 0 18:42:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:25 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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:25 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 18:42:25 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 18:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:25 INFO - 'HOME': '/Users/cltbld', 18:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:25 INFO - 'LOGNAME': 'cltbld', 18:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:25 INFO - 'MOZ_NO_REMOTE': '1', 18:42:25 INFO - 'NO_EM_RESTART': '1', 18:42:25 INFO - 'PAGER': '/bin/cat', 18:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:25 INFO - 'PWD': '/builds/slave/test', 18:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:25 INFO - 'SHELL': '/bin/bash', 18:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:25 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:25 INFO - 'USER': 'cltbld', 18:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:25 INFO - 'XPC_FLAGS': '0x0', 18:42:25 INFO - 'XPC_SERVICE_NAME': '0', 18:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-nhLS4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:25 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)) 18:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-mwEMwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 18:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Kas5MM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:26 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vxtcWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:26 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-R_3WKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:26 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-RqEpUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:26 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ELNWYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:26 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-AXDQev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:26 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-aTWTvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:26 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-L9gLlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-4pbmzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:26 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)) 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-2Hn3Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:27 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)) 18:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-iAkmDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:27 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)) 18:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-JPlSCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-KSKTTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:27 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)) 18:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-cUBZSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:27 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)) 18:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-3w7XtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:27 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)) 18:42:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:42:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:42:27 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)) 18:42:27 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)) 18:42:27 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)) 18:42:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:42:27 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 18:42:27 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 18:42:27 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 18:42:27 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 18:42:27 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 18:42:27 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 18:42:30 INFO - Downloading blessings-1.6.tar.gz 18:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:42:30 INFO - Installing collected packages: blessings 18:42:30 INFO - Running setup.py install for blessings 18:42:30 INFO - Successfully installed blessings 18:42:30 INFO - Cleaning up... 18:42:30 INFO - Return code: 0 18:42:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:42:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:30 INFO - Reading from file tmpfile_stdout 18:42:30 INFO - Current package versions: 18:42:30 INFO - blessings == 1.6 18:42:30 INFO - blobuploader == 1.2.4 18:42:30 INFO - docopt == 0.6.1 18:42:30 INFO - functools32 == 3.2.3-2 18:42:30 INFO - jsonschema == 2.5.1 18:42:30 INFO - manifestparser == 1.1 18:42:30 INFO - mozInstall == 1.12 18:42:30 INFO - mozcrash == 0.17 18:42:30 INFO - mozdebug == 0.1 18:42:30 INFO - mozdevice == 0.48 18:42:30 INFO - mozfile == 1.2 18:42:30 INFO - mozhttpd == 0.7 18:42:30 INFO - mozinfo == 0.9 18:42:30 INFO - mozleak == 0.1 18:42:30 INFO - mozlog == 3.3 18:42:30 INFO - moznetwork == 0.27 18:42:30 INFO - mozprocess == 0.23 18:42:30 INFO - mozprofile == 0.28 18:42:30 INFO - mozrunner == 6.12 18:42:30 INFO - mozscreenshot == 0.1 18:42:30 INFO - mozsystemmonitor == 0.3 18:42:30 INFO - moztest == 0.7 18:42:30 INFO - mozversion == 1.4 18:42:30 INFO - psutil == 3.1.1 18:42:30 INFO - requests == 1.2.3 18:42:30 INFO - wsgiref == 0.1.2 18:42:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:30 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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:30 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 18:42:30 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 18:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:30 INFO - 'HOME': '/Users/cltbld', 18:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:30 INFO - 'LOGNAME': 'cltbld', 18:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:30 INFO - 'MOZ_NO_REMOTE': '1', 18:42:30 INFO - 'NO_EM_RESTART': '1', 18:42:30 INFO - 'PAGER': '/bin/cat', 18:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:30 INFO - 'PWD': '/builds/slave/test', 18:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:30 INFO - 'SHELL': '/bin/bash', 18:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:30 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:30 INFO - 'USER': 'cltbld', 18:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:30 INFO - 'XPC_FLAGS': '0x0', 18:42:30 INFO - 'XPC_SERVICE_NAME': '0', 18:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 INFO - Cleaning up... 18:42:31 INFO - Return code: 0 18:42:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:42:31 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')]} 18:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:31 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')]} 18:42:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:31 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 0x106e81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2dcdc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, '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.gIertc1aDo/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.und83cJxz7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:42:31 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 18:42:31 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 18:42:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:42:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:31 INFO - 'HOME': '/Users/cltbld', 18:42:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:31 INFO - 'LOGNAME': 'cltbld', 18:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:31 INFO - 'MOZ_NO_REMOTE': '1', 18:42:31 INFO - 'NO_EM_RESTART': '1', 18:42:31 INFO - 'PAGER': '/bin/cat', 18:42:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:31 INFO - 'PWD': '/builds/slave/test', 18:42:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:31 INFO - 'SHELL': '/bin/bash', 18:42:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:42:31 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:42:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:31 INFO - 'USER': 'cltbld', 18:42:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:31 INFO - 'XPC_FLAGS': '0x0', 18:42:31 INFO - 'XPC_SERVICE_NAME': '0', 18:42:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:42:31 INFO - Cleaning up... 18:42:31 INFO - Return code: 0 18:42:31 INFO - Running post-action listener: _resource_record_post_action 18:42:31 INFO - Running post-action listener: _start_resource_monitoring 18:42:31 INFO - Starting resource monitoring. 18:42:31 INFO - [mozharness: 2016-08-27 01:42:31.348560Z] Finished create-virtualenv step (success) 18:42:31 INFO - [mozharness: 2016-08-27 01:42:31.349622Z] Running install step. 18:42:31 INFO - Running pre-action listener: _resource_record_pre_action 18:42:31 INFO - Running main action method: install 18:42:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:31 INFO - Reading from file tmpfile_stdout 18:42:31 INFO - Detecting whether we're running mozinstall >=1.0... 18:42:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:42:31 INFO - Reading from file tmpfile_stdout 18:42:31 INFO - Output received: 18:42:31 INFO - Usage: mozinstall [options] installer 18:42:31 INFO - Options: 18:42:31 INFO - -h, --help show this help message and exit 18:42:31 INFO - -d DEST, --destination=DEST 18:42:31 INFO - Directory to install application into. [default: 18:42:31 INFO - "/builds/slave/test"] 18:42:31 INFO - --app=APP Application being installed. [default: firefox] 18:42:31 INFO - mkdir: /builds/slave/test/build/application 18:42:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:43:06 INFO - Reading from file tmpfile_stdout 18:43:06 INFO - Output received: 18:43:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:43:06 INFO - Running post-action listener: _resource_record_post_action 18:43:06 INFO - [mozharness: 2016-08-27 01:43:06.517647Z] Finished install step (success) 18:43:06 INFO - [mozharness: 2016-08-27 01:43:06.517802Z] Running run-tests step. 18:43:06 INFO - Running pre-action listener: _resource_record_pre_action 18:43:06 INFO - Running main action method: run_tests 18:43:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:43:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:43:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:43:06 INFO - 2016-08-26 18:43:06.533 screenresolution[2262:285828] starting screenresolution argv=screenresolution get 18:43:06 INFO - 2016-08-26 18:43:06.536 screenresolution[2262:285828] Display 0: 1600x1200x32@60 18:43:06 INFO - 2016-08-26 18:43:06.541 screenresolution[2263:285830] starting screenresolution argv=screenresolution list 18:43:06 INFO - Available Modes on Display 0 18:43:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:43:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:43:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:43:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:43:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:43:06 INFO - Intel Iris: 18:43:06 INFO - Chipset Model: Intel Iris 18:43:06 INFO - Type: GPU 18:43:06 INFO - Bus: Built-In 18:43:06 INFO - VRAM (Dynamic, Max): 1536 MB 18:43:06 INFO - Vendor: Intel (0x8086) 18:43:06 INFO - Device ID: 0x0a2e 18:43:06 INFO - Revision ID: 0x0009 18:43:06 INFO - Displays: 18:43:06 INFO - Display: 18:43:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:43:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:43:06 INFO - Display Serial Number: 1600x1200 60 18:43:06 INFO - Main Display: Yes 18:43:06 INFO - Mirror: Off 18:43:06 INFO - Online: Yes 18:43:06 INFO - Rotation: Supported 18:43:06 INFO - Return code: 0 18:43:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:43:06 INFO - Python 2.7.3 18:43:06 INFO - Return code: 0 18:43:06 INFO - grabbing minidump binary from tooltool 18:43:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:43:06 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 0x106de5d30>, '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 0x106b4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dddb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:43:06 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 18:43:06 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 18:43:06 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 18:43:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:43:06 INFO - Return code: 0 18:43:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:43:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:43:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:43:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:43:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:43:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 18:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --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 18:43:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gIertc1aDo/Render', 18:43:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:43:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:43:06 INFO - 'HOME': '/Users/cltbld', 18:43:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:43:06 INFO - 'LOGNAME': 'cltbld', 18:43:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:43:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:43:06 INFO - 'MOZ_NO_REMOTE': '1', 18:43:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:43:06 INFO - 'NO_EM_RESTART': '1', 18:43:06 INFO - 'PAGER': '/bin/cat', 18:43:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:43:06 INFO - 'PWD': '/builds/slave/test', 18:43:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:43:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:43:06 INFO - 'SHELL': '/bin/bash', 18:43:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.und83cJxz7/Listeners', 18:43:06 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:43:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:43:06 INFO - 'USER': 'cltbld', 18:43:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:43:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:43:06 INFO - 'XPC_FLAGS': '0x0', 18:43:06 INFO - 'XPC_SERVICE_NAME': '0', 18:43:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:43:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 18:43:07 INFO - mozversion application_buildid: 20160826163012 18:43:07 INFO - mozversion application_changeset: c724ae8bb7b867b6bc65efa1049c0322457ff4b0 18:43:07 INFO - mozversion application_display_name: Nightly 18:43:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:43:07 INFO - mozversion application_name: Firefox 18:43:07 INFO - mozversion application_remotingname: firefox 18:43:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:43:07 INFO - mozversion application_vendor: Mozilla 18:43:07 INFO - mozversion application_version: 51.0a1 18:43:07 INFO - mozversion platform_buildid: 20160826163012 18:43:07 INFO - mozversion platform_changeset: c724ae8bb7b867b6bc65efa1049c0322457ff4b0 18:43:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:43:07 INFO - mozversion platform_version: 51.0a1 18:43:07 INFO - using testdate: 1472262187 18:43:07 INFO - actual date: 1472262187 18:43:07 INFO - starting webserver on 'localhost:52700' 18:43:07 INFO - SUITE-START | Running 2 tests 18:43:07 INFO - TEST-START | dromaeo_css 18:43:07 INFO - operating with platform_type : mac_ 18:43:07 INFO - Initialising browser for dromaeo_css test... 18:43:07 INFO - TEST-INFO | started process 2273 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpIuC9t_/profile http://localhost:52700/getInfo.html) 18:43:09 INFO - PROCESS | 2273 | __metrics Screen width/height:1600/1200 18:43:09 INFO - PROCESS | 2273 | colorDepth:24 18:43:09 INFO - PROCESS | 2273 | Browser inner width/height: 1024/689 18:43:09 INFO - PROCESS | 2273 | __metrics 18:43:09 INFO - PROCESS | 2273 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:43:09 INFO - PROCESS | 2273 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:43:10 INFO - TEST-INFO | 2273: exit 0 18:43:10 INFO - Browser initialized. 18:43:10 INFO - Running cycle 1/1 for dromaeo_css test... 18:43:10 INFO - TEST-INFO | started process 2276 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpIuC9t_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:22 INFO - PROCESS | 2276 | RSS: Main: 233152512 18:43:22 INFO - PROCESS | 2276 | 18:44:13 INFO - PROCESS | 2276 | 1472262253607 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] 18:46:25 INFO - PROCESS | 2276 | Cycle 1(1): loaded http://localhost:52700/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52700/tests/dromaeo/cssquery-ext.html) 18:46:26 INFO - PROCESS | 2276 | RSS: Main: 385167360 18:46:26 INFO - PROCESS | 2276 | 18:49:29 INFO - PROCESS | 2276 | Cycle 1(1): loaded http://localhost:52700/tests/dromaeo/cssquery-ext.html (next: http://localhost:52700/tests/dromaeo/cssquery-jquery.html) 18:49:30 INFO - PROCESS | 2276 | RSS: Main: 371875840 18:49:30 INFO - PROCESS | 2276 | 18:52:32 INFO - PROCESS | 2276 | Cycle 1(1): loaded http://localhost:52700/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52700/tests/dromaeo/cssquery-mootools.html) 18:52:33 INFO - PROCESS | 2276 | RSS: Main: 335351808 18:52:33 INFO - PROCESS | 2276 | 18:55:35 INFO - PROCESS | 2276 | Cycle 1(1): loaded http://localhost:52700/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52700/tests/dromaeo/cssquery-prototype.html) 18:55:36 INFO - PROCESS | 2276 | RSS: Main: 337924096 18:55:36 INFO - PROCESS | 2276 | 18:58:39 INFO - PROCESS | 2276 | Cycle 1(1): loaded http://localhost:52700/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52700/tests/dromaeo/cssquery-yui.html) 18:58:40 INFO - PROCESS | 2276 | RSS: Main: 332341248 18:58:40 INFO - PROCESS | 2276 | 19:01:43 INFO - PROCESS | 2276 | Cycle 1(1): loaded http://localhost:52700/tests/dromaeo/cssquery-yui.html (next: http://localhost:52700/tests/dromaeo/cssquery-dojo.html) 19:01:44 INFO - PROCESS | 2276 | RSS: Main: 385257472 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - PROCESS | 2276 | __start_tp_report 19:01:44 INFO - PROCESS | 2276 | _x_x_mozilla_page_load 19:01:44 INFO - PROCESS | 2276 | _x_x_mozilla_page_load_details 19:01:44 INFO - PROCESS | 2276 | |i|pagename|runs| 19:01:44 INFO - PROCESS | 2276 | |0;dojo.html;3291.094948889055;3315.270640459099;3334.4664853623426;3376.1222082258605;3407.3015031918444;5596;5623.268975033245;5680.261565996425;5733.111367737997;5733.340665823432;5654.943450565494;5669.319681638199;5744.281964754406;5774.538036957044;5828.358880523139;5148.588112950955;5184.326037615105;5201.271821944912;5208.348956380452;5336.573074154058;5353.892922141555;5358.169483730028;5363;5368.516833484986;5403.7027963462;5144.176931690949;5159.5614372778355;5204.245384419238;5267.5259226669405;5272.604554658416;5239.528442440199;5261.97391508656;5263.34208223972;5270.262163297141;5275.155975043975;5181.404138524081;5217.3217481727515;5226.5818734501145;5230.032443997872;5256.9474305256845;5601.887962240732;5696.088625819891;5713.942860571383;5799.449052340063;5799.623024503394;6154.815355539341;6213.285472170713;6220.968895155541;6265.780697675559;6289.811305660837;4956.752162391866;6255.468285195764;6261.217347831521;6287.842803929892;6290.779822706183;5680.289963754531;5688.91466628005;5797.797077102366;5854.912176317359;5857.179994800731;6213.844653883689;6216.098665693449;6232.3144454110015;6233.158523599347;6236.469900058455;6166.475849552839;6187.597806142587;6190.81427557165;6195.473384762256;6214.9689251553455;6078.646092583374;6152.661603611844;6184.134221209034;6201.286852011986;6260.342664020212;6205.441510264008;6245.094461303085;6247.437730604177;6258.749650013949;6262.937370626236;6236.719347629277;6248.593841400385;6261.530385221126;6270.372962703785;6273.7176827042895;4887.486813884562;4935.777889994959;4936.531029552187;4965.47862474435;4992.575631071327;3959.524857017119;4369.235383807821;4460.197164510354;4465.464144302639;4470.754108523997;5637.492625663628;5658.377578466366;5660.377358490563;5677.261955945701;5678.829635110953;6261.78083767066;6269.467095296951;6270.874582508324;6281.748730050838;6291.653959032297;5728.885422291531;5740.913886291709;5751.59738818287;5755.280589926259;5784.94215057844;5352.9732351338;5395.676259424447;5457.20870473787;5465.6447330923365;5470.890582188414;6160.661163635953;6236.126942228092;6249.406306400884;6266.154069200599;6266.467350275278;6263.091851681572;6267.9373206267355;6274.937250627435;6287.339829317856;6298.7480500781285;6154;6195.907061394173;6232.906506402317;6250.3749625037135;6261.123442717932;6099.634021958696;6156.5998210116195;6200.844978875564;6201.627902325875;6205.007198848162;6264.2796078451565;6274.560780745304;6278.6860656966055;6282.120503129474;6288.245410550763;5905.084711869667;6033.366496517802;6069.241344831896;6095.603785753912;6107.572469927062;6013.6692481913915;6108.969455152695;6111.561351782825;6117.401149625394;6130.367562715148;5901.114832775118;5919.526437884872;5922.674252916131;5943.52451803846;5949.137375080502;4708.411448568929;5983.072623743326;5993.550483713651;6094.786682466092;6118.479929206067;5844.094165404369;5924.585279030599;5926.022206335906;5961.016432288798;5984.072468767348 19:01:44 INFO - PROCESS | 2276 | |1;ext.html;485.72071059140995;487.865836894854;506.03852679908147;531.0626743797199;532.2495281652871;29512.26219344515;40867.77396678101;42183.945401364945;42187.789061054726;44168.55831441685;42942.14115717684;43471.56528434715;43487.34768978468;43633.7818310908;43689.7815510922;9624.663136790232;9915.157211637019;9915.603375864957;9924.950375248114;9926.801463970753;8812.427192232517;9058.954705226464;11872.347020913847;12090.972267357285;12150.149489535735;11861.584844530444;11892.405379731023;11900.285982841015;11985.220960637575;11988.820167697491;8708.042115367305;12040.879591204108;12094.939525302318;12120.757584848341;12125.969292610138;10120.645777397829;11766;12112.454939527743;12119.151659383846;12401.193922395105;40860;41253.79373103145;41260.968475788344;41262.587374126175;41318.793406032775;3392.9321413571593;3399.1162297802503;3418.7094097001786;3459.2043829919007;3555.928881422383;28095.438091238062;28766.71233287682;30900.918467853517;31673;31805.045848624348;3134.4044631520164;3138;3277.4428347181038;3315.336932613463;3325.6840600142946;346945.5305446939;347636;347986;349896;350059.2497037524;60854.0871886922;61307.693461532406;61330.69334653298;61399.69300153471;61502.4624384385;64266;64366.03450948239;64378.678106609164;64424.35575644184;64455.35544644587;16489.83510164907;16745.748813767805;16783.412580559623;16793.99236045841;16807.32770689159;11565.595204167814;11795.882041179479;12990.285534295515;13001.349932503337;13004.479820807252;14438.783418248735;14655.48705795292;14764;14868.884833637521;14926.253687315591;6220.62676239418;6258.687065646699;6262.624242545462;6348.682565871688;6365.936340636627;747.6710247491195;928.6563971330651;941.2375975459762;948.2793077261193;1138.6356365962813;29473.705262947096;29771.809127634653;30623.08130756081;30712.69287307143;30838.845805770827;860.350435421253;880.6961598248594;922.5986695787354;993.5181437003048;1027.465717826726;15626.687466250838;15664.451744189126;15693.686126277411;15783.763243551355;15923.203839807964;652011;654064;655774;659259;662679;55900.44099559034;55974.600634984454;56011.719941400035;56035;56149.71925140348;1229.6065259117177;1354.3363751761565;1393.4078016842802;1405.4729476446332;1414.554415359179;30447.25974662291;31435.685643144192;31498.37003259968;31521.369572608877;32397.67602324041;50359.49640503548;50942.471725848285;50994.74502627463;51033.72415689489;51054;545491.2725436348;547753;549574;549643;550141;21771.78228217761;25164.370890727878;25873.482530349665;26098;26344.473110538067;46509.83725406756;46801.29798052964;47057.29414058723;47237;47656;36996.630033699315;37063.81468092642;37242.44136337903;37926.24147517089;38026.619733802305;35247;37151.071223218554;37563.99063454929;38315.04212394601;38846.61153388543;36988.63011369852;37442;38457;38465.80767096147;38552.42171367488;32427;33589;33867.62697131504;34301;34427.4835877457;22352.77647223507;24842.37894052591;25042.499150016534;25204.873975630737;25670.486590267716 19:01:44 INFO - PROCESS | 2276 | |2;jquery.html;297.12200447677117;346.93928562501554;360.18597968590996;375.2513735098477;402.91337362467084;14884.553463396089;15865.682686346267;20605.896970515125;20860.165593376267;21385.358439246807;20437.978101094923;20860.478488037807;21462.89268553655;21546.922653867285;21752.347429577098;5925.614835035731;6059.182010428602;6278.4663303619245;6337.46131578814;6338.683065846712;4702.29465580164;6246.281677607065;6246.344133865947;6255.40573645503;6262.342454042328;5519.9448005519835;5855.975204339224;5870.501007414375;6201.317855035943;6212.78255261066;6100.267967843865;6181.752729890822;6185.288691800455;6187.721552530147;6189.845253868644;5103.209002604603;5374.731263436813;5376.838694839161;5380.838574842761;5381.48875856793;26337.3415664608;26395.73604263952;26449.73550264492;26466.867665661743;26836.329091772666;2177.923772667965;2232.229880691159;2271.6138256496433;2434.805215582749;2972.70272972701;15041.774373384407;15787.684246315124;15878.52364429069;16040.43858464948;16457.753133703005;1805.6840052990674;1840.2363019346956;1869.1962456143854;1879.6522643311032;1882.7458293130526;93823.53088234515;129152.35423822915;130610;131776;132276.3386183063;9695.51522423876;10740.892591074145;11061.059809916247;11199.248251835563;11356.886431135583;11614.361210133524;13969.022168448315;13975.37110830015;13993.510227142206;14013.159210447406;8360.874586881202;8589;8599.441036332619;8756.168164024408;8760.824783504295;8652.78368040804;8714.085021072697;8716.956415217883;8909.777255568662;8943.284537237005;5569.331680198402;6603.70283337251;6747.797566073025;6765.255821859591;6845.486588505882;1672.4313733330725;1683.5791052236941;1764.7176451767655;1809.0592891696244;1817.6637322095455;925.5511077127587;936.5551363102472;945.432740355781;990.6631745206664;1064.7436025489842;20216.09027593762;21209.469763256042;22295.219667311892;22369;22473.6515809052;865.4028720183179;895.8029233568604;919.347263442963;954.3605784124653;1002.063071028576;10937.796652510617;11230.213885028134;11341;11356.716082098013;11357.261777984404;608743.9562802158;609285;615154;624573;625463;83157;134688.32655836854;136885.31557342148;137248;140459;891.3671293381642;985.8619793228954;997.5760301871676;1019.6889948565611;1020.2705065877933;19157.233710651977;19220.519487012938;19327.907534794365;19487.8051219486;19528.609427811647;41581.752547423624;53717.73141134269;55330.34008979737;56702;57501;59378.70310648592;75924.86112708424;79841.80237296328;80324.3935121306;81516.95728683383;7725.420593455401;7874.960625196837;8001.839963200587;8394.874076888973;8447.535385553854;9248.213901818151;12540.435680394587;13134.60596182116;13407.463701451832;13560.389782459615;10144.746381340525;11307.151963602928;11745.823812642646;11750.35373054456;11867.76264474723;11323.263987840764;11337.943310283395;11507.136964727511;11743.236689615147;11790.94104529472;11499.195056345976;11580.594679186188;11920.463579139134;11933.880661193623;12016.519339226334;7975.5613441261285;8147.9185208147155;8150.83698326042;8304.626291817003;8492.108328625405;8418.494890306602;10971.451427428756;11560.930511481203;11742.88257117418;11910.821337680116 19:01:44 INFO - PROCESS | 2276 | |3;mootools.html;1335.4257669202239;1397.7484052870482;1490.9254537273137;1673.2386764022372;1879.0322983663416;7747.767566972992;8430.36772242082;8664.913350866505;8673.73978780636;8755.080716524768;8594.312455003601;8683.088275731036;8694.478331300126;8699.39104262702;8701.7824554386;2350.976490235097;2372.1578839511985;2377.203636781672;2381.7261014983237;2382.9880850595723;2313.5835549601065;2354.693889794324;2371.2886134159685;2374.2283757778705;2374.489484760776;2333.4866329407505;2352.200251914349;2358.6933698619155;2368.6802281692007;2369.206315884181;2295.2081531871477;2332.941676458085;2336.9883150584224;2338.7137074608895;2342.1685301717985;2327.150590034642;2331.0792237066435;2335.2760644200184;2337.696099507053;2340.1224540797202;8593.914060859373;8669.609867555975;8735.039145693969;8791.64833406663;8829.646814127427;5157.381114266274;5342.091844386464;5362.490563396471;5362.973185134049;5388.110961691316;3536.1159710072484;3557.4130268505664;3608.1520842601967;3625.492431059654;3632.20091579852;1073.9892601073968;1082.8050950828847;1085.3921803789904;1096.8683757949018;1105.171121658756;11373.976342129248;11452.026577740819;11506.712332191679;11523.135764817589;11539.826902596384;3997.200559888034;4310.612044915973;4407.2507673695545;4573.794179261941;4576.107659006462;8827.470351778913;9041.68354107603;9052.140046695553;9052.411593246417;9056.683016094405;5186.844394668166;5188.948110518922;5196.662216955886;5197.830488140138;5202.3497062867145;4378.343248512685;4541.409616749801;4702.694324868872;4831.879203019882;4879.487653796371;3625.836837342326;3673.6326367363586;3772.6981841452616;3790.7915064671156;3805.923881522354;1133.631569739838;1143.1712008793559;1146.306484576819;1154.7286387698884;1158.901493373056;888.9333300002526;891.7815135291895;906.959186836594;925.227435091706;941.3692825806726;5456.727163641802;6036.6679832609625;6083.11794789753;6142.877142457126;6247.125402443661;810.4691427115249;916.2166347772692;943.6272672294406;953.1088432315806;971.9562619682131;3349.4305967985015;3764.6235376462682;4036.2936486114813;4087.1621317629815;4118.423420721101;8929.33030022751;10167.898321016843;10723.30298530593;10739;10754.677359679223;10228.181745460346;10657.626983055556;10672.359658420519;10690;10693.625723099654;7772.961135194401;8122.75631731049;8148.83702325962;8164.387670924704;8167.42828002046;5743.712814359349;5840.649561026352;5920;5964.493018093511;5972.313183983897;9749.902500975184;9809.264305177283;9862.753431163992;10031.946645602107;10080.04239597237;9499.810003800023;9926.503674816375;9934.602615895283;10120.797584048425;10142.797144057226;6928.7228510859;7110.7511237106455;7132.928670713434;7426.517276377018;7608.771736847903;7810.687572497264;7896.131425543071;7897.605119743875;8106.310963568163;8237;7318.231585683427;7453.552786832807;7459.179490256176;7551.773446796605;7596.734114305973;7232.940451275815;7278.550475924343;7290.088738907637;7363.852722945619;7453.291937265951;6486.286508483969;6647.335266473314;6653.767118150841;6656.30108838565;6658.234303055211;4761.880952976203;4809.495003024632;4821.879453013703;4849.351220585018;4940.456549779449;4078.490188726409;4169.645580125723;4747.335373047707;4869.829555965524;4871.123197824284 19:01:44 INFO - PROCESS | 2276 | |4;prototype.html;298.42404159971255;303.1043267145585;306.19318096814885;311.95632611434405;314.5753233135266;4518.525554816742;4823.866391398019;5213.322268105146;5227.3465816772905;5275.784170648328;4429.756363400012;4771.9761401192945;4816.951830481694;4932.076490168837;5071.74641267936;3521.7182625390037;3594.856205751767;3710.9443358349645;3802.315583195017;3809.657130858222;3991.8086009689855;3997.7401468904573;3998.1403998140395;4008.3386241270155;4016.9112061727883;3287.1289108386295;3889.0083028827607;3969.702272329572;4005.939910901339;4047.919041619166;3327.7337812975147;3569.7501174917766;3590.7666001709936;3918.960810391888;4019.376996565537;2970.866311015988;3034.2691577580135;3457.4986626939203;3492.6158122606494;3495.4582039783872;5025.673331233458;5040.521150490697;5044.520770526794;5062.519060689266;5071.199724097966;4379.5182529921685;4500.572445617661;4532.569405906466;4547.7271363717955;4556.54434556545;5085.694858308476;5212.628254144393;5470.398256191856;5486.91769623456;5502.972485137589;3838.5777564467885;3847.576766555677;3847.7498962567624;3850.4224366344993;3867.5552311484257;4867.659263851568;5336.779760661175;5662.178984047372;5744.913826292609;5755.338136114358;3442.604100528421;3891.5330160380936;3901.395283731026;3909.5308562972627;3923.2733250390706;3940.310445671996;3945.723799334019;3946.4080387941463;3956.485656864647;3958.7332053743103;4529.750863702462;4544.931826022612;4564.612007979293;4608.907821843612;4612.238980568169;4557.44782921565;5195.376554813371;5609.495145436848;5617.9719101404235;5628.943710562842;1121.5738019552518;1124.1231839165464;1321.5242512695422;1325.707435250623;1346.596021193638;681.6567138385902;687.9484038697018;810.0926961802819;816.2572059425894;817.5013241922422;4487.977560112178;4645.5354464553275;4662.829276092222;4744.762761861962;5075.796968121308;5652.506257525277;5696.572757043155;5697.658140511582;5715.142578661951;5750.159948816338;4716.867951691638;5199.29809475716;5215.852512447255;5221.295125158131;5262.289590905255;2762.502749505068;2778.4440713200675;2939.955900661492;2980.478416277143;2981.821090734563;5810.273715785527;5867.53311672082;5885.588008839341;5896.469317761422;5947.1079338099635;5618.943810561924;5730.650433322317;5758.913616295759;5815.244018277597;5818.912716309259;5821.767129314865;5825.941740582624;5841.4450627190345;5851.941480585225;5854.385289544622;4443.533428989909;4609.24848557546;5465.535429488538;5627.352854421794;5682.897327988505;5527.5577953762795;5541.667499950016;5571.913476871971;5585.5702769295485;5629.831105066854;5377.758000889891;5795.275590551181;5804.73878675469;5826.504747096545;5878.470937615635;3737.6449237322413;3875.4961854958674;3909.6285852843935;3910.3352430086384;3922.4312474691583;5467.289252397163;5507.807226747044;5518.420565840529;5568.359638641608;5574.247476590771;4708.81164753406;4758.619310455086;5364.865878353073;5378.435264297193;5390.97304513475;4467.642588592969;4965.82619608312;5132.79468821243;5221.4517475666025;5341.439148889311;5239.816606418757;5251.894962100813;5302.70835104073;5311.9468805313;5330.360356757214;4856.660033797742;5162.948370516397;5169.302144210484;5332.520378024237;5395.433479484597;4481.132075471635;5082.110630639697;5269.78920843162;5317.8404647860625;5326.9200961984825 19:01:44 INFO - PROCESS | 2276 | |5;yui.html;683.1051322767175;708.7094291340551;736.3740820302743;750.6884642873208;753.1602263476225;13222.20666759994;13480.595582132528;13488.123271987313;13494;13532;14058.507952221675;14147.929260353683;14157.575272741808;14167.008309418345;14591.708165836677;655.3708439897704;676.1987045351275;680.0479328939499;841.2008591837748;866.5970323799432;671.9596824190539;674.2886255000972;834.3742193354984;856.7601071699934;861.9267362274214;744.2668971063498;880.5069161269678;892.2460520859863;892.2727976699002;893.053363434758;887.4453466583385;887.622760326865;888.1606881496923;888.8044630181349;889.4174315823146;517.3791450259704;532.4223217808682;657.5594351784268;658.2989116590863;659.1562799616498;12380;12504.062195335295;14338.784918226234;14863;14874.033187842755;13945.58163255104;14498.42006319746;14522.927385363006;14568.344424500923;14602.34289456977;11546.480408381643;11641;11698.824517632242;11977.580784672582;12079.396030198543;3745.438184272381;3774.2640185163905;3804.4483549885113;3819.866304679351;3925.509311336083;81803;109684;114304;114648;114863.42568287105;10652.573897044187;10879.184061195441;10932.180086493387;10970.835437468444;11170.776584468427;23321.95051222699;23341.649875251886;23369.41576460568;23377.532449350918;23815;7681.654325555363;7864.567448790257;7975.5613441261285;8025.598720063974;8125.1062383137805;4244.172386384687;4347.891302717458;4397.846075387346;4523.683342166082;4524.751138687404;3528.5412896323314;3653.8903832885057;3660.7071434285194;3660.853565857389;3661.9633803661623;624.5191202773839;626.7962912053509;636.2524034259726;648.536296547961;651.0397164182792;586.3667239381458;618.9597676151036;625.6183727925961;642.3351830855042;643.3405759096963;8595.355348348774;8938.240249578728;9078.364514483923;9079.050612983374;9291.256699464027;578.6614830324307;590.4971846650285;633.8288662061218;649.2436311696914;657.139147716493;4216.536181020086;4395.582419670126;4397.846075387346;4410.117976404732;4486.925122018996;96663.51668241614;102343;110022.44988775004;159329.20335398248;165968.34031659528;110923;116094.41952790182;129517.35241323922;131850.68149318575;134048.65012001566;102330;104780.47609762054;117464;123141.38429307796;127188;8251.917480825115;8532.488050716976;8544.871826922477;8709.651613935372;8790.560471976247;52402.95194096021;58588;63458;66185;68822.9676554842;97914;100288.49855750674;102639;104702;106854;11946.283223006647;12073.57742479009;12091.153619246568;12318.938405307916;12391.504339826306;12574.49702011946;12875.935620322212;12946.67633309137;13251.13867598603;13260.138091024053;10005.499725013866;11688;12304.540866807003;12426.502939882303;12546.309952952675;11251.606193783506;12066.879331206575;12277.140600157903;12429.37853107323;12517.874821251671;11851.111166662362;12044.397780111134;12051.939740301243;12163.209391389531;12423.31671758026;8519.701810436605;10247.180225582084;10306;10361.4421737459;10423.530941107516;10172.542235599563;10293.176545876462;10310.175185985254;10313.432761198206;10327.48362581883 19:01:44 INFO - PROCESS | 2276 | __end_tp_report 19:01:44 INFO - PROCESS | 2276 | __start_cc_report 19:01:44 INFO - PROCESS | 2276 | _x_x_mozilla_cycle_collect,1426 19:01:44 INFO - PROCESS | 2276 | __end_cc_report 19:01:44 INFO - PROCESS | 2276 | __startTimestamp1472263304124__endTimestamp 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - PROCESS | 2276 | ------- Summary: start ------- 19:01:44 INFO - PROCESS | 2276 | Number of tests: 6 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - PROCESS | 2276 | [#0] dojo.html Cycles:165 Average:5734.18 Median:5924.59 stddev:642.90 (10.9%) stddev-sans-first:615.64 19:01:44 INFO - PROCESS | 2276 | Values: 3291.1 3315.3 3334.5 3376.1 3407.3 5596.0 5623.3 5680.3 5733.1 5733.3 5654.9 5669.3 5744.3 5774.5 5828.4 5148.6 5184.3 5201.3 5208.3 5336.6 5353.9 5358.2 5363.0 5368.5 5403.7 5144.2 5159.6 5204.2 5267.5 5272.6 5239.5 5262.0 5263.3 5270.3 5275.2 5181.4 5217.3 5226.6 5230.0 5256.9 5601.9 5696.1 5713.9 5799.4 5799.6 6154.8 6213.3 6221.0 6265.8 6289.8 4956.8 6255.5 6261.2 6287.8 6290.8 5680.3 5688.9 5797.8 5854.9 5857.2 6213.8 6216.1 6232.3 6233.2 6236.5 6166.5 6187.6 6190.8 6195.5 6215.0 6078.6 6152.7 6184.1 6201.3 6260.3 6205.4 6245.1 6247.4 6258.7 6262.9 6236.7 6248.6 6261.5 6270.4 6273.7 4887.5 4935.8 4936.5 4965.5 4992.6 3959.5 4369.2 4460.2 4465.5 4470.8 5637.5 5658.4 5660.4 5677.3 5678.8 6261.8 6269.5 6270.9 6281.7 6291.7 5728.9 5740.9 5751.6 5755.3 5784.9 5353.0 5395.7 5457.2 5465.6 5470.9 6160.7 6236.1 6249.4 6266.2 6266.5 6263.1 6267.9 6274.9 6287.3 6298.7 6154.0 6195.9 6232.9 6250.4 6261.1 6099.6 6156.6 6200.8 6201.6 6205.0 6264.3 6274.6 6278.7 6282.1 6288.2 5905.1 6033.4 6069.2 6095.6 6107.6 6013.7 6109.0 6111.6 6117.4 6130.4 5901.1 5919.5 5922.7 5943.5 5949.1 4708.4 5983.1 5993.6 6094.8 6118.5 5844.1 5924.6 5926.0 5961.0 5984.1 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - PROCESS | 2276 | [#1] ext.html Cycles:180 Average:66059.42 Median:20272.28 stddev:142665.98 (703.7%) stddev-sans-first:142981.24 19:01:44 INFO - PROCESS | 2276 | Values: 485.7 487.9 506.0 531.1 532.2 29512.3 40867.8 42183.9 42187.8 44168.6 42942.1 43471.6 43487.3 43633.8 43689.8 9624.7 9915.2 9915.6 9925.0 9926.8 8812.4 9059.0 11872.3 12091.0 12150.1 11861.6 11892.4 11900.3 11985.2 11988.8 8708.0 12040.9 12094.9 12120.8 12126.0 10120.6 11766.0 12112.5 12119.2 12401.2 40860.0 41253.8 41261.0 41262.6 41318.8 3392.9 3399.1 3418.7 3459.2 3555.9 28095.4 28766.7 30900.9 31673.0 31805.0 3134.4 3138.0 3277.4 3315.3 3325.7 346945.5 347636.0 347986.0 349896.0 350059.2 60854.1 61307.7 61330.7 61399.7 61502.5 64266.0 64366.0 64378.7 64424.4 64455.4 16489.8 16745.7 16783.4 16794.0 16807.3 11565.6 11795.9 12990.3 13001.3 13004.5 14438.8 14655.5 14764.0 14868.9 14926.3 6220.6 6258.7 6262.6 6348.7 6365.9 747.7 928.7 941.2 948.3 1138.6 29473.7 29771.8 30623.1 30712.7 30838.8 860.4 880.7 922.6 993.5 1027.5 15626.7 15664.5 15693.7 15783.8 15923.2 652011.0 654064.0 655774.0 659259.0 662679.0 55900.4 55974.6 56011.7 56035.0 56149.7 1229.6 1354.3 1393.4 1405.5 1414.6 30447.3 31435.7 31498.4 31521.4 32397.7 50359.5 50942.5 50994.7 51033.7 51054.0 545491.3 547753.0 549574.0 549643.0 550141.0 21771.8 25164.4 25873.5 26098.0 26344.5 46509.8 46801.3 47057.3 47237.0 47656.0 36996.6 37063.8 37242.4 37926.2 38026.6 35247.0 37151.1 37564.0 38315.0 38846.6 36988.6 37442.0 38457.0 38465.8 38552.4 32427.0 33589.0 33867.6 34301.0 34427.5 22352.8 24842.4 25042.5 25204.9 25670.5 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - PROCESS | 2276 | [#2] jquery.html Cycles:180 Average:35859.43 Median:26458.30 stddev:102862.04 (388.8%) stddev-sans-first:103115.94 19:01:44 INFO - PROCESS | 2276 | Values: 297.1 346.9 360.2 375.3 402.9 14884.6 15865.7 20605.9 20860.2 21385.4 20438.0 20860.5 21462.9 21546.9 21752.3 5925.6 6059.2 6278.5 6337.5 6338.7 4702.3 6246.3 6246.3 6255.4 6262.3 5519.9 5856.0 5870.5 6201.3 6212.8 6100.3 6181.8 6185.3 6187.7 6189.8 5103.2 5374.7 5376.8 5380.8 5381.5 26337.3 26395.7 26449.7 26466.9 26836.3 2177.9 2232.2 2271.6 2434.8 2972.7 15041.8 15787.7 15878.5 16040.4 16457.8 1805.7 1840.2 1869.2 1879.7 1882.7 93823.5 129152.4 130610.0 131776.0 132276.3 9695.5 10740.9 11061.1 11199.2 11356.9 11614.4 13969.0 13975.4 13993.5 14013.2 8360.9 8589.0 8599.4 8756.2 8760.8 8652.8 8714.1 8717.0 8909.8 8943.3 5569.3 6603.7 6747.8 6765.3 6845.5 1672.4 1683.6 1764.7 1809.1 1817.7 925.6 936.6 945.4 990.7 1064.7 20216.1 21209.5 22295.2 22369.0 22473.7 865.4 895.8 919.3 954.4 1002.1 10937.8 11230.2 11341.0 11356.7 11357.3 608744.0 609285.0 615154.0 624573.0 625463.0 83157.0 134688.3 136885.3 137248.0 140459.0 891.4 985.9 997.6 1019.7 1020.3 19157.2 19220.5 19327.9 19487.8 19528.6 41581.8 53717.7 55330.3 56702.0 57501.0 59378.7 75924.9 79841.8 80324.4 81517.0 7725.4 7875.0 8001.8 8394.9 8447.5 9248.2 12540.4 13134.6 13407.5 13560.4 10144.7 11307.2 11745.8 11750.4 11867.8 11323.3 11337.9 11507.1 11743.2 11790.9 11499.2 11580.6 11920.5 11933.9 12016.5 7975.6 8147.9 8150.8 8304.6 8492.1 8418.5 10971.5 11560.9 11742.9 11910.8 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - PROCESS | 2276 | [#3] mootools.html Cycles:180 Average:5587.18 Median:4875.31 stddev:3122.58 (64.0%) stddev-sans-first:3114.99 19:01:44 INFO - PROCESS | 2276 | Values: 1335.4 1397.7 1490.9 1673.2 1879.0 7747.8 8430.4 8664.9 8673.7 8755.1 8594.3 8683.1 8694.5 8699.4 8701.8 2351.0 2372.2 2377.2 2381.7 2383.0 2313.6 2354.7 2371.3 2374.2 2374.5 2333.5 2352.2 2358.7 2368.7 2369.2 2295.2 2332.9 2337.0 2338.7 2342.2 2327.2 2331.1 2335.3 2337.7 2340.1 8593.9 8669.6 8735.0 8791.6 8829.6 5157.4 5342.1 5362.5 5363.0 5388.1 3536.1 3557.4 3608.2 3625.5 3632.2 1074.0 1082.8 1085.4 1096.9 1105.2 11374.0 11452.0 11506.7 11523.1 11539.8 3997.2 4310.6 4407.3 4573.8 4576.1 8827.5 9041.7 9052.1 9052.4 9056.7 5186.8 5188.9 5196.7 5197.8 5202.3 4378.3 4541.4 4702.7 4831.9 4879.5 3625.8 3673.6 3772.7 3790.8 3805.9 1133.6 1143.2 1146.3 1154.7 1158.9 888.9 891.8 907.0 925.2 941.4 5456.7 6036.7 6083.1 6142.9 6247.1 810.5 916.2 943.6 953.1 972.0 3349.4 3764.6 4036.3 4087.2 4118.4 8929.3 10167.9 10723.3 10739.0 10754.7 10228.2 10657.6 10672.4 10690.0 10693.6 7773.0 8122.8 8148.8 8164.4 8167.4 5743.7 5840.6 5920.0 5964.5 5972.3 9749.9 9809.3 9862.8 10031.9 10080.0 9499.8 9926.5 9934.6 10120.8 10142.8 6928.7 7110.8 7132.9 7426.5 7608.8 7810.7 7896.1 7897.6 8106.3 8237.0 7318.2 7453.6 7459.2 7551.8 7596.7 7232.9 7278.6 7290.1 7363.9 7453.3 6486.3 6647.3 6653.8 6656.3 6658.2 4761.9 4809.5 4821.9 4849.4 4940.5 4078.5 4169.6 4747.3 4869.8 4871.1 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - PROCESS | 2276 | [#4] prototype.html Cycles:180 Average:4444.87 Median:4995.75 stddev:1373.86 (27.5%) stddev-sans-first:1342.00 19:01:44 INFO - PROCESS | 2276 | Values: 298.4 303.1 306.2 312.0 314.6 4518.5 4823.9 5213.3 5227.3 5275.8 4429.8 4772.0 4817.0 4932.1 5071.7 3521.7 3594.9 3710.9 3802.3 3809.7 3991.8 3997.7 3998.1 4008.3 4016.9 3287.1 3889.0 3969.7 4005.9 4047.9 3327.7 3569.8 3590.8 3919.0 4019.4 2970.9 3034.3 3457.5 3492.6 3495.5 5025.7 5040.5 5044.5 5062.5 5071.2 4379.5 4500.6 4532.6 4547.7 4556.5 5085.7 5212.6 5470.4 5486.9 5503.0 3838.6 3847.6 3847.7 3850.4 3867.6 4867.7 5336.8 5662.2 5744.9 5755.3 3442.6 3891.5 3901.4 3909.5 3923.3 3940.3 3945.7 3946.4 3956.5 3958.7 4529.8 4544.9 4564.6 4608.9 4612.2 4557.4 5195.4 5609.5 5618.0 5628.9 1121.6 1124.1 1321.5 1325.7 1346.6 681.7 687.9 810.1 816.3 817.5 4488.0 4645.5 4662.8 4744.8 5075.8 5652.5 5696.6 5697.7 5715.1 5750.2 4716.9 5199.3 5215.9 5221.3 5262.3 2762.5 2778.4 2940.0 2980.5 2981.8 5810.3 5867.5 5885.6 5896.5 5947.1 5618.9 5730.7 5758.9 5815.2 5818.9 5821.8 5825.9 5841.4 5851.9 5854.4 4443.5 4609.2 5465.5 5627.4 5682.9 5527.6 5541.7 5571.9 5585.6 5629.8 5377.8 5795.3 5804.7 5826.5 5878.5 3737.6 3875.5 3909.6 3910.3 3922.4 5467.3 5507.8 5518.4 5568.4 5574.2 4708.8 4758.6 5364.9 5378.4 5391.0 4467.6 4965.8 5132.8 5221.5 5341.4 5239.8 5251.9 5302.7 5311.9 5330.4 4856.7 5162.9 5169.3 5332.5 5395.4 4481.1 5082.1 5269.8 5317.8 5326.9 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - PROCESS | 2276 | [#5] yui.html Cycles:180 Average:24151.01 Median:13671.77 stddev:38733.28 (283.3%) stddev-sans-first:38801.86 19:01:44 INFO - PROCESS | 2276 | Values: 683.1 708.7 736.4 750.7 753.2 13222.2 13480.6 13488.1 13494.0 13532.0 14058.5 14147.9 14157.6 14167.0 14591.7 655.4 676.2 680.0 841.2 866.6 672.0 674.3 834.4 856.8 861.9 744.3 880.5 892.2 892.3 893.1 887.4 887.6 888.2 888.8 889.4 517.4 532.4 657.6 658.3 659.2 12380.0 12504.1 14338.8 14863.0 14874.0 13945.6 14498.4 14522.9 14568.3 14602.3 11546.5 11641.0 11698.8 11977.6 12079.4 3745.4 3774.3 3804.4 3819.9 3925.5 81803.0 109684.0 114304.0 114648.0 114863.4 10652.6 10879.2 10932.2 10970.8 11170.8 23322.0 23341.6 23369.4 23377.5 23815.0 7681.7 7864.6 7975.6 8025.6 8125.1 4244.2 4347.9 4397.8 4523.7 4524.8 3528.5 3653.9 3660.7 3660.9 3662.0 624.5 626.8 636.3 648.5 651.0 586.4 619.0 625.6 642.3 643.3 8595.4 8938.2 9078.4 9079.1 9291.3 578.7 590.5 633.8 649.2 657.1 4216.5 4395.6 4397.8 4410.1 4486.9 96663.5 102343.0 110022.4 159329.2 165968.3 110923.0 116094.4 129517.4 131850.7 134048.7 102330.0 104780.5 117464.0 123141.4 127188.0 8251.9 8532.5 8544.9 8709.7 8790.6 52403.0 58588.0 63458.0 66185.0 68823.0 97914.0 100288.5 102639.0 104702.0 106854.0 11946.3 12073.6 12091.2 12318.9 12391.5 12574.5 12875.9 12946.7 13251.1 13260.1 10005.5 11688.0 12304.5 12426.5 12546.3 11251.6 12066.9 12277.1 12429.4 12517.9 11851.1 12044.4 12051.9 12163.2 12423.3 8519.7 10247.2 10306.0 10361.4 10423.5 10172.5 10293.2 10310.2 10313.4 10327.5 19:01:44 INFO - PROCESS | 2276 | -------- Summary: end -------- 19:01:44 INFO - PROCESS | 2276 | 19:01:44 INFO - TEST-INFO | 2276: exit 0 19:01:44 INFO - TEST-OK | dromaeo_css | took 1117609ms 19:01:44 INFO - TEST-START | kraken 19:01:44 INFO - operating with platform_type : mac_ 19:01:44 INFO - Initialising browser for kraken test... 19:01:44 INFO - TEST-INFO | started process 2279 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpHuU1lN/profile http://localhost:52700/getInfo.html) 19:01:47 INFO - PROCESS | 2279 | __metrics Screen width/height:1600/1200 19:01:47 INFO - PROCESS | 2279 | colorDepth:24 19:01:47 INFO - PROCESS | 2279 | Browser inner width/height: 1024/689 19:01:47 INFO - PROCESS | 2279 | __metrics 19:01:47 INFO - PROCESS | 2279 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:01:47 INFO - PROCESS | 2279 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:01:47 INFO - TEST-INFO | 2279: exit 0 19:01:47 INFO - Browser initialized. 19:01:47 INFO - Running cycle 1/1 for kraken test... 19:01:47 INFO - TEST-INFO | started process 2280 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpHuU1lN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:59 INFO - PROCESS | 2280 | RSS: Main: 233123840 19:01:59 INFO - PROCESS | 2280 | 19:02:02 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52700/tests/kraken/driver.html?testName=audio-beat-detection) 19:02:03 INFO - PROCESS | 2280 | RSS: Main: 291471360 19:02:03 INFO - PROCESS | 2280 | 19:02:06 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52700/tests/kraken/driver.html?testName=audio-dft) 19:02:07 INFO - PROCESS | 2280 | RSS: Main: 287313920 19:02:07 INFO - PROCESS | 2280 | 19:02:10 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52700/tests/kraken/driver.html?testName=audio-fft) 19:02:11 INFO - PROCESS | 2280 | RSS: Main: 379617280 19:02:11 INFO - PROCESS | 2280 | 19:02:13 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52700/tests/kraken/driver.html?testName=audio-oscillator) 19:02:13 INFO - PROCESS | 2280 | RSS: Main: 280477696 19:02:13 INFO - PROCESS | 2280 | 19:02:15 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52700/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:02:16 INFO - PROCESS | 2280 | RSS: Main: 282607616 19:02:16 INFO - PROCESS | 2280 | 19:02:19 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52700/tests/kraken/driver.html?testName=imaging-darkroom) 19:02:19 INFO - PROCESS | 2280 | RSS: Main: 321314816 19:02:19 INFO - PROCESS | 2280 | 19:02:22 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52700/tests/kraken/driver.html?testName=imaging-desaturate) 19:02:23 INFO - PROCESS | 2280 | RSS: Main: 321757184 19:02:23 INFO - PROCESS | 2280 | 19:02:26 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52700/tests/kraken/driver.html?testName=json-parse-financial) 19:02:26 INFO - PROCESS | 2280 | RSS: Main: 321478656 19:02:26 INFO - PROCESS | 2280 | 19:02:28 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52700/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:02:29 INFO - PROCESS | 2280 | RSS: Main: 297426944 19:02:29 INFO - PROCESS | 2280 | 19:02:30 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52700/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:02:31 INFO - PROCESS | 2280 | RSS: Main: 303333376 19:02:31 INFO - PROCESS | 2280 | 19:02:33 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52700/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:02:34 INFO - PROCESS | 2280 | RSS: Main: 305528832 19:02:34 INFO - PROCESS | 2280 | 19:02:37 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52700/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:02:38 INFO - PROCESS | 2280 | RSS: Main: 321138688 19:02:38 INFO - PROCESS | 2280 | 19:02:40 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52700/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:02:41 INFO - PROCESS | 2280 | RSS: Main: 317222912 19:02:41 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52700/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52700/tests/kraken/driver.html?testName=ai-astar) 19:02:43 INFO - PROCESS | 2280 | RSS: Main: 360775680 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | __start_tp_report 19:02:43 INFO - PROCESS | 2280 | _x_x_mozilla_page_load 19:02:43 INFO - PROCESS | 2280 | _x_x_mozilla_page_load_details 19:02:43 INFO - PROCESS | 2280 | |i|pagename|runs| 19:02:43 INFO - PROCESS | 2280 | |0;ai-astar;114;112;132;114;115;110;113;113;114;116 19:02:43 INFO - PROCESS | 2280 | |1;audio-beat-detection;164;109;150;201;137;132;129;108;162;110 19:02:43 INFO - PROCESS | 2280 | |2;audio-dft;184;186;184;183;184;185;182;183;181;182 19:02:43 INFO - PROCESS | 2280 | |3;audio-fft;79;74;80;157;59;61;59;60;141;73 19:02:43 INFO - PROCESS | 2280 | |4;audio-oscillator;83;83;137;70;71;79;119;69;68;119 19:02:43 INFO - PROCESS | 2280 | |5;imaging-gaussian-blur;90;86;90;87;89;87;91;88;90;86 19:02:43 INFO - PROCESS | 2280 | |6;imaging-darkroom;97;99;98;99;97;97;101;96;129;163 19:02:43 INFO - PROCESS | 2280 | |7;imaging-desaturate;85;84;83;85;87;84;84;84;83;85 19:02:43 INFO - PROCESS | 2280 | |8;json-parse-financial;54;55;54;55;54;54;53;56;54;53 19:02:43 INFO - PROCESS | 2280 | |9;json-stringify-tinderbox;66;68;71;73;73;77;74;73;70;70 19:02:43 INFO - PROCESS | 2280 | |10;stanford-crypto-aes;89;86;89;87;83;85;86;85;85;90 19:02:43 INFO - PROCESS | 2280 | |11;stanford-crypto-ccm;143;144;151;147;140;137;139;133;179;141 19:02:43 INFO - PROCESS | 2280 | |12;stanford-crypto-pbkdf2;172;167;162;169;167;169;172;170;172;170 19:02:43 INFO - PROCESS | 2280 | |13;stanford-crypto-sha256-iterative;68;67;68;66;68;67;66;66;67;66 19:02:43 INFO - PROCESS | 2280 | __end_tp_report 19:02:43 INFO - PROCESS | 2280 | __start_cc_report 19:02:43 INFO - PROCESS | 2280 | _x_x_mozilla_cycle_collect,2056 19:02:43 INFO - PROCESS | 2280 | __end_cc_report 19:02:43 INFO - PROCESS | 2280 | __startTimestamp1472263363534__endTimestamp 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | ------- Summary: start ------- 19:02:43 INFO - PROCESS | 2280 | Number of tests: 14 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#0] ai-astar Cycles:10 Average:115.30 Median:114.00 stddev:6.09 (5.3%) stddev-sans-first:6.44 19:02:43 INFO - PROCESS | 2280 | Values: 114.0 112.0 132.0 114.0 115.0 110.0 113.0 113.0 114.0 116.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:143.50 stddev:29.74 (20.7%) stddev-sans-first:30.27 19:02:43 INFO - PROCESS | 2280 | Values: 164.0 109.0 150.0 201.0 137.0 132.0 129.0 108.0 162.0 110.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.51 (0.8%) stddev-sans-first:1.58 19:02:43 INFO - PROCESS | 2280 | Values: 184.0 186.0 184.0 183.0 184.0 185.0 182.0 183.0 181.0 182.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#3] audio-fft Cycles:10 Average:84.30 Median:67.00 stddev:35.27 (52.6%) stddev-sans-first:37.35 19:02:43 INFO - PROCESS | 2280 | Values: 79.0 74.0 80.0 157.0 59.0 61.0 59.0 60.0 141.0 73.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:70.50 stddev:25.38 (36.0%) stddev-sans-first:26.80 19:02:43 INFO - PROCESS | 2280 | Values: 83.0 83.0 137.0 70.0 71.0 79.0 119.0 69.0 68.0 119.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.50 stddev:1.84 (2.1%) stddev-sans-first:1.86 19:02:43 INFO - PROCESS | 2280 | Values: 90.0 86.0 90.0 87.0 89.0 87.0 91.0 88.0 90.0 86.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#6] imaging-darkroom Cycles:10 Average:107.60 Median:97.00 stddev:21.81 (22.5%) stddev-sans-first:22.80 19:02:43 INFO - PROCESS | 2280 | Values: 97.0 99.0 98.0 99.0 97.0 97.0 101.0 96.0 129.0 163.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.17 (1.4%) stddev-sans-first:1.22 19:02:43 INFO - PROCESS | 2280 | Values: 85.0 84.0 83.0 85.0 87.0 84.0 84.0 84.0 83.0 85.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.97 19:02:43 INFO - PROCESS | 2280 | Values: 54.0 55.0 54.0 55.0 54.0 54.0 53.0 56.0 54.0 53.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:73.00 stddev:3.17 (4.3%) stddev-sans-first:2.67 19:02:43 INFO - PROCESS | 2280 | Values: 66.0 68.0 71.0 73.0 73.0 77.0 74.0 73.0 70.0 70.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:86.50 stddev:2.22 (2.6%) stddev-sans-first:2.17 19:02:43 INFO - PROCESS | 2280 | Values: 89.0 86.0 89.0 87.0 83.0 85.0 86.0 85.0 85.0 90.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:143.50 stddev:12.84 (8.9%) stddev-sans-first:13.59 19:02:43 INFO - PROCESS | 2280 | Values: 143.0 144.0 151.0 147.0 140.0 137.0 139.0 133.0 179.0 141.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:170.00 stddev:3.09 (1.8%) stddev-sans-first:3.08 19:02:43 INFO - PROCESS | 2280 | Values: 172.0 167.0 162.0 169.0 167.0 169.0 172.0 170.0 172.0 170.0 19:02:43 INFO - PROCESS | 2280 | 19:02:43 INFO - PROCESS | 2280 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.83 19:02:43 INFO - PROCESS | 2280 | Values: 68.0 67.0 68.0 66.0 68.0 67.0 66.0 66.0 67.0 66.0 19:02:43 INFO - PROCESS | 2280 | -------- Summary: end -------- 19:02:43 INFO - PROCESS | 2280 | 19:02:44 INFO - TEST-INFO | 2280: exit 0 19:02:44 INFO - TEST-OK | kraken | took 59621ms 19:02:44 INFO - SUITE-END | took 1177s 19:02:44 INFO - Completed test suite (00:19:37) 19:02:44 INFO - javascript benchmark 19:02:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3291.094948889055, 3315.270640459099, 3334.4664853623426, 3376.1222082258605, 3407.3015031918444, 5596.0, 5623.268975033245, 5680.261565996425, 5733.111367737997, 5733.340665823432, 5654.943450565494, 5669.319681638199, 5744.281964754406, 5774.538036957044, 5828.358880523139, 5148.588112950955, 5184.326037615105, 5201.271821944912, 5208.348956380452, 5336.573074154058, 5353.892922141555, 5358.169483730028, 5363.0, 5368.516833484986, 5403.7027963462, 5144.176931690949, 5159.5614372778355, 5204.245384419238, 5267.5259226669405, 5272.604554658416, 5239.528442440199, 5261.97391508656, 5263.34208223972, 5270.262163297141, 5275.155975043975, 5181.404138524081, 5217.3217481727515, 5226.5818734501145, 5230.032443997872, 5256.9474305256845, 5601.887962240732, 5696.088625819891, 5713.942860571383, 5799.449052340063, 5799.623024503394, 6154.815355539341, 6213.285472170713, 6220.968895155541, 6265.780697675559, 6289.811305660837, 4956.752162391866, 6255.468285195764, 6261.217347831521, 6287.842803929892, 6290.779822706183, 5680.289963754531, 5688.91466628005, 5797.797077102366, 5854.912176317359, 5857.179994800731, 6213.844653883689, 6216.098665693449, 6232.3144454110015, 6233.158523599347, 6236.469900058455, 6166.475849552839, 6187.597806142587, 6190.81427557165, 6195.473384762256, 6214.9689251553455, 6078.646092583374, 6152.661603611844, 6184.134221209034, 6201.286852011986, 6260.342664020212, 6205.441510264008, 6245.094461303085, 6247.437730604177, 6258.749650013949, 6262.937370626236, 6236.719347629277, 6248.593841400385, 6261.530385221126, 6270.372962703785, 6273.7176827042895, 4887.486813884562, 4935.777889994959, 4936.531029552187, 4965.47862474435, 4992.575631071327, 3959.524857017119, 4369.235383807821, 4460.197164510354, 4465.464144302639, 4470.754108523997, 5637.492625663628, 5658.377578466366, 5660.377358490563, 5677.261955945701, 5678.829635110953, 6261.78083767066, 6269.467095296951, 6270.874582508324, 6281.748730050838, 6291.653959032297, 5728.885422291531, 5740.913886291709, 5751.59738818287, 5755.280589926259, 5784.94215057844, 5352.9732351338, 5395.676259424447, 5457.20870473787, 5465.6447330923365, 5470.890582188414, 6160.661163635953, 6236.126942228092, 6249.406306400884, 6266.154069200599, 6266.467350275278, 6263.091851681572, 6267.9373206267355, 6274.937250627435, 6287.339829317856, 6298.7480500781285, 6154.0, 6195.907061394173, 6232.906506402317, 6250.3749625037135, 6261.123442717932, 6099.634021958696, 6156.5998210116195, 6200.844978875564, 6201.627902325875, 6205.007198848162, 6264.2796078451565, 6274.560780745304, 6278.6860656966055, 6282.120503129474, 6288.245410550763, 5905.084711869667, 6033.366496517802, 6069.241344831896, 6095.603785753912, 6107.572469927062, 6013.6692481913915, 6108.969455152695, 6111.561351782825, 6117.401149625394, 6130.367562715148, 5901.114832775118, 5919.526437884872, 5922.674252916131, 5943.52451803846, 5949.137375080502, 4708.411448568929, 5983.072623743326, 5993.550483713651, 6094.786682466092, 6118.479929206067, 5844.094165404369, 5924.585279030599, 5926.022206335906, 5961.016432288798, 5984.072468767348], "value": 5693.171066684939, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.72071059140995, 487.865836894854, 506.03852679908147, 531.0626743797199, 532.2495281652871, 29512.26219344515, 40867.77396678101, 42183.945401364945, 42187.789061054726, 44168.55831441685, 42942.14115717684, 43471.56528434715, 43487.34768978468, 43633.7818310908, 43689.7815510922, 9624.663136790232, 9915.157211637019, 9915.603375864957, 9924.950375248114, 9926.801463970753, 8812.427192232517, 9058.954705226464, 11872.347020913847, 12090.972267357285, 12150.149489535735, 11861.584844530444, 11892.405379731023, 11900.285982841015, 11985.220960637575, 11988.820167697491, 8708.042115367305, 12040.879591204108, 12094.939525302318, 12120.757584848341, 12125.969292610138, 10120.645777397829, 11766.0, 12112.454939527743, 12119.151659383846, 12401.193922395105, 40860.0, 41253.79373103145, 41260.968475788344, 41262.587374126175, 41318.793406032775, 3392.9321413571593, 3399.1162297802503, 3418.7094097001786, 3459.2043829919007, 3555.928881422383, 28095.438091238062, 28766.71233287682, 30900.918467853517, 31673.0, 31805.045848624348, 3134.4044631520164, 3138.0, 3277.4428347181038, 3315.336932613463, 3325.6840600142946, 346945.5305446939, 347636.0, 347986.0, 349896.0, 350059.2497037524, 60854.0871886922, 61307.693461532406, 61330.69334653298, 61399.69300153471, 61502.4624384385, 64266.0, 64366.03450948239, 64378.678106609164, 64424.35575644184, 64455.35544644587, 16489.83510164907, 16745.748813767805, 16783.412580559623, 16793.99236045841, 16807.32770689159, 11565.595204167814, 11795.882041179479, 12990.285534295515, 13001.349932503337, 13004.479820807252, 14438.783418248735, 14655.48705795292, 14764.0, 14868.884833637521, 14926.253687315591, 6220.62676239418, 6258.687065646699, 6262.624242545462, 6348.682565871688, 6365.936340636627, 747.6710247491195, 928.6563971330651, 941.2375975459762, 948.2793077261193, 1138.6356365962813, 29473.705262947096, 29771.809127634653, 30623.08130756081, 30712.69287307143, 30838.845805770827, 860.350435421253, 880.6961598248594, 922.5986695787354, 993.5181437003048, 1027.465717826726, 15626.687466250838, 15664.451744189126, 15693.686126277411, 15783.763243551355, 15923.203839807964, 652011.0, 654064.0, 655774.0, 659259.0, 662679.0, 55900.44099559034, 55974.600634984454, 56011.719941400035, 56035.0, 56149.71925140348, 1229.6065259117177, 1354.3363751761565, 1393.4078016842802, 1405.4729476446332, 1414.554415359179, 30447.25974662291, 31435.685643144192, 31498.37003259968, 31521.369572608877, 32397.67602324041, 50359.49640503548, 50942.471725848285, 50994.74502627463, 51033.72415689489, 51054.0, 545491.2725436348, 547753.0, 549574.0, 549643.0, 550141.0, 21771.78228217761, 25164.370890727878, 25873.482530349665, 26098.0, 26344.473110538067, 46509.83725406756, 46801.29798052964, 47057.29414058723, 47237.0, 47656.0, 36996.630033699315, 37063.81468092642, 37242.44136337903, 37926.24147517089, 38026.619733802305, 35247.0, 37151.071223218554, 37563.99063454929, 38315.04212394601, 38846.61153388543, 36988.63011369852, 37442.0, 38457.0, 38465.80767096147, 38552.42171367488, 32427.0, 33589.0, 33867.62697131504, 34301.0, 34427.4835877457, 22352.77647223507, 24842.37894052591, 25042.499150016534, 25204.873975630737, 25670.486590267716], "value": 19858.56437728574, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.12200447677117, 346.93928562501554, 360.18597968590996, 375.2513735098477, 402.91337362467084, 14884.553463396089, 15865.682686346267, 20605.896970515125, 20860.165593376267, 21385.358439246807, 20437.978101094923, 20860.478488037807, 21462.89268553655, 21546.922653867285, 21752.347429577098, 5925.614835035731, 6059.182010428602, 6278.4663303619245, 6337.46131578814, 6338.683065846712, 4702.29465580164, 6246.281677607065, 6246.344133865947, 6255.40573645503, 6262.342454042328, 5519.9448005519835, 5855.975204339224, 5870.501007414375, 6201.317855035943, 6212.78255261066, 6100.267967843865, 6181.752729890822, 6185.288691800455, 6187.721552530147, 6189.845253868644, 5103.209002604603, 5374.731263436813, 5376.838694839161, 5380.838574842761, 5381.48875856793, 26337.3415664608, 26395.73604263952, 26449.73550264492, 26466.867665661743, 26836.329091772666, 2177.923772667965, 2232.229880691159, 2271.6138256496433, 2434.805215582749, 2972.70272972701, 15041.774373384407, 15787.684246315124, 15878.52364429069, 16040.43858464948, 16457.753133703005, 1805.6840052990674, 1840.2363019346956, 1869.1962456143854, 1879.6522643311032, 1882.7458293130526, 93823.53088234515, 129152.35423822915, 130610.0, 131776.0, 132276.3386183063, 9695.51522423876, 10740.892591074145, 11061.059809916247, 11199.248251835563, 11356.886431135583, 11614.361210133524, 13969.022168448315, 13975.37110830015, 13993.510227142206, 14013.159210447406, 8360.874586881202, 8589.0, 8599.441036332619, 8756.168164024408, 8760.824783504295, 8652.78368040804, 8714.085021072697, 8716.956415217883, 8909.777255568662, 8943.284537237005, 5569.331680198402, 6603.70283337251, 6747.797566073025, 6765.255821859591, 6845.486588505882, 1672.4313733330725, 1683.5791052236941, 1764.7176451767655, 1809.0592891696244, 1817.6637322095455, 925.5511077127587, 936.5551363102472, 945.432740355781, 990.6631745206664, 1064.7436025489842, 20216.09027593762, 21209.469763256042, 22295.219667311892, 22369.0, 22473.6515809052, 865.4028720183179, 895.8029233568604, 919.347263442963, 954.3605784124653, 1002.063071028576, 10937.796652510617, 11230.213885028134, 11341.0, 11356.716082098013, 11357.261777984404, 608743.9562802158, 609285.0, 615154.0, 624573.0, 625463.0, 83157.0, 134688.32655836854, 136885.31557342148, 137248.0, 140459.0, 891.3671293381642, 985.8619793228954, 997.5760301871676, 1019.6889948565611, 1020.2705065877933, 19157.233710651977, 19220.519487012938, 19327.907534794365, 19487.8051219486, 19528.609427811647, 41581.752547423624, 53717.73141134269, 55330.34008979737, 56702.0, 57501.0, 59378.70310648592, 75924.86112708424, 79841.80237296328, 80324.3935121306, 81516.95728683383, 7725.420593455401, 7874.960625196837, 8001.839963200587, 8394.874076888973, 8447.535385553854, 9248.213901818151, 12540.435680394587, 13134.60596182116, 13407.463701451832, 13560.389782459615, 10144.746381340525, 11307.151963602928, 11745.823812642646, 11750.35373054456, 11867.76264474723, 11323.263987840764, 11337.943310283395, 11507.136964727511, 11743.236689615147, 11790.94104529472, 11499.195056345976, 11580.594679186188, 11920.463579139134, 11933.880661193623, 12016.519339226334, 7975.5613441261285, 8147.9185208147155, 8150.83698326042, 8304.626291817003, 8492.108328625405, 8418.494890306602, 10971.451427428756, 11560.930511481203, 11742.88257117418, 11910.821337680116], "value": 9830.877541203301, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.4257669202239, 1397.7484052870482, 1490.9254537273137, 1673.2386764022372, 1879.0322983663416, 7747.767566972992, 8430.36772242082, 8664.913350866505, 8673.73978780636, 8755.080716524768, 8594.312455003601, 8683.088275731036, 8694.478331300126, 8699.39104262702, 8701.7824554386, 2350.976490235097, 2372.1578839511985, 2377.203636781672, 2381.7261014983237, 2382.9880850595723, 2313.5835549601065, 2354.693889794324, 2371.2886134159685, 2374.2283757778705, 2374.489484760776, 2333.4866329407505, 2352.200251914349, 2358.6933698619155, 2368.6802281692007, 2369.206315884181, 2295.2081531871477, 2332.941676458085, 2336.9883150584224, 2338.7137074608895, 2342.1685301717985, 2327.150590034642, 2331.0792237066435, 2335.2760644200184, 2337.696099507053, 2340.1224540797202, 8593.914060859373, 8669.609867555975, 8735.039145693969, 8791.64833406663, 8829.646814127427, 5157.381114266274, 5342.091844386464, 5362.490563396471, 5362.973185134049, 5388.110961691316, 3536.1159710072484, 3557.4130268505664, 3608.1520842601967, 3625.492431059654, 3632.20091579852, 1073.9892601073968, 1082.8050950828847, 1085.3921803789904, 1096.8683757949018, 1105.171121658756, 11373.976342129248, 11452.026577740819, 11506.712332191679, 11523.135764817589, 11539.826902596384, 3997.200559888034, 4310.612044915973, 4407.2507673695545, 4573.794179261941, 4576.107659006462, 8827.470351778913, 9041.68354107603, 9052.140046695553, 9052.411593246417, 9056.683016094405, 5186.844394668166, 5188.948110518922, 5196.662216955886, 5197.830488140138, 5202.3497062867145, 4378.343248512685, 4541.409616749801, 4702.694324868872, 4831.879203019882, 4879.487653796371, 3625.836837342326, 3673.6326367363586, 3772.6981841452616, 3790.7915064671156, 3805.923881522354, 1133.631569739838, 1143.1712008793559, 1146.306484576819, 1154.7286387698884, 1158.901493373056, 888.9333300002526, 891.7815135291895, 906.959186836594, 925.227435091706, 941.3692825806726, 5456.727163641802, 6036.6679832609625, 6083.11794789753, 6142.877142457126, 6247.125402443661, 810.4691427115249, 916.2166347772692, 943.6272672294406, 953.1088432315806, 971.9562619682131, 3349.4305967985015, 3764.6235376462682, 4036.2936486114813, 4087.1621317629815, 4118.423420721101, 8929.33030022751, 10167.898321016843, 10723.30298530593, 10739.0, 10754.677359679223, 10228.181745460346, 10657.626983055556, 10672.359658420519, 10690.0, 10693.625723099654, 7772.961135194401, 8122.75631731049, 8148.83702325962, 8164.387670924704, 8167.42828002046, 5743.712814359349, 5840.649561026352, 5920.0, 5964.493018093511, 5972.313183983897, 9749.902500975184, 9809.264305177283, 9862.753431163992, 10031.946645602107, 10080.04239597237, 9499.810003800023, 9926.503674816375, 9934.602615895283, 10120.797584048425, 10142.797144057226, 6928.7228510859, 7110.7511237106455, 7132.928670713434, 7426.517276377018, 7608.771736847903, 7810.687572497264, 7896.131425543071, 7897.605119743875, 8106.310963568163, 8237.0, 7318.231585683427, 7453.552786832807, 7459.179490256176, 7551.773446796605, 7596.734114305973, 7232.940451275815, 7278.550475924343, 7290.088738907637, 7363.852722945619, 7453.291937265951, 6486.286508483969, 6647.335266473314, 6653.767118150841, 6656.30108838565, 6658.234303055211, 4761.880952976203, 4809.495003024632, 4821.879453013703, 4849.351220585018, 4940.456549779449, 4078.490188726409, 4169.645580125723, 4747.335373047707, 4869.829555965524, 4871.123197824284], "value": 4496.266534201813, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.42404159971255, 303.1043267145585, 306.19318096814885, 311.95632611434405, 314.5753233135266, 4518.525554816742, 4823.866391398019, 5213.322268105146, 5227.3465816772905, 5275.784170648328, 4429.756363400012, 4771.9761401192945, 4816.951830481694, 4932.076490168837, 5071.74641267936, 3521.7182625390037, 3594.856205751767, 3710.9443358349645, 3802.315583195017, 3809.657130858222, 3991.8086009689855, 3997.7401468904573, 3998.1403998140395, 4008.3386241270155, 4016.9112061727883, 3287.1289108386295, 3889.0083028827607, 3969.702272329572, 4005.939910901339, 4047.919041619166, 3327.7337812975147, 3569.7501174917766, 3590.7666001709936, 3918.960810391888, 4019.376996565537, 2970.866311015988, 3034.2691577580135, 3457.4986626939203, 3492.6158122606494, 3495.4582039783872, 5025.673331233458, 5040.521150490697, 5044.520770526794, 5062.519060689266, 5071.199724097966, 4379.5182529921685, 4500.572445617661, 4532.569405906466, 4547.7271363717955, 4556.54434556545, 5085.694858308476, 5212.628254144393, 5470.398256191856, 5486.91769623456, 5502.972485137589, 3838.5777564467885, 3847.576766555677, 3847.7498962567624, 3850.4224366344993, 3867.5552311484257, 4867.659263851568, 5336.779760661175, 5662.178984047372, 5744.913826292609, 5755.338136114358, 3442.604100528421, 3891.5330160380936, 3901.395283731026, 3909.5308562972627, 3923.2733250390706, 3940.310445671996, 3945.723799334019, 3946.4080387941463, 3956.485656864647, 3958.7332053743103, 4529.750863702462, 4544.931826022612, 4564.612007979293, 4608.907821843612, 4612.238980568169, 4557.44782921565, 5195.376554813371, 5609.495145436848, 5617.9719101404235, 5628.943710562842, 1121.5738019552518, 1124.1231839165464, 1321.5242512695422, 1325.707435250623, 1346.596021193638, 681.6567138385902, 687.9484038697018, 810.0926961802819, 816.2572059425894, 817.5013241922422, 4487.977560112178, 4645.5354464553275, 4662.829276092222, 4744.762761861962, 5075.796968121308, 5652.506257525277, 5696.572757043155, 5697.658140511582, 5715.142578661951, 5750.159948816338, 4716.867951691638, 5199.29809475716, 5215.852512447255, 5221.295125158131, 5262.289590905255, 2762.502749505068, 2778.4440713200675, 2939.955900661492, 2980.478416277143, 2981.821090734563, 5810.273715785527, 5867.53311672082, 5885.588008839341, 5896.469317761422, 5947.1079338099635, 5618.943810561924, 5730.650433322317, 5758.913616295759, 5815.244018277597, 5818.912716309259, 5821.767129314865, 5825.941740582624, 5841.4450627190345, 5851.941480585225, 5854.385289544622, 4443.533428989909, 4609.24848557546, 5465.535429488538, 5627.352854421794, 5682.897327988505, 5527.5577953762795, 5541.667499950016, 5571.913476871971, 5585.5702769295485, 5629.831105066854, 5377.758000889891, 5795.275590551181, 5804.73878675469, 5826.504747096545, 5878.470937615635, 3737.6449237322413, 3875.4961854958674, 3909.6285852843935, 3910.3352430086384, 3922.4312474691583, 5467.289252397163, 5507.807226747044, 5518.420565840529, 5568.359638641608, 5574.247476590771, 4708.81164753406, 4758.619310455086, 5364.865878353073, 5378.435264297193, 5390.97304513475, 4467.642588592969, 4965.82619608312, 5132.79468821243, 5221.4517475666025, 5341.439148889311, 5239.816606418757, 5251.894962100813, 5302.70835104073, 5311.9468805313, 5330.360356757214, 4856.660033797742, 5162.948370516397, 5169.302144210484, 5332.520378024237, 5395.433479484597, 4481.132075471635, 5082.110630639697, 5269.78920843162, 5317.8404647860625, 5326.9200961984825], "value": 3995.957732011436, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.1051322767175, 708.7094291340551, 736.3740820302743, 750.6884642873208, 753.1602263476225, 13222.20666759994, 13480.595582132528, 13488.123271987313, 13494.0, 13532.0, 14058.507952221675, 14147.929260353683, 14157.575272741808, 14167.008309418345, 14591.708165836677, 655.3708439897704, 676.1987045351275, 680.0479328939499, 841.2008591837748, 866.5970323799432, 671.9596824190539, 674.2886255000972, 834.3742193354984, 856.7601071699934, 861.9267362274214, 744.2668971063498, 880.5069161269678, 892.2460520859863, 892.2727976699002, 893.053363434758, 887.4453466583385, 887.622760326865, 888.1606881496923, 888.8044630181349, 889.4174315823146, 517.3791450259704, 532.4223217808682, 657.5594351784268, 658.2989116590863, 659.1562799616498, 12380.0, 12504.062195335295, 14338.784918226234, 14863.0, 14874.033187842755, 13945.58163255104, 14498.42006319746, 14522.927385363006, 14568.344424500923, 14602.34289456977, 11546.480408381643, 11641.0, 11698.824517632242, 11977.580784672582, 12079.396030198543, 3745.438184272381, 3774.2640185163905, 3804.4483549885113, 3819.866304679351, 3925.509311336083, 81803.0, 109684.0, 114304.0, 114648.0, 114863.42568287105, 10652.573897044187, 10879.184061195441, 10932.180086493387, 10970.835437468444, 11170.776584468427, 23321.95051222699, 23341.649875251886, 23369.41576460568, 23377.532449350918, 23815.0, 7681.654325555363, 7864.567448790257, 7975.5613441261285, 8025.598720063974, 8125.1062383137805, 4244.172386384687, 4347.891302717458, 4397.846075387346, 4523.683342166082, 4524.751138687404, 3528.5412896323314, 3653.8903832885057, 3660.7071434285194, 3660.853565857389, 3661.9633803661623, 624.5191202773839, 626.7962912053509, 636.2524034259726, 648.536296547961, 651.0397164182792, 586.3667239381458, 618.9597676151036, 625.6183727925961, 642.3351830855042, 643.3405759096963, 8595.355348348774, 8938.240249578728, 9078.364514483923, 9079.050612983374, 9291.256699464027, 578.6614830324307, 590.4971846650285, 633.8288662061218, 649.2436311696914, 657.139147716493, 4216.536181020086, 4395.582419670126, 4397.846075387346, 4410.117976404732, 4486.925122018996, 96663.51668241614, 102343.0, 110022.44988775004, 159329.20335398248, 165968.34031659528, 110923.0, 116094.41952790182, 129517.35241323922, 131850.68149318575, 134048.65012001566, 102330.0, 104780.47609762054, 117464.0, 123141.38429307796, 127188.0, 8251.917480825115, 8532.488050716976, 8544.871826922477, 8709.651613935372, 8790.560471976247, 52402.95194096021, 58588.0, 63458.0, 66185.0, 68822.9676554842, 97914.0, 100288.49855750674, 102639.0, 104702.0, 106854.0, 11946.283223006647, 12073.57742479009, 12091.153619246568, 12318.938405307916, 12391.504339826306, 12574.49702011946, 12875.935620322212, 12946.67633309137, 13251.13867598603, 13260.138091024053, 10005.499725013866, 11688.0, 12304.540866807003, 12426.502939882303, 12546.309952952675, 11251.606193783506, 12066.879331206575, 12277.140600157903, 12429.37853107323, 12517.874821251671, 11851.111166662362, 12044.397780111134, 12051.939740301243, 12163.209391389531, 12423.31671758026, 8519.701810436605, 10247.180225582084, 10306.0, 10361.4421737459, 10423.530941107516, 10172.542235599563, 10293.176545876462, 10310.175185985254, 10313.432761198206, 10327.48362581883], "value": 7498.55245684499, "unit": "score"}], "name": "dromaeo_css", "value": 7287.279790712041, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 132.0, 114.0, 115.0, 110.0, 113.0, 113.0, 114.0, 116.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 109.0, 150.0, 201.0, 137.0, 132.0, 129.0, 108.0, 162.0, 110.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 183.0, 184.0, 185.0, 182.0, 183.0, 181.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 80.0, 157.0, 59.0, 61.0, 59.0, 60.0, 141.0, 73.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 137.0, 70.0, 71.0, 79.0, 119.0, 69.0, 68.0, 119.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 87.0, 89.0, 87.0, 91.0, 88.0, 90.0, 86.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 98.0, 99.0, 97.0, 97.0, 101.0, 96.0, 129.0, 163.0], "value": 107.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 85.0, 87.0, 84.0, 84.0, 84.0, 83.0, 85.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 55.0, 54.0, 54.0, 53.0, 56.0, 54.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 71.0, 73.0, 73.0, 77.0, 74.0, 73.0, 70.0, 70.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 83.0, 85.0, 86.0, 85.0, 85.0, 90.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 144.0, 151.0, 147.0, 140.0, 137.0, 139.0, 133.0, 179.0, 141.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 167.0, 162.0, 169.0, 167.0, 169.0, 172.0, 170.0, 172.0, 170.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 68.0, 67.0, 66.0, 66.0, 67.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1486.9, "alertThreshold": 2.0}]} 19:02:44 INFO - Return code: 0 19:02:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:02:44 INFO - # TBPL SUCCESS # 19:02:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:02:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:02:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:02:44 INFO - 2016-08-26 19:02:44.874 screenresolution[2284:287822] starting screenresolution argv=screenresolution get 19:02:44 INFO - 2016-08-26 19:02:44.877 screenresolution[2284:287822] Display 0: 1600x1200x32@60 19:02:44 INFO - 2016-08-26 19:02:44.883 screenresolution[2285:287825] starting screenresolution argv=screenresolution list 19:02:44 INFO - Available Modes on Display 0 19:02:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:02:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:02:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:02:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:02:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:02:45 INFO - Intel Iris: 19:02:45 INFO - Chipset Model: Intel Iris 19:02:45 INFO - Type: GPU 19:02:45 INFO - Bus: Built-In 19:02:45 INFO - VRAM (Dynamic, Max): 1536 MB 19:02:45 INFO - Vendor: Intel (0x8086) 19:02:45 INFO - Device ID: 0x0a2e 19:02:45 INFO - Revision ID: 0x0009 19:02:45 INFO - Displays: 19:02:45 INFO - Display: 19:02:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:02:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:02:45 INFO - Display Serial Number: 1600x1200 60 19:02:45 INFO - Main Display: Yes 19:02:45 INFO - Mirror: Off 19:02:45 INFO - Online: Yes 19:02:45 INFO - Rotation: Supported 19:02:45 INFO - Return code: 0 19:02:45 INFO - Running post-action listener: _resource_record_post_action 19:02:45 INFO - [mozharness: 2016-08-27 02:02:45.120236Z] Finished run-tests step (success) 19:02:45 INFO - Running post-run listener: _resource_record_post_run 19:02:45 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 7344640; Write bytes: 707313664; Read time: 63; Write time: 2560 19:02:45 INFO - TinderboxPrint: CPU usage
53.6% 19:02:45 INFO - TinderboxPrint: I/O read bytes / time
7,344,640 / 63 19:02:45 INFO - TinderboxPrint: I/O write bytes / time
707,313,664 / 2,560 19:02:45 INFO - TinderboxPrint: CPU idle
2,253.2 (46.4%) 19:02:45 INFO - TinderboxPrint: CPU system
971.2 (20.0%) 19:02:45 INFO - TinderboxPrint: CPU user
1,629.3 (33.6%) 19:02:45 INFO - TinderboxPrint: Swap in / out
420,823,040 / 0 19:02:45 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 406700544; Write bytes: 409122816; Read time: 31308; Write time: 764 19:02:45 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 7266816; Write bytes: 296736768; Read time: 61; Write time: 1787 19:02:45 INFO - Running post-run listener: _upload_blobber_files 19:02:45 INFO - Blob upload gear active. 19:02:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:02:45 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 19:02:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:02:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:02:46 INFO - (blobuploader) - INFO - Open directory for files ... 19:02:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:02:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:02:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:46 INFO - (blobuploader) - INFO - Done attempting. 19:02:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:02:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:02:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:48 INFO - (blobuploader) - INFO - Done attempting. 19:02:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:02:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:02:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:51 INFO - (blobuploader) - INFO - Done attempting. 19:02:51 INFO - (blobuploader) - INFO - Iteration through files over. 19:02:51 INFO - Return code: 0 19:02:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:02:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:02:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/969b6e1bbc38529d72ea5375242113983c664be22c2b68f57b953d54a09d98519d9053c8398fcef3e150637e8703f4673d429b756acd10ef67d6811febcf13ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4089c31f4a5407e77cd5730697227bc4b09a40971aa90894e0682442bd85af23df99f92468007409127edb356d155e9f715be343779c782b542af39d848bbf1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca3994cef0fde664214fc7153f81e8d1d23636056b4b2a7a00685d6a39eb1fd9fb6814be84cfded5df450a17425343f9eed90c126b31d5487641754404b172bb"} 19:02:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:02:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:02:51 INFO - Contents: 19:02:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/969b6e1bbc38529d72ea5375242113983c664be22c2b68f57b953d54a09d98519d9053c8398fcef3e150637e8703f4673d429b756acd10ef67d6811febcf13ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4089c31f4a5407e77cd5730697227bc4b09a40971aa90894e0682442bd85af23df99f92468007409127edb356d155e9f715be343779c782b542af39d848bbf1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca3994cef0fde664214fc7153f81e8d1d23636056b4b2a7a00685d6a39eb1fd9fb6814be84cfded5df450a17425343f9eed90c126b31d5487641754404b172bb"} 19:02:51 INFO - Running post-run listener: copy_logs_to_upload_dir 19:02:51 INFO - Copying logs to upload dir... 19:02:51 INFO - mkdir: /builds/slave/test/build/upload/logs 19:02:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.648076 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 19:02:51.173049) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 19:02:51.177912) ========= 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.gIertc1aDo/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.und83cJxz7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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/Firefox/sha512/969b6e1bbc38529d72ea5375242113983c664be22c2b68f57b953d54a09d98519d9053c8398fcef3e150637e8703f4673d429b756acd10ef67d6811febcf13ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4089c31f4a5407e77cd5730697227bc4b09a40971aa90894e0682442bd85af23df99f92468007409127edb356d155e9f715be343779c782b542af39d848bbf1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca3994cef0fde664214fc7153f81e8d1d23636056b4b2a7a00685d6a39eb1fd9fb6814be84cfded5df450a17425343f9eed90c126b31d5487641754404b172bb"} build_url:https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/AQHlcBIuTWik07jDXGqXrA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/969b6e1bbc38529d72ea5375242113983c664be22c2b68f57b953d54a09d98519d9053c8398fcef3e150637e8703f4673d429b756acd10ef67d6811febcf13ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4089c31f4a5407e77cd5730697227bc4b09a40971aa90894e0682442bd85af23df99f92468007409127edb356d155e9f715be343779c782b542af39d848bbf1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca3994cef0fde664214fc7153f81e8d1d23636056b4b2a7a00685d6a39eb1fd9fb6814be84cfded5df450a17425343f9eed90c126b31d5487641754404b172bb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 19:02:51.211663) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 19:02:51.212035) ========= 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.gIertc1aDo/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.und83cJxz7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.005338 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 19:02:51.260538) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 19:02:51.260893) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 19:02:51.261364) ========= ========= Total master_lag: 0.19 =========