builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0067 starttime: 1470659808.02 results: success (0) buildid: 20160808030441 builduid: f603c1cd8c81472499074ffbf994b474 revision: e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:48.024415) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:48.024909) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:48.025277) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:48.051080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:48.051499) ========= 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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-08 05:36:48-- 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% 116M=0s 2016-08-08 05:36:48 (116 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138750 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:48.213767) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:48.214141) ========= 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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.027882 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:48.263895) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:36:48.264508) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 --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 e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-08 05:36:48,345 truncating revision to first 12 chars 2016-08-08 05:36:48,345 Setting DEBUG logging. 2016-08-08 05:36:48,345 attempt 1/10 2016-08-08 05:36:48,345 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e78975b53563?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 05:36:49,478 unpacking tar archive at: mozilla-central-e78975b53563/testing/mozharness/ program finished with exit code 0 elapsedTime=1.410257 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:36:49.697639) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:49.698133) ========= script_repo_revision: e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:49.698557) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:49.698845) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:36:49.716972) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-08 05:36:49.717279) ========= /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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:36:49 INFO - MultiFileLogger online at 20160808 05:36:49 in /builds/slave/test 05:36:49 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 05:36:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:36:49 INFO - {'append_to_log': False, 05:36:49 INFO - 'base_work_dir': '/builds/slave/test', 05:36:49 INFO - 'blob_upload_branch': 'Firefox', 05:36:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:36:49 INFO - 'branch': 'Firefox', 05:36:49 INFO - 'buildbot_json_path': 'buildprops.json', 05:36:49 INFO - 'config_files': ('talos/mac_config.py',), 05:36:49 INFO - 'default_actions': ('clobber', 05:36:49 INFO - 'read-buildbot-config', 05:36:49 INFO - 'download-and-extract', 05:36:49 INFO - 'populate-webroot', 05:36:49 INFO - 'create-virtualenv', 05:36:49 INFO - 'install', 05:36:49 INFO - 'run-tests'), 05:36:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:36:49 INFO - 'download_minidump_stackwalk': True, 05:36:49 INFO - 'download_symbols': 'ondemand', 05:36:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:36:49 INFO - 'tooltool.py': '/tools/tooltool.py', 05:36:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:36:49 INFO - '/tools/misc-python/virtualenv.py')}, 05:36:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:36:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:36:49 INFO - 'installer_path': 'installer.exe', 05:36:49 INFO - 'log_level': 'info', 05:36:49 INFO - 'log_name': 'talos', 05:36:49 INFO - 'log_to_console': True, 05:36:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:36:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:36:49 INFO - 'opt_config_files': (), 05:36:49 INFO - 'pip_index': False, 05:36:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:36:49 INFO - 'cmd': ('bash', 05:36:49 INFO - '-c', 05:36:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:36:49 INFO - 'enabled': True, 05:36:49 INFO - 'halt_on_failure': False, 05:36:49 INFO - 'name': 'check_screen_resolution'},), 05:36:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:36:49 INFO - 'cmd': ('bash', 05:36:49 INFO - '-c', 05:36:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:36:49 INFO - 'enabled': True, 05:36:49 INFO - 'halt_on_failure': False, 05:36:49 INFO - 'name': 'check_screen_resolution'},), 05:36:49 INFO - 'run_cmd_checks_enabled': True, 05:36:49 INFO - 'sps_profile': False, 05:36:49 INFO - 'sps_profile_interval': 0, 05:36:49 INFO - 'suite': 'dromaeojs', 05:36:49 INFO - 'system_bits': '32', 05:36:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:36:49 INFO - 'title': 't-yosemite-r7-0067', 05:36:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:36:49 INFO - 'use_talos_json': True, 05:36:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:36:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:36:49 INFO - 'work_dir': 'build'} 05:36:49 INFO - [mozharness: 2016-08-08 12:36:49.910401Z] Running clobber step. 05:36:49 INFO - Running pre-action listener: _resource_record_pre_action 05:36:49 INFO - Running main action method: clobber 05:36:49 INFO - rmtree: /builds/slave/test/build 05:36:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:36:51 INFO - Running post-action listener: _resource_record_post_action 05:36:51 INFO - [mozharness: 2016-08-08 12:36:51.950074Z] Finished clobber step (success) 05:36:51 INFO - [mozharness: 2016-08-08 12:36:51.950200Z] Running read-buildbot-config step. 05:36:51 INFO - Running pre-action listener: _resource_record_pre_action 05:36:51 INFO - Running main action method: read_buildbot_config 05:36:51 INFO - Using buildbot properties: 05:36:51 INFO - { 05:36:51 INFO - "project": "", 05:36:51 INFO - "product": "firefox", 05:36:51 INFO - "script_repo_revision": "production", 05:36:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:36:51 INFO - "repository": "", 05:36:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:36:51 INFO - "buildid": "20160808030441", 05:36:51 INFO - "stage_platform": "macosx64", 05:36:51 INFO - "basedir": "/builds/slave/test", 05:36:51 INFO - "nightly_build": "True", 05:36:51 INFO - "buildnumber": 29, 05:36:51 INFO - "slavename": "t-yosemite-r7-0067", 05:36:51 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:36:51 INFO - "platform": "yosemite_r7", 05:36:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:36:51 INFO - "branch": "mozilla-central", 05:36:51 INFO - "repo_path": "mozilla-central", 05:36:51 INFO - "revision": "e78975b53563d80c99ebfbdf8a9fbf6b829a8a48", 05:36:51 INFO - "pgo_build": "False", 05:36:51 INFO - "builduid": "f603c1cd8c81472499074ffbf994b474", 05:36:51 INFO - "slavebuilddir": "test" 05:36:51 INFO - } 05:36:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:36:51 INFO - Running post-action listener: _resource_record_post_action 05:36:51 INFO - [mozharness: 2016-08-08 12:36:51.953780Z] Finished read-buildbot-config step (success) 05:36:51 INFO - [mozharness: 2016-08-08 12:36:51.953904Z] Running download-and-extract step. 05:36:51 INFO - Running pre-action listener: _resource_record_pre_action 05:36:51 INFO - Running main action method: download_and_extract 05:36:51 INFO - mkdir: /builds/slave/test/build/tests 05:36:51 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')]} 05:36:51 INFO - trying https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:36:51 INFO - Downloading https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:36:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:36:53 INFO - Downloaded 1199 bytes. 05:36:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:36:53 INFO - Using the following test package requirements: 05:36:53 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:36:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:53 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:36:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:53 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:36:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:53 INFO - u'jsshell-mac.zip'], 05:36:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:53 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:36:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:36:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:53 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:36:53 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:53 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:36:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:53 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:36:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:53 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:36:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:53 INFO - trying https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:36:53 INFO - Downloading https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:36:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:36:54 INFO - Downloaded 16324347 bytes. 05:36:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:36:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:36:57 INFO - Return code: 0 05:36:57 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 05:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:57 INFO - trying https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:36:57 INFO - Downloading https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:36:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:36:58 INFO - Downloaded 16324347 bytes. 05:36:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:36:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:37:01 INFO - Return code: 0 05:37:01 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')]} 05:37:01 INFO - trying https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:37:01 INFO - Downloading https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:37:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:37:02 INFO - Downloaded 14413723 bytes. 05:37:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:37:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:37:02 INFO - Return code: 0 05:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:02 INFO - trying https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:37:02 INFO - Downloading https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:37:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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 05:37:07 INFO - Downloaded 112013008 bytes. 05:37:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:37:07 INFO - mkdir: /builds/slave/test/properties 05:37:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:37:07 INFO - Writing to file /builds/slave/test/properties/build_url 05:37:07 INFO - Contents: 05:37:07 INFO - build_url:https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:37:08 INFO - Running post-action listener: _resource_record_post_action 05:37:08 INFO - Running post-action listener: set_extra_try_arguments 05:37:08 INFO - [mozharness: 2016-08-08 12:37:08.220990Z] Finished download-and-extract step (success) 05:37:08 INFO - [mozharness: 2016-08-08 12:37:08.221116Z] Running populate-webroot step. 05:37:08 INFO - Running pre-action listener: _resource_record_pre_action 05:37:08 INFO - Running main action method: populate_webroot 05:37:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:37:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:37:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:37:08 INFO - '--tppagecycles', 05:37:08 INFO - '7'], 05:37:08 INFO - 'tests': ['tsvgm']}}, 05:37:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:37:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:37:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:37:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:37:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:08 INFO - 'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:08 INFO - 'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['damp', 'tps']}, 05:37:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:08 INFO - 'tests': ['damp', 'tps']}, 05:37:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['dromaeo_dom']}, 05:37:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['basic_compositor_video']}, 05:37:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:37:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['a11yr', 05:37:08 INFO - 'ts_paint', 05:37:08 INFO - 'tpaint', 05:37:08 INFO - 'sessionrestore', 05:37:08 INFO - 'sessionrestore_no_auto_restore', 05:37:08 INFO - 'tabpaint']}, 05:37:08 INFO - 'other-e10s': {'tests': ['a11yr', 05:37:08 INFO - 'ts_paint', 05:37:08 INFO - 'tpaint', 05:37:08 INFO - 'sessionrestore', 05:37:08 INFO - 'sessionrestore_no_auto_restore', 05:37:08 INFO - 'tabpaint']}, 05:37:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:37:08 INFO - 'ts_paint', 05:37:08 INFO - 'tpaint', 05:37:08 INFO - 'sessionrestore', 05:37:08 INFO - 'sessionrestore_no_auto_restore', 05:37:08 INFO - 'tabpaint']}, 05:37:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:37:08 INFO - 'ts_paint', 05:37:08 INFO - 'tpaint', 05:37:08 INFO - 'sessionrestore', 05:37:08 INFO - 'sessionrestore_no_auto_restore', 05:37:08 INFO - 'tabpaint']}, 05:37:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['a11yr', 05:37:08 INFO - 'ts_paint', 05:37:08 INFO - 'tpaint', 05:37:08 INFO - 'sessionrestore', 05:37:08 INFO - 'sessionrestore_no_auto_restore', 05:37:08 INFO - 'tabpaint']}, 05:37:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['a11yr', 05:37:08 INFO - 'ts_paint', 05:37:08 INFO - 'tpaint', 05:37:08 INFO - 'sessionrestore', 05:37:08 INFO - 'sessionrestore_no_auto_restore', 05:37:08 INFO - 'tabpaint']}, 05:37:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['tsvgx', 05:37:08 INFO - 'tsvgr_opacity', 05:37:08 INFO - 'tart', 05:37:08 INFO - 'tscrollx', 05:37:08 INFO - 'cart']}, 05:37:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:37:08 INFO - 'tsvgr_opacity', 05:37:08 INFO - 'tart', 05:37:08 INFO - 'tscrollx', 05:37:08 INFO - 'cart']}, 05:37:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:08 INFO - 'talos_options': ['--disable-e10s'], 05:37:08 INFO - 'tests': ['tp5o']}, 05:37:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:08 INFO - 'tests': ['tp5o']}, 05:37:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:08 INFO - 'talos_options': ['--disable-e10s', 05:37:08 INFO - '--xperf_path', 05:37:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:08 INFO - 'tests': ['tp5n']}, 05:37:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:08 INFO - 'talos_options': ['--xperf_path', 05:37:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:08 INFO - 'tests': ['tp5n']}}, 05:37:08 INFO - 'talos.zip': {'path': '', 05:37:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:37:08 INFO - Running post-action listener: _resource_record_post_action 05:37:08 INFO - [mozharness: 2016-08-08 12:37:08.240469Z] Finished populate-webroot step (success) 05:37:08 INFO - [mozharness: 2016-08-08 12:37:08.240599Z] Running create-virtualenv step. 05:37:08 INFO - Running pre-action listener: _resource_record_pre_action 05:37:08 INFO - Running main action method: create_virtualenv 05:37:08 INFO - Creating virtualenv /builds/slave/test/build/venv 05:37:08 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 05:37:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:37:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:08 INFO - Using real prefix '/tools/python27' 05:37:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:37:09 INFO - Installing distribute.............................................................................................................................................................................................done. 05:37:11 INFO - Installing pip.................done. 05:37:11 INFO - Return code: 0 05:37:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:37:11 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')]} 05:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:11 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')]} 05:37:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:11 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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:11 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 05:37:11 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 05:37:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:11 INFO - 'HOME': '/Users/cltbld', 05:37:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:11 INFO - 'LOGNAME': 'cltbld', 05:37:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:11 INFO - 'MOZ_NO_REMOTE': '1', 05:37:11 INFO - 'NO_EM_RESTART': '1', 05:37:11 INFO - 'PAGER': '/bin/cat', 05:37:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:11 INFO - 'PWD': '/builds/slave/test', 05:37:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:11 INFO - 'SHELL': '/bin/bash', 05:37:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:11 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:11 INFO - 'USER': 'cltbld', 05:37:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:11 INFO - 'XPC_FLAGS': '0x0', 05:37:11 INFO - 'XPC_SERVICE_NAME': '0', 05:37:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:12 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 05:37:12 INFO - Cleaning up... 05:37:12 INFO - Return code: 0 05:37:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:37: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')]} 05:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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')]} 05:37:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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', '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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:37: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 psutil>=3.1.1 05:37:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:12 INFO - 'HOME': '/Users/cltbld', 05:37:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:12 INFO - 'LOGNAME': 'cltbld', 05:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:12 INFO - 'MOZ_NO_REMOTE': '1', 05:37:12 INFO - 'NO_EM_RESTART': '1', 05:37:12 INFO - 'PAGER': '/bin/cat', 05:37:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:12 INFO - 'PWD': '/builds/slave/test', 05:37:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:12 INFO - 'SHELL': '/bin/bash', 05:37:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:12 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:12 INFO - 'USER': 'cltbld', 05:37:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:12 INFO - 'XPC_FLAGS': '0x0', 05:37:12 INFO - 'XPC_SERVICE_NAME': '0', 05:37:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:12 INFO - Downloading/unpacking psutil>=3.1.1 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:37:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:15 INFO - Installing collected packages: psutil 05:37:15 INFO - Running setup.py install for psutil 05:37:15 INFO - building 'psutil._psutil_osx' extension 05:37:15 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 05:37:16 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 05:37:16 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 05:37:16 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 05:37:16 INFO - building 'psutil._psutil_posix' extension 05:37:16 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 05:37:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:37:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:37:16 INFO - ^ 05:37:16 INFO - 1 warning generated. 05:37:16 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 05:37:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:16 INFO - Successfully installed psutil 05:37:16 INFO - Cleaning up... 05:37:17 INFO - Return code: 0 05:37:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:37:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:17 INFO - 'HOME': '/Users/cltbld', 05:37:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:17 INFO - 'LOGNAME': 'cltbld', 05:37:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:17 INFO - 'MOZ_NO_REMOTE': '1', 05:37:17 INFO - 'NO_EM_RESTART': '1', 05:37:17 INFO - 'PAGER': '/bin/cat', 05:37:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:17 INFO - 'PWD': '/builds/slave/test', 05:37:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:17 INFO - 'SHELL': '/bin/bash', 05:37:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:17 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:17 INFO - 'USER': 'cltbld', 05:37:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:17 INFO - 'XPC_FLAGS': '0x0', 05:37:17 INFO - 'XPC_SERVICE_NAME': '0', 05:37:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:37:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:37:19 INFO - Installing collected packages: mozsystemmonitor 05:37:19 INFO - Running setup.py install for mozsystemmonitor 05:37:19 INFO - Successfully installed mozsystemmonitor 05:37:19 INFO - Cleaning up... 05:37:19 INFO - Return code: 0 05:37:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:37:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:37:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:19 INFO - 'HOME': '/Users/cltbld', 05:37:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:19 INFO - 'LOGNAME': 'cltbld', 05:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:19 INFO - 'MOZ_NO_REMOTE': '1', 05:37:19 INFO - 'NO_EM_RESTART': '1', 05:37:19 INFO - 'PAGER': '/bin/cat', 05:37:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:19 INFO - 'PWD': '/builds/slave/test', 05:37:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:19 INFO - 'SHELL': '/bin/bash', 05:37:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:19 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:19 INFO - 'USER': 'cltbld', 05:37:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:19 INFO - 'XPC_FLAGS': '0x0', 05:37:19 INFO - 'XPC_SERVICE_NAME': '0', 05:37:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:20 INFO - Downloading/unpacking jsonschema==2.5.1 05:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:37:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:23 INFO - Downloading functools32-3.2.3-2.tar.gz 05:37:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:37:23 INFO - warning: no files found matching '*.txt' 05:37:23 INFO - no previously-included directories found matching 'build' 05:37:23 INFO - no previously-included directories found matching 'dist' 05:37:23 INFO - no previously-included directories found matching '.git*' 05:37:23 INFO - Installing collected packages: jsonschema, functools32 05:37:23 INFO - Running setup.py install for functools32 05:37:23 INFO - warning: no files found matching '*.txt' 05:37:23 INFO - no previously-included directories found matching 'build' 05:37:23 INFO - no previously-included directories found matching 'dist' 05:37:23 INFO - no previously-included directories found matching '.git*' 05:37:23 INFO - Successfully installed jsonschema functools32 05:37:23 INFO - Cleaning up... 05:37:23 INFO - Return code: 0 05:37:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:23 INFO - 'HOME': '/Users/cltbld', 05:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:23 INFO - 'LOGNAME': 'cltbld', 05:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:23 INFO - 'MOZ_NO_REMOTE': '1', 05:37:23 INFO - 'NO_EM_RESTART': '1', 05:37:23 INFO - 'PAGER': '/bin/cat', 05:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:23 INFO - 'PWD': '/builds/slave/test', 05:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:23 INFO - 'SHELL': '/bin/bash', 05:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:23 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:23 INFO - 'USER': 'cltbld', 05:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:23 INFO - 'XPC_FLAGS': '0x0', 05:37:23 INFO - 'XPC_SERVICE_NAME': '0', 05:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:37:23 INFO - Cleaning up... 05:37:23 INFO - Return code: 0 05:37:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:23 INFO - 'HOME': '/Users/cltbld', 05:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:23 INFO - 'LOGNAME': 'cltbld', 05:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:23 INFO - 'MOZ_NO_REMOTE': '1', 05:37:23 INFO - 'NO_EM_RESTART': '1', 05:37:23 INFO - 'PAGER': '/bin/cat', 05:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:23 INFO - 'PWD': '/builds/slave/test', 05:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:23 INFO - 'SHELL': '/bin/bash', 05:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:23 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:23 INFO - 'USER': 'cltbld', 05:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:23 INFO - 'XPC_FLAGS': '0x0', 05:37:23 INFO - 'XPC_SERVICE_NAME': '0', 05:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:23 INFO - Downloading/unpacking blobuploader==1.2.4 05:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:26 INFO - Downloading blobuploader-1.2.4.tar.gz 05:37:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:37:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:37:26 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 05:37:26 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 05:37:26 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 05:37:26 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 05:37:26 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 05:37:26 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 05:37:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:37:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37:27 INFO - Downloading docopt-0.6.1.tar.gz 05:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:37:27 INFO - Installing collected packages: blobuploader, requests, docopt 05:37:27 INFO - Running setup.py install for blobuploader 05:37:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:37:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:37:27 INFO - Running setup.py install for requests 05:37:28 INFO - Running setup.py install for docopt 05:37:28 INFO - Successfully installed blobuploader requests docopt 05:37:28 INFO - Cleaning up... 05:37:28 INFO - Return code: 0 05:37:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:37:28 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')]} 05:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:28 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')]} 05:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:28 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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:28 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 05:37:28 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 05:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:28 INFO - 'HOME': '/Users/cltbld', 05:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:28 INFO - 'LOGNAME': 'cltbld', 05:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:28 INFO - 'MOZ_NO_REMOTE': '1', 05:37:28 INFO - 'NO_EM_RESTART': '1', 05:37:28 INFO - 'PAGER': '/bin/cat', 05:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:28 INFO - 'PWD': '/builds/slave/test', 05:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:28 INFO - 'SHELL': '/bin/bash', 05:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:28 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:28 INFO - 'USER': 'cltbld', 05:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:28 INFO - 'XPC_FLAGS': '0x0', 05:37:28 INFO - 'XPC_SERVICE_NAME': '0', 05:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:37:28 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-JkD8Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-liPXWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-MkaxcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-qdqsJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-4izPin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-H44LJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-r2fic_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-JtjKDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-_HsMMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DhfnFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:37:29 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Vl4LC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:37:30 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-BHW7kX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:37:30 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Mrpfrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:37:30 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-lrFtyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:30 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Rrdez5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:37:30 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-exkeh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:37:30 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-VNqQ1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:37:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:37:30 INFO - Running setup.py install for manifestparser 05:37:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:37:30 INFO - Running setup.py install for mozcrash 05:37:30 INFO - Running setup.py install for mozdebug 05:37:31 INFO - Running setup.py install for mozdevice 05:37:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:37:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:37:31 INFO - Running setup.py install for mozfile 05:37:31 INFO - Running setup.py install for mozhttpd 05:37:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:37:31 INFO - Running setup.py install for mozinfo 05:37:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:37:31 INFO - Running setup.py install for mozInstall 05:37:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:37:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:37:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:37:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:37:31 INFO - Running setup.py install for mozleak 05:37:31 INFO - Running setup.py install for mozlog 05:37:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:37:32 INFO - Running setup.py install for moznetwork 05:37:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:37:32 INFO - Running setup.py install for mozprocess 05:37:32 INFO - Running setup.py install for mozprofile 05:37:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:37:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:37:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:37:32 INFO - Running setup.py install for mozrunner 05:37:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:37:32 INFO - Running setup.py install for mozscreenshot 05:37:32 INFO - Running setup.py install for moztest 05:37:33 INFO - Running setup.py install for mozversion 05:37:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:37:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:37:33 INFO - Cleaning up... 05:37:33 INFO - Return code: 0 05:37:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:33 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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:33 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 05:37:33 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 05:37:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:33 INFO - 'HOME': '/Users/cltbld', 05:37:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:33 INFO - 'LOGNAME': 'cltbld', 05:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:33 INFO - 'MOZ_NO_REMOTE': '1', 05:37:33 INFO - 'NO_EM_RESTART': '1', 05:37:33 INFO - 'PAGER': '/bin/cat', 05:37:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:33 INFO - 'PWD': '/builds/slave/test', 05:37:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:33 INFO - 'SHELL': '/bin/bash', 05:37:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:33 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:33 INFO - 'USER': 'cltbld', 05:37:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:33 INFO - 'XPC_FLAGS': '0x0', 05:37:33 INFO - 'XPC_SERVICE_NAME': '0', 05:37:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:37:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-TLRTzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:37:33 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)) 05:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:37:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-nbcj5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:37:33 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)) 05:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:37:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-H04nc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:37:33 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)) 05:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:37:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-YAOufv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:37:33 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)) 05:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:37:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-XWCAoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-dfAc1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-f92tlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-cbcaOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-kBz4kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-yXRMHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-M961rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-lNZz9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-OxQ1Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:37:34 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)) 05:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:37:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ofkCoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:37:35 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)) 05:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:35 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-q5Evi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:35 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)) 05:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:37:35 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-uGr6CF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:37:35 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)) 05:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:37:35 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-bbFDCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:37:35 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)) 05:37:35 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)) 05:37:35 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)) 05:37:35 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)) 05:37:35 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)) 05:37:35 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)) 05:37:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:37:35 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 05:37:35 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 05:37:35 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 05:37:35 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 05:37:35 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 05:37:35 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 05:37:37 INFO - Downloading blessings-1.6.tar.gz 05:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:37:37 INFO - Installing collected packages: blessings 05:37:37 INFO - Running setup.py install for blessings 05:37:38 INFO - Successfully installed blessings 05:37:38 INFO - Cleaning up... 05:37:38 INFO - Return code: 0 05:37:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:37:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:37:38 INFO - Reading from file tmpfile_stdout 05:37:38 INFO - Current package versions: 05:37:38 INFO - blessings == 1.6 05:37:38 INFO - blobuploader == 1.2.4 05:37:38 INFO - docopt == 0.6.1 05:37:38 INFO - functools32 == 3.2.3-2 05:37:38 INFO - jsonschema == 2.5.1 05:37:38 INFO - manifestparser == 1.1 05:37:38 INFO - mozInstall == 1.12 05:37:38 INFO - mozcrash == 0.17 05:37:38 INFO - mozdebug == 0.1 05:37:38 INFO - mozdevice == 0.48 05:37:38 INFO - mozfile == 1.2 05:37:38 INFO - mozhttpd == 0.7 05:37:38 INFO - mozinfo == 0.9 05:37:38 INFO - mozleak == 0.1 05:37:38 INFO - mozlog == 3.3 05:37:38 INFO - moznetwork == 0.27 05:37:38 INFO - mozprocess == 0.23 05:37:38 INFO - mozprofile == 0.28 05:37:38 INFO - mozrunner == 6.12 05:37:38 INFO - mozscreenshot == 0.1 05:37:38 INFO - mozsystemmonitor == 0.3 05:37:38 INFO - moztest == 0.7 05:37:38 INFO - mozversion == 1.4 05:37:38 INFO - psutil == 3.1.1 05:37:38 INFO - requests == 1.2.3 05:37:38 INFO - wsgiref == 0.1.2 05:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:37: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')]} 05:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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')]} 05:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:38 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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:38 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 05:37:38 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 05:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:38 INFO - 'HOME': '/Users/cltbld', 05:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:38 INFO - 'LOGNAME': 'cltbld', 05:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:38 INFO - 'MOZ_NO_REMOTE': '1', 05:37:38 INFO - 'NO_EM_RESTART': '1', 05:37:38 INFO - 'PAGER': '/bin/cat', 05:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:38 INFO - 'PWD': '/builds/slave/test', 05:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:38 INFO - 'SHELL': '/bin/bash', 05:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:38 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:38 INFO - 'USER': 'cltbld', 05:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:38 INFO - 'XPC_FLAGS': '0x0', 05:37:38 INFO - 'XPC_SERVICE_NAME': '0', 05:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 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)) 05:37:38 INFO - Cleaning up... 05:37:38 INFO - Return code: 0 05:37:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:37: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')]} 05:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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')]} 05:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:38 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 0x1031bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103251618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbceb4c2d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, '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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 05:37:38 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 05:37:38 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 05:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:38 INFO - 'HOME': '/Users/cltbld', 05:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:38 INFO - 'LOGNAME': 'cltbld', 05:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:38 INFO - 'MOZ_NO_REMOTE': '1', 05:37:38 INFO - 'NO_EM_RESTART': '1', 05:37:38 INFO - 'PAGER': '/bin/cat', 05:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:38 INFO - 'PWD': '/builds/slave/test', 05:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:38 INFO - 'SHELL': '/bin/bash', 05:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:37:38 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:38 INFO - 'USER': 'cltbld', 05:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:38 INFO - 'XPC_FLAGS': '0x0', 05:37:38 INFO - 'XPC_SERVICE_NAME': '0', 05:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:37:39 INFO - Cleaning up... 05:37:39 INFO - Return code: 0 05:37:39 INFO - Running post-action listener: _resource_record_post_action 05:37:39 INFO - Running post-action listener: _start_resource_monitoring 05:37:39 INFO - Starting resource monitoring. 05:37:39 INFO - [mozharness: 2016-08-08 12:37:39.065867Z] Finished create-virtualenv step (success) 05:37:39 INFO - [mozharness: 2016-08-08 12:37:39.066805Z] Running install step. 05:37:39 INFO - Running pre-action listener: _resource_record_pre_action 05:37:39 INFO - Running main action method: install 05:37:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:37:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:37:39 INFO - Reading from file tmpfile_stdout 05:37:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:37:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:37:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:37:39 INFO - Reading from file tmpfile_stdout 05:37:39 INFO - Output received: 05:37:39 INFO - Usage: mozinstall [options] installer 05:37:39 INFO - Options: 05:37:39 INFO - -h, --help show this help message and exit 05:37:39 INFO - -d DEST, --destination=DEST 05:37:39 INFO - Directory to install application into. [default: 05:37:39 INFO - "/builds/slave/test"] 05:37:39 INFO - --app=APP Application being installed. [default: firefox] 05:37:39 INFO - mkdir: /builds/slave/test/build/application 05:37:39 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'] 05:37:39 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 05:38:13 INFO - Reading from file tmpfile_stdout 05:38:13 INFO - Output received: 05:38:13 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:38:13 INFO - Running post-action listener: _resource_record_post_action 05:38:13 INFO - [mozharness: 2016-08-08 12:38:13.626019Z] Finished install step (success) 05:38:13 INFO - [mozharness: 2016-08-08 12:38:13.626169Z] Running run-tests step. 05:38:13 INFO - Running pre-action listener: _resource_record_pre_action 05:38:13 INFO - Running main action method: run_tests 05:38:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:13 INFO - 2016-08-08 05:38:13.640 screenresolution[1640:7545] starting screenresolution argv=screenresolution get 05:38:13 INFO - 2016-08-08 05:38:13.643 screenresolution[1640:7545] Display 0: 1600x1200x32@60 05:38:13 INFO - 2016-08-08 05:38:13.649 screenresolution[1641:7548] starting screenresolution argv=screenresolution list 05:38:13 INFO - Available Modes on Display 0 05:38:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:13 INFO - Intel Iris: 05:38:13 INFO - Chipset Model: Intel Iris 05:38:13 INFO - Type: GPU 05:38:13 INFO - Bus: Built-In 05:38:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:13 INFO - Vendor: Intel (0x8086) 05:38:13 INFO - Device ID: 0x0a2e 05:38:13 INFO - Revision ID: 0x0009 05:38:13 INFO - Displays: 05:38:13 INFO - Display: 05:38:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:13 INFO - Display Serial Number: 1600x1200 60 05:38:13 INFO - Main Display: Yes 05:38:13 INFO - Mirror: Off 05:38:13 INFO - Online: Yes 05:38:13 INFO - Rotation: Supported 05:38:13 INFO - Return code: 0 05:38:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:38:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:38:13 INFO - Python 2.7.3 05:38:13 INFO - Return code: 0 05:38:13 INFO - grabbing minidump binary from tooltool 05:38:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:13 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 0x103120d30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103118b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:38:13 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 05:38:13 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 05:38:13 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 05:38:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:14 INFO - Return code: 0 05:38:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:38:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:38:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:38:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:38:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:38:14 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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 05:38:14 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0067 --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 05:38:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hqdoMXWXFO/Render', 05:38:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:14 INFO - 'HOME': '/Users/cltbld', 05:38:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:14 INFO - 'LOGNAME': 'cltbld', 05:38:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:38:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:14 INFO - 'MOZ_NO_REMOTE': '1', 05:38:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:38:14 INFO - 'NO_EM_RESTART': '1', 05:38:14 INFO - 'PAGER': '/bin/cat', 05:38:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:14 INFO - 'PWD': '/builds/slave/test', 05:38:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:38:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:14 INFO - 'SHELL': '/bin/bash', 05:38:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RBJHGD8Dz4/Listeners', 05:38:14 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:38:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:14 INFO - 'USER': 'cltbld', 05:38:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:14 INFO - 'XPC_FLAGS': '0x0', 05:38:14 INFO - 'XPC_SERVICE_NAME': '0', 05:38:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:14 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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 05:38:14 INFO - mozversion application_buildid: 20160808030441 05:38:14 INFO - mozversion application_changeset: e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 05:38:14 INFO - mozversion application_display_name: Nightly 05:38:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:14 INFO - mozversion application_name: Firefox 05:38:14 INFO - mozversion application_remotingname: firefox 05:38:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:38:14 INFO - mozversion application_vendor: Mozilla 05:38:14 INFO - mozversion application_version: 51.0a1 05:38:14 INFO - mozversion platform_buildid: 20160808030441 05:38:14 INFO - mozversion platform_changeset: e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 05:38:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:38:14 INFO - mozversion platform_version: 51.0a1 05:38:14 INFO - using testdate: 1470659894 05:38:14 INFO - actual date: 1470659894 05:38:14 INFO - starting webserver on 'localhost:49281' 05:38:14 INFO - SUITE-START | Running 2 tests 05:38:14 INFO - TEST-START | dromaeo_css 05:38:14 INFO - operating with platform_type : mac_ 05:38:14 INFO - Initialising browser for dromaeo_css test... 05:38:14 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmphqW6lI/profile http://localhost:49281/getInfo.html) 05:38:16 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 05:38:16 INFO - PROCESS | 1651 | colorDepth:24 05:38:16 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 05:38:16 INFO - PROCESS | 1651 | __metrics 05:38:16 INFO - PROCESS | 1651 | 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" 05:38:16 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:38:17 INFO - TEST-INFO | 1651: exit 0 05:38:17 INFO - Browser initialized. 05:38:17 INFO - Running cycle 1/1 for dromaeo_css test... 05:38:17 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmphqW6lI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:29 INFO - PROCESS | 1654 | RSS: Main: 243449856 05:38:29 INFO - PROCESS | 1654 | 05:39:20 INFO - PROCESS | 1654 | 1470659960275 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] 05:41:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 05:41:34 INFO - PROCESS | 1654 | RSS: Main: 367857664 05:41:34 INFO - PROCESS | 1654 | 05:44:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 05:44:38 INFO - PROCESS | 1654 | RSS: Main: 387354624 05:44:38 INFO - PROCESS | 1654 | 05:47:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 05:47:41 INFO - PROCESS | 1654 | RSS: Main: 355110912 05:47:41 INFO - PROCESS | 1654 | 05:50:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 05:50:44 INFO - PROCESS | 1654 | RSS: Main: 357642240 05:50:44 INFO - PROCESS | 1654 | 05:53:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 05:53:48 INFO - PROCESS | 1654 | RSS: Main: 348213248 05:53:48 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 05:56:51 INFO - PROCESS | 1654 | RSS: Main: 468832256 05:56:51 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | __start_tp_report 05:56:51 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 05:56:51 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 05:56:51 INFO - PROCESS | 1654 | |i|pagename|runs| 05:56:51 INFO - PROCESS | 1654 | |0;dojo.html;2825.5761635754648;3371.629120796712;3378.543896573962;3383.0865666270106;3441.741869359799;5611.186377975191;5711.942880571193;5773.971130144343;5791.21818554495;5838.562107841907;5687.7156142192825;5737.540996720252;5821.941780582193;5863.618864773786;5869.530437564995;5149;5175.715335656538;5224.660397074197;5231.869203269911;5301.1783173608155;5297.814576489824;5298.973505132469;5298.973505132488;5314.202869569557;5356.705381204032;5168.3281173447585;5216.217567364888;5217.243499692561;5272.92090618641;5309.814156504543;5248.950209958024;5255.579553635738;5258.13240815265;5260.237265596467;5262.894742105174;4251.5748425157235;5143.022825663112;5221.451747566527;5262.631615786897;5332.120200166968;5649.322081350264;5804.2744656917885;5825.7378417971295;5854.326752423483;5857.443542863463;6266.934621114376;6326.082718005863;6326.462250708695;6329.36706329368;6330.208723909512;6207.906881396782;6272.749090036439;6316.368363163693;6393.488520918362;6412.583182093195;5882.558808089368;5970.0746384310505;5973.163757074013;5975.073863551156;5978.521718262528;6243.406876346739;6318.052292156213;6319.873602527924;6326.051092336186;6334.809955701337;6103.664298463538;6162.630242185393;6163.568550201443;6253.6560489173535;6265.96867015662;6351.650659213695;6371.936280637227;6372.74509019634;6380.106785050097;6381.936180638227;6072.696365181723;6278.963970944835;6298.212723409574;6308.968455157695;6319.8104056878365;6140.513092197667;6167.814965551041;6220.533459990519;6273.9372606273355;6277.18396608438;5015.097282489187;5017.347744793154;5029.044481548494;5037.11850426174;5067.619928505377;4520.118576877478;4524.208263553931;4541.682082254277;4555.954440455619;4597.08058388317;5600.52395546375;5672.177534257592;5686.744096515584;5698.2592263005545;5766.423357664284;6345.270293916166;6350.809475715736;6351.555391122577;6352.745890164341;6352.777652782131;4916.680415772963;5671.602987790815;5749.080147176432;5787.363390027093;5795.797147099831;5382.058139825514;5450.073487507133;5458.6724796512335;5503.669779813224;5506.752196151103;6273.027680709497;6282.560220784684;6321.494280457644;6323.715432805627;6363.840903977252;6367.745290188526;6370.140031095742;6370.968145159244;6378.266499352449;6391.96804015977;6246.656433896179;6356.586821856565;6374.649394283359;6383.489320854228;6398.424141827258;6099.340048628031;6118.479929206067;6145.047517634774;6147.0779383092895;6160.476359509492;6283.2460104787715;6307.43233109004;6319.273283572448;6331.778387756406;6339.1125242467015;5001.724905130107;6188.341252463911;6198.380161983766;6219.751209951552;6222.471089957223;5955.851103722268;6019.759209631741;6134.723937422916;6221.06683997404;6227.221597300338;6019.518438524995;6088.330119245226;6095.876406903803;6096.658735078276;6115.3578874217565;6201.255849297932;6206.503479721701;6213.285472170577;6219.284782250099;6226.221722284715;5844.26946631671;5922.141289512911;6049.727762250797;6068;6077.058056001327 05:56:51 INFO - PROCESS | 1654 | |1;ext.html;492.3033907021565;522.9645302301441;526.2632314759336;545.4136802936841;555.4639772619421;26309.210723678298;31586;32733.18167045822;41225;42204.36693449593;44016.77991610045;44394;44440.55559444405;44461.333080003824;44520.77739611297;9690.46702431366;9862.654807081753;9866.506674666274;9867.309288349818;9873.851892221623;8811.162939520767;9012.594433250486;9806.411615303105;11756.23584467007;12088.758224835541;12152.878471215306;12192.81710774339;12232.388380580935;12256.142070055099;12296.5696200633;12106.576269830557;12188.81716774249;12194.207376520499;12203.145779795417;12216.450259738333;8723.825523489495;10495.527701253463;11823.881761182363;11864.406779660982;12088.274703517729;41612.58387416148;42022.949426264284;42142;42157.367639485434;42174.73475795731;3336.616289126733;3644.344018076745;4063.5733248009124;4106.77412742299;4272.558024078441;26683.332916677045;31940.680593194003;32664.183395415068;32828.6717132828;33349.99950001504;3177.507486339621;3340.7160391366765;3345.8327083645963;3372.241245719718;3385.1198688340965;504482;507255;510761;511222;514277;60527;60927.69536152291;61090.69454652787;61100;61126.69436652877;63735;63779;63792.68103659545;63874;64046.35953640404;14696.632584185267;16668.083255420825;16688.415905443253;16704.6659066818;16712.49862504127;11514.539418423337;12770.106092573431;12826.422810973529;12841.678958025937;12847.550335738204;12971.740565188644;14049.367778449994;16216.270267837739;16384.508464746075;16612.750808737877;5766.952731780241;5770.653760774367;5915.935131676253;6708;6853.657317134123;878.9208971192495;915.514777168102;922.5156792683895;1040.1730624153815;1143.3654321851452;30387;30499.69500305013;30873.845630771702;31163;31247.843760781052;1026.9127124194379;1075.2634445404767;1082.442542090824;1083.3066837224183;1085.5277954089995;15553.30010149546;15599.142047187514;15661.765073523906;15846.841531584767;15876;489482;496446;503645;505227;508581;59807.70096149491;59937.100943485886;59985.10022349668;59985.70007149936;60120.699396502736;1063.5426766490484;1364.7406992671558;1383.5365152673967;1393.6028231953844;1406.7749160134329;30821.22946926345;31376;31380.529292061095;31443.52834707527;32269.193270168435;50387.74806125946;50934.74532627461;50962.49037509726;51003.979920402126;51045.74477127591;534347;535662;536353.3182334063;538574;539358;26354.20937371882;26583.335416613965;26612.334691632863;27570.448591027667;40942.1811563773;48857.5114248853;48946.75526622344;48959.02081958412;49161.508384915694;49325.506744933526;39695.2060958785;39869.800650996556;40052.39921401123;40261.59738402696;41334.586654133076;39177;39541;39718.00704982399;40815.59184408118;40855.795721021204;38325;38895.8331250063;39043.609563905135;39118.41322380109;39248.411273830345;33784.986450406526;34206.65793342035;35056.298874021864;35244.47133292951;35257.94226173219;25360.365990849637;25592.488150237263;26179.869100654374;26351.20946371612;26577.867110664323 05:56:51 INFO - PROCESS | 1654 | |2;jquery.html;346.7260863917505;379.4592705394813;379.6905521999572;386.7621412831109;410.5996653263069;15205.619859503518;15407.614809629766;20872.373828785123;21156.047977840997;21292.89353553234;19628.70556941647;20061.1975520979;20214.292499762512;20631.07160177792;20710.68933965992;4830.806767729296;5827.533797296206;5956.612820166675;5971.492423144038;5984.251968503937;6459.51553633477;6580.3748643879;6600.93399066008;6621.370969757865;6648.5678430901835;6393.904091438632;6482.773102941398;6524.510661700391;6553.606783592976;6569.211694596655;6254.15568898202;6498.9350106498805;6529.2164940207;6539.149910511603;6567.835804104888;5518.558515318765;5628.465295796893;5630.859228519279;5631.267935168443;5633.690147041911;25544;26126.216213513624;26143.08499202519;26273.3431664208;26365.604515932275;1975.5555000124857;2362.70466191726;2484.0808900706675;2573.5882258838496;2871.6697579778174;8155.836883262361;10184.694459166238;10281.383117012929;10384.32501887375;12183.93908030463;1848.805875383079;1851.139220262571;1867.8692491525599;1875.6061227142316;1923.3749031564798;115926.42036789762;120541;122433;123560;124570;10917.890821091847;11453.14101442395;11646;11647.12646551512;11988.700282492833;14309.141451512734;14347.210903400413;14348.49780257686;14348.641283967985;14349.56951291463;8132.512049277062;8376.539290338967;8416.158384161658;8502.06477287498;8538.957305213435;8366.623501942428;8370.372222083368;8376.49741015541;8534.487930724052;8562.143785621513;3722.8510859565854;3741.0086327123336;4381.255186618282;4468.932966005513;4558.133954548625;1837.80703026183;1917.8178073083034;1919.539310565482;1919.664058789734;1923.4133589255412;807.463037080335;832.1636755061124;902.5757893789909;905.2983937448398;944.6504793226413;19718.507037323896;20403.795962040487;21082.051307691192;21084.262050828147;21090.57818843645;816.5386556595505;905.8460061789511;925.893522244937;930.1907340613709;1009.6415772400838;10410.739731506621;10855.782884342269;10892.945535272273;10943.28868623537;10978.474950761369;533209;565270;588328;618150;619287;73018;106604.46697766462;121630;121632;130625;937.0863408176973;974.2790335152079;995.8556009378744;996.7490799047149;996.7707427291618;18492.907535462775;18666.533336666147;18698.34555790541;18721.531961700523;18728.344507942158;54267.72866135644;54547.72726136344;55410.06064787713;55691.44308556862;56265;63809;79458.80811787712;81441;83533.91165220918;84507.73238401551;7827.099883513241;7894.21057894206;8092.71675491355;8157.918420815953;8383.910091688042;10724.410157441416;12591.93704031474;12935.288559129338;12975.545855894998;12994.610161695164;10596.311239769699;11010.614628487965;11167.319843237727;11232.887671123512;11339.943300283447;9716.854247186156;11081.833772493257;11093.667189984313;11312.6040588579;11618.76762464763;9173.128552787475;10699.411532365795;11759.706007349885;11908.225965312227;11940.164188506722;7748.7675369739;8200.630971606173;8215.080690038896;8251.33989280868;8275.83448331042;9017.684381046633;11064.944675276894;11433.59982400612;11457.197996140523;11485.598004069818 05:56:51 INFO - PROCESS | 1654 | |3;mootools.html;1353.4383230959152;1529.923503824809;1535.1249787621055;1564.8185619857006;1870.1958157992067;7723.536587804736;8195.672173113084;8201.097879233286;8204.548749818758;8227.382946279024;8311.168883111686;8600.22597966183;8605.096464871176;8711.695090671828;8713.912860871389;2329.8252631052655;2360.4570948681812;2365.7161140663056;2367.9289621311386;2373.1812524679044;2316.8494047886834;2341.180586794617;2362.5629258587132;2365.9881700591473;2367.6922000140044;1954.3257576136211;2330.720313562375;2336.100601268507;2349.95300093998;2352.7411984681758;2296.954060918789;2323.8838058097112;2328.557574060923;2330.3242059802637;2339.0643742502966;2326.336993956731;2327.639215921518;2327.918522851692;2329.6971393718877;2334.0430423526273;8724.214820666108;8749.256313213384;8834.337424693174;8846.5134417607;8861.069587693328;5112.079825631385;5112.693238405707;5275.894482110375;5276.92084618731;5282.577393808525;3549.8757543486113;3640.9271814563826;3648.799316037617;3650.981745091284;3660.4509323601405;925.6343744220995;1040.287403128859;1050.863387759594;1071.7588542577935;1077.8006068877205;11497.195196336259;11586.652400428;11592.942035289769;11610.18728688992;11670.474828632732;4378.605925466723;4530.22986092365;4531.56950089741;4643.698159619614;4874.805007799649;9003.189712925869;9173.724788256362;9176.44941303524;9181.173694367406;9186.86219706705;5226.294450249167;5232.947670523322;5234.214867769865;5245.10833158364;5260.92108618371;4351.390805287262;4514.706544074625;4686.695364801345;4809.134355815917;4911.140550403665;3714.795686237283;3735.6637902588895;3817.10298079951;3849;3870.2453021661067;1153.2907262033789;1163.8021536338842;1168.2523185161504;1171.1801738782885;1175.9823602645968;847.7075408983891;868;894.4007514964859;913.8263729891299;943.1511639524346;5498.45015498447;5727.427257274239;5977.760889564455;6006.579539432285;6112.083187521818;750.816050067726;758.7268583310004;882.5278476015301;916.8028873792167;955.1212884146602;3831.578526362098;3873.9225215496094;3965.2267807777785;4017.296973029708;4037.7779222143063;10363.481825908675;10846.891531084588;10847.294925829796;10882.564697412015;10898.782024359469;10396.688099357032;10588.682339529827;10775.245732798785;10815.080718139046;10821.29661572011;8100.432969692183;8162.510249385056;8286.129956354616;8303.667853285922;8304.086550479442;5185.559227465707;5826.155207494974;5923.674197919156;5948.286205655176;5948.8810223796145;9539.427634341684;9796.559154838193;9952.55213515379;10012.948640392653;10090.243231757795;10182.389056656624;10241.897581024094;10262.384256944608;10264.230182736474;10266.691999240034;6571.5399922005;6767.154105736783;6915.101036865175;7034.859302813813;7166.964165179141;8289.129641387808;8353.331733461187;8504.744857654281;8522.147785221428;8525.317974562144;6312.526560508072;7034.5427547209265;7060.364567188978;7547.094348677974;7691.38468922496;7308.342249197385;7418.777436676693;7427.129350051837;7427.2201418850245;7434.36807871337;6992.300769922967;6997.1253593300835;7046.859062818818;7068.399186069241;7073.363397294268;4846.176150054429;4918.213085906238;4951.851444456672;4956.950430495793;5034.018366418513;4861.586765125004;4892.0705066037335;4988.301637770643;5011.123053465556;5040.319556859924 05:56:51 INFO - PROCESS | 1654 | |4;prototype.html;299.94600971825076;303.9589655396521;315.08939165810796;318.3505648477107;322.1688044844302;4288.769839683363;4671.27595222741;5368.409474957756;5427.891442171159;5454.4545545445435;4407.955920440794;4621.237495813195;5105.8767071244265;5121.9487805121935;5425.29103332451;4075.694322925785;4141.917161656765;4146.543880173179;4155.272827255233;4173.958260417403;3785.413260944558;3830.080780612647;4123.60825721556;4173.3114036184;4179.623833854953;3393.915152121192;3418.8974330770116;4069.287874621944;4139.668826493873;4151.418801367811;3535.515083664867;4031.9798401007806;4063.553009168989;4069.2878746219294;4071.246819338432;2967.4065186962694;3329.667033296651;3530.2057037166433;3531.2231309111953;3535.8055306958363;5113.667611605234;5306.389765176977;5309.283246761676;5346.492083252085;5346.705931173784;4641.907161856778;4643.721376717408;4667.603253723438;4674.2053850845095;4697.459792123881;5566.524870909212;5682.346530149003;5697.914531282034;5717.827225654225;5720.768900128953;3719.2189640175598;3909.257241124177;3918.5689574146822;3923.1565213478953;3928.2143571285856;5913.467787899067;5927.229460170151;5941.257342832146;5949.524038076944;5951.196588460589;3333.6997471744417;3920.3923391874305;3934.4688467057154;3936.291467535814;3941.581030828899;3526.2771131917893;4018.738781979162;4021.738586991836;4024.8730355500506;4034.919301613951;3894.2990261753157;4512.2554778461845;4586.096769840479;4627.565454709051;4631.397918270603;5016.297718319438;5685.1909220822145;5708.343540492813;5715.857103572361;5717.141429285357;1278.8209650648917;1278.859325474197;1305.5169587252587;1363.8363396392497;1372.1218420211073;763.2939530933864;823.8146417056234;860.3977215948861;872.9611762003196;877.8507653698887;4582.518835522289;5213.973930130325;5229.470107677303;5245.115186647405;5297.470252974672;5529.502344788988;5696.943030569724;5737.454941780524;5769.846030793774;5776.277965254343;3863.141275456142;4515.848458643025;4840.298156767249;5124.641275110781;5147.485251474823;2545.9307091021815;2664.986675066651;2989.697932591892;3056.465118604235;3094.8394352117957;5980.192673989043;6017.916774980546;6019.277686677539;6025.276966764016;6037.426444487766;5566.66600003993;5646.2942132233475;5788.507976821933;6026.246719160105;6073.939260607426;6107.96946015276;6137.225606252212;6138.508919286446;6145.927036481759;6152.538559607958;5418.322709661293;5501.807436739695;5844.327902291291;6011.458968692838;6036.215292012011;5302.310699609026;5850.970745146417;5857.355690873916;5859.355470898283;5862.267216597926;4863.90272194547;6141.293751218488;6150.231221097363;6176.35148309421;6181.660008699565;3015.8190508569555;3906.3749800032997;3929.430232616312;3951.2887680217837;3987.641112299791;5217.443452708069;5625.353084395347;5888.705564721832;5923.911141332969;5950.727100095017;5269.578433725216;5407.567394608501;5689.431056894443;5707.973669347321;5827.329857066322;5118.872028199325;5676.4323567642905;5735.483806457439;5837.007708689618;5853.824385268449;4963.057019166491;5317.271886636781;5651.57613179005;5666.405027472221;5681.289838770153;5751.677114263659;5761.48146666802;5763.769449222152;5783.942160578508;5803.361630220588;4676.415448068991;5065.088284108749;5640.559258852015;5741.1388291756575;5756.539476841926 05:56:51 INFO - PROCESS | 1654 | |5;yui.html;657.7171816119069;795.3835777272614;803.7347675267163;810.145296711969;846.6655671009951;15012.324445399956;16157.43448979286;16164.83835161648;16225.83774162258;16243.025418474885;13117.081804273705;14287.428502859899;14341.28293585319;14371.35328910199;14373.137611743303;835.6114406800837;877.9253763430116;882.74400423877;883.9823203535925;884.4030279561302;856.5246288309985;859.2181115185183;859.4671303791658;861.8276344731048;862.0173002776808;902.5351943748974;905.0903841639143;907.4827348411408;909.2589539525299;909.8726178335038;898.2544488074948;899.3344924755667;899.9910000900005;900.7207765592655;903.2457897655399;671.2012704881175;676.4453148418299;676.9153855768029;677.2347247610218;680.6392611915693;13169;13389.19664820114;13408.26254555999;13428.395722192525;14105.717885642332;11202.327860328325;11734.647960561197;12031.458584363725;12077.456514456782;12342.691432714164;10812.51343689528;11327.660170194908;11330.773384532264;11354.48904799286;11805.055595552336;3744.2324323513885;3750.212455384345;3778.9811050944845;3909;3913.3347330953807;121428;125802.37098814538;127339;127791.99999999907;128275;9264.073592640681;9351.672691455766;9457.858132127885;9464.242860571137;9680.370775899544;20254.594908101757;20256.493587660425;20307.593848122953;20312;20337.694934575993;7983.084059825716;8118.75643730689;8205.876911846328;8312.8753068704;8384.413091083565;4372.278574035312;4460.1525710114975;4481.775911204492;4485.304777759453;4668.929966050512;3551.0412188708906;3631.854725810938;3642.5446819147605;3677.6138505457075;3679.3745063339297;645.9903101453482;646.812424396929;653.3825534869598;656.4453037183572;666.237158453568;497.1647631978239;633.6134957675814;653.565379022954;654.2182092399632;684.8116767888871;8918.73243802687;9116.361854670242;9460.24318054554;9645.807083858284;9661.420314781135;551.1124398933716;552.6739223857943;623.2894500269696;635.4131667885524;675.3213020914048;4423.970453913642;4606.424196975378;4714.222153344728;4727.314539391769;4816.903661926742;82589.58705206435;98004;103801;148710.25644871706;154750;88796.5560172195;89373;133782;137018;139461.3026934879;108754.3686844698;110417.34373984397;110528;112894.43552782183;113369.43315283535;9743.171830394496;9986.400815951067;10017.749556261151;10020.599176032876;10032.197424206191;56089;59480;60717;61653.45866353377;62327;90924.09075909156;92201.85091671025;95238;95820.60448488843;97729;13369.799453008407;13828.654283642587;14031.29843507841;14199.716005680035;14500.984931055147;14563.490277840225;14571.85428145705;15113.47102851395;15195;15278;13676.384562694504;14132.081414708011;14144.292785360485;14233.173778963426;14335.713285734435;12121.909028187505;13532.929963856139;13784.724305513633;13980.930095349458;14126.576202713935;13557.389917453935;13570.86429135696;13647.79528307096;13884.58346249614;14234.928825355806;11041.72395690082;11356.545738170711;11592.6522204334;11702.4733886977;11964.222325548812;10596.841047384449;11182.161337899528;11551;11564.595239166589;11572.537098516303 05:56:51 INFO - PROCESS | 1654 | __end_tp_report 05:56:51 INFO - PROCESS | 1654 | __start_cc_report 05:56:51 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1373 05:56:51 INFO - PROCESS | 1654 | __end_cc_report 05:56:51 INFO - PROCESS | 1654 | __startTimestamp1470661011938__endTimestamp 05:56:51 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | ------- Summary: start ------- 05:56:51 INFO - PROCESS | 1654 | Number of tests: 6 05:56:51 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5792.07 Median:6068.00 stddev:670.73 (11.1%) stddev-sans-first:631.13 05:56:51 INFO - PROCESS | 1654 | Values: 2825.6 3371.6 3378.5 3383.1 3441.7 5611.2 5711.9 5774.0 5791.2 5838.6 5687.7 5737.5 5821.9 5863.6 5869.5 5149.0 5175.7 5224.7 5231.9 5301.2 5297.8 5299.0 5299.0 5314.2 5356.7 5168.3 5216.2 5217.2 5272.9 5309.8 5249.0 5255.6 5258.1 5260.2 5262.9 4251.6 5143.0 5221.5 5262.6 5332.1 5649.3 5804.3 5825.7 5854.3 5857.4 6266.9 6326.1 6326.5 6329.4 6330.2 6207.9 6272.7 6316.4 6393.5 6412.6 5882.6 5970.1 5973.2 5975.1 5978.5 6243.4 6318.1 6319.9 6326.1 6334.8 6103.7 6162.6 6163.6 6253.7 6266.0 6351.7 6371.9 6372.7 6380.1 6381.9 6072.7 6279.0 6298.2 6309.0 6319.8 6140.5 6167.8 6220.5 6273.9 6277.2 5015.1 5017.3 5029.0 5037.1 5067.6 4520.1 4524.2 4541.7 4556.0 4597.1 5600.5 5672.2 5686.7 5698.3 5766.4 6345.3 6350.8 6351.6 6352.7 6352.8 4916.7 5671.6 5749.1 5787.4 5795.8 5382.1 5450.1 5458.7 5503.7 5506.8 6273.0 6282.6 6321.5 6323.7 6363.8 6367.7 6370.1 6371.0 6378.3 6392.0 6246.7 6356.6 6374.6 6383.5 6398.4 6099.3 6118.5 6145.0 6147.1 6160.5 6283.2 6307.4 6319.3 6331.8 6339.1 5001.7 6188.3 6198.4 6219.8 6222.5 5955.9 6019.8 6134.7 6221.1 6227.2 6019.5 6088.3 6095.9 6096.7 6115.4 6201.3 6206.5 6213.3 6219.3 6226.2 5844.3 5922.1 6049.7 6068.0 6077.1 05:56:51 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:66360.74 Median:39147.71 stddev:137164.36 (350.4%) stddev-sans-first:137459.99 05:56:51 INFO - PROCESS | 1654 | Values: 492.3 523.0 526.3 545.4 555.5 26309.2 31586.0 32733.2 41225.0 42204.4 44016.8 44394.0 44440.6 44461.3 44520.8 9690.5 9862.7 9866.5 9867.3 9873.9 8811.2 9012.6 9806.4 11756.2 12088.8 12152.9 12192.8 12232.4 12256.1 12296.6 12106.6 12188.8 12194.2 12203.1 12216.5 8723.8 10495.5 11823.9 11864.4 12088.3 41612.6 42022.9 42142.0 42157.4 42174.7 3336.6 3644.3 4063.6 4106.8 4272.6 26683.3 31940.7 32664.2 32828.7 33350.0 3177.5 3340.7 3345.8 3372.2 3385.1 504482.0 507255.0 510761.0 511222.0 514277.0 60527.0 60927.7 61090.7 61100.0 61126.7 63735.0 63779.0 63792.7 63874.0 64046.4 14696.6 16668.1 16688.4 16704.7 16712.5 11514.5 12770.1 12826.4 12841.7 12847.6 12971.7 14049.4 16216.3 16384.5 16612.8 5767.0 5770.7 5915.9 6708.0 6853.7 878.9 915.5 922.5 1040.2 1143.4 30387.0 30499.7 30873.8 31163.0 31247.8 1026.9 1075.3 1082.4 1083.3 1085.5 15553.3 15599.1 15661.8 15846.8 15876.0 489482.0 496446.0 503645.0 505227.0 508581.0 59807.7 59937.1 59985.1 59985.7 60120.7 1063.5 1364.7 1383.5 1393.6 1406.8 30821.2 31376.0 31380.5 31443.5 32269.2 50387.7 50934.7 50962.5 51004.0 51045.7 534347.0 535662.0 536353.3 538574.0 539358.0 26354.2 26583.3 26612.3 27570.4 40942.2 48857.5 48946.8 48959.0 49161.5 49325.5 39695.2 39869.8 40052.4 40261.6 41334.6 39177.0 39541.0 39718.0 40815.6 40855.8 38325.0 38895.8 39043.6 39118.4 39248.4 33785.0 34206.7 35056.3 35244.5 35257.9 25360.4 25592.5 26179.9 26351.2 26577.9 05:56:51 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34335.92 Median:1609.20 stddev:97640.60 (6067.7%) stddev-sans-first:97881.16 05:56:51 INFO - PROCESS | 1654 | Values: 346.7 379.5 379.7 386.8 410.6 15205.6 15407.6 20872.4 21156.0 21292.9 19628.7 20061.2 20214.3 20631.1 20710.7 4830.8 5827.5 5956.6 5971.5 5984.3 6459.5 6580.4 6600.9 6621.4 6648.6 6393.9 6482.8 6524.5 6553.6 6569.2 6254.2 6498.9 6529.2 6539.1 6567.8 5518.6 5628.5 5630.9 5631.3 5633.7 25544.0 26126.2 26143.1 26273.3 26365.6 1975.6 2362.7 2484.1 2573.6 2871.7 8155.8 10184.7 10281.4 10384.3 12183.9 1848.8 1851.1 1867.9 1875.6 1923.4 115926.4 120541.0 122433.0 123560.0 124570.0 10917.9 11453.1 11646.0 11647.1 11988.7 14309.1 14347.2 14348.5 14348.6 14349.6 8132.5 8376.5 8416.2 8502.1 8539.0 8366.6 8370.4 8376.5 8534.5 8562.1 3722.9 3741.0 4381.3 4468.9 4558.1 1837.8 1917.8 1919.5 1919.7 1923.4 807.5 832.2 902.6 905.3 944.7 19718.5 20403.8 21082.1 21084.3 21090.6 816.5 905.8 925.9 930.2 1009.6 10410.7 10855.8 10892.9 10943.3 10978.5 533209.0 565270.0 588328.0 618150.0 619287.0 73018.0 106604.5 121630.0 121632.0 130625.0 937.1 974.3 995.9 996.7 996.8 18492.9 18666.5 18698.3 18721.5 18728.3 54267.7 54547.7 55410.1 55691.4 56265.0 63809.0 79458.8 81441.0 83533.9 84507.7 7827.1 7894.2 8092.7 8157.9 8383.9 10724.4 12591.9 12935.3 12975.5 12994.6 10596.3 11010.6 11167.3 11232.9 11339.9 9716.9 11081.8 11093.7 11312.6 11618.8 9173.1 10699.4 11759.7 11908.2 11940.2 7748.8 8200.6 8215.1 8251.3 8275.8 9017.7 11064.9 11433.6 11457.2 11485.6 05:56:51 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5630.08 Median:4954.40 stddev:3168.52 (64.0%) stddev-sans-first:3161.10 05:56:51 INFO - PROCESS | 1654 | Values: 1353.4 1529.9 1535.1 1564.8 1870.2 7723.5 8195.7 8201.1 8204.5 8227.4 8311.2 8600.2 8605.1 8711.7 8713.9 2329.8 2360.5 2365.7 2367.9 2373.2 2316.8 2341.2 2362.6 2366.0 2367.7 1954.3 2330.7 2336.1 2350.0 2352.7 2297.0 2323.9 2328.6 2330.3 2339.1 2326.3 2327.6 2327.9 2329.7 2334.0 8724.2 8749.3 8834.3 8846.5 8861.1 5112.1 5112.7 5275.9 5276.9 5282.6 3549.9 3640.9 3648.8 3651.0 3660.5 925.6 1040.3 1050.9 1071.8 1077.8 11497.2 11586.7 11592.9 11610.2 11670.5 4378.6 4530.2 4531.6 4643.7 4874.8 9003.2 9173.7 9176.4 9181.2 9186.9 5226.3 5232.9 5234.2 5245.1 5260.9 4351.4 4514.7 4686.7 4809.1 4911.1 3714.8 3735.7 3817.1 3849.0 3870.2 1153.3 1163.8 1168.3 1171.2 1176.0 847.7 868.0 894.4 913.8 943.2 5498.5 5727.4 5977.8 6006.6 6112.1 750.8 758.7 882.5 916.8 955.1 3831.6 3873.9 3965.2 4017.3 4037.8 10363.5 10846.9 10847.3 10882.6 10898.8 10396.7 10588.7 10775.2 10815.1 10821.3 8100.4 8162.5 8286.1 8303.7 8304.1 5185.6 5826.2 5923.7 5948.3 5948.9 9539.4 9796.6 9952.6 10012.9 10090.2 10182.4 10241.9 10262.4 10264.2 10266.7 6571.5 6767.2 6915.1 7034.9 7167.0 8289.1 8353.3 8504.7 8522.1 8525.3 6312.5 7034.5 7060.4 7547.1 7691.4 7308.3 7418.8 7427.1 7427.2 7434.4 6992.3 6997.1 7046.9 7068.4 7073.4 4846.2 4918.2 4951.9 4957.0 5034.0 4861.6 4892.1 4988.3 5011.1 5040.3 05:56:51 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4612.57 Median:5223.46 stddev:1459.86 (27.9%) stddev-sans-first:1427.62 05:56:51 INFO - PROCESS | 1654 | Values: 299.9 304.0 315.1 318.4 322.2 4288.8 4671.3 5368.4 5427.9 5454.5 4408.0 4621.2 5105.9 5121.9 5425.3 4075.7 4141.9 4146.5 4155.3 4174.0 3785.4 3830.1 4123.6 4173.3 4179.6 3393.9 3418.9 4069.3 4139.7 4151.4 3535.5 4032.0 4063.6 4069.3 4071.2 2967.4 3329.7 3530.2 3531.2 3535.8 5113.7 5306.4 5309.3 5346.5 5346.7 4641.9 4643.7 4667.6 4674.2 4697.5 5566.5 5682.3 5697.9 5717.8 5720.8 3719.2 3909.3 3918.6 3923.2 3928.2 5913.5 5927.2 5941.3 5949.5 5951.2 3333.7 3920.4 3934.5 3936.3 3941.6 3526.3 4018.7 4021.7 4024.9 4034.9 3894.3 4512.3 4586.1 4627.6 4631.4 5016.3 5685.2 5708.3 5715.9 5717.1 1278.8 1278.9 1305.5 1363.8 1372.1 763.3 823.8 860.4 873.0 877.9 4582.5 5214.0 5229.5 5245.1 5297.5 5529.5 5696.9 5737.5 5769.8 5776.3 3863.1 4515.8 4840.3 5124.6 5147.5 2545.9 2665.0 2989.7 3056.5 3094.8 5980.2 6017.9 6019.3 6025.3 6037.4 5566.7 5646.3 5788.5 6026.2 6073.9 6108.0 6137.2 6138.5 6145.9 6152.5 5418.3 5501.8 5844.3 6011.5 6036.2 5302.3 5851.0 5857.4 5859.4 5862.3 4863.9 6141.3 6150.2 6176.4 6181.7 3015.8 3906.4 3929.4 3951.3 3987.6 5217.4 5625.4 5888.7 5923.9 5950.7 5269.6 5407.6 5689.4 5708.0 5827.3 5118.9 5676.4 5735.5 5837.0 5853.8 4963.1 5317.3 5651.6 5666.4 5681.3 5751.7 5761.5 5763.8 5783.9 5803.4 4676.4 5065.1 5640.6 5741.1 5756.5 05:56:51 INFO - PROCESS | 1654 | 05:56:51 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24102.09 Median:3764.60 stddev:38087.36 (1011.7%) stddev-sans-first:38153.52 05:56:51 INFO - PROCESS | 1654 | Values: 657.7 795.4 803.7 810.1 846.7 15012.3 16157.4 16164.8 16225.8 16243.0 13117.1 14287.4 14341.3 14371.4 14373.1 835.6 877.9 882.7 884.0 884.4 856.5 859.2 859.5 861.8 862.0 902.5 905.1 907.5 909.3 909.9 898.3 899.3 900.0 900.7 903.2 671.2 676.4 676.9 677.2 680.6 13169.0 13389.2 13408.3 13428.4 14105.7 11202.3 11734.6 12031.5 12077.5 12342.7 10812.5 11327.7 11330.8 11354.5 11805.1 3744.2 3750.2 3779.0 3909.0 3913.3 121428.0 125802.4 127339.0 127792.0 128275.0 9264.1 9351.7 9457.9 9464.2 9680.4 20254.6 20256.5 20307.6 20312.0 20337.7 7983.1 8118.8 8205.9 8312.9 8384.4 4372.3 4460.2 4481.8 4485.3 4668.9 3551.0 3631.9 3642.5 3677.6 3679.4 646.0 646.8 653.4 656.4 666.2 497.2 633.6 653.6 654.2 684.8 8918.7 9116.4 9460.2 9645.8 9661.4 551.1 552.7 623.3 635.4 675.3 4424.0 4606.4 4714.2 4727.3 4816.9 82589.6 98004.0 103801.0 148710.3 154750.0 88796.6 89373.0 133782.0 137018.0 139461.3 108754.4 110417.3 110528.0 112894.4 113369.4 9743.2 9986.4 10017.7 10020.6 10032.2 56089.0 59480.0 60717.0 61653.5 62327.0 90924.1 92201.9 95238.0 95820.6 97729.0 13369.8 13828.7 14031.3 14199.7 14501.0 14563.5 14571.9 15113.5 15195.0 15278.0 13676.4 14132.1 14144.3 14233.2 14335.7 12121.9 13532.9 13784.7 13980.9 14126.6 13557.4 13570.9 13647.8 13884.6 14234.9 11041.7 11356.5 11592.7 11702.5 11964.2 10596.8 11182.2 11551.0 11564.6 11572.5 05:56:51 INFO - PROCESS | 1654 | -------- Summary: end -------- 05:56:51 INFO - PROCESS | 1654 | 05:56:52 INFO - TEST-INFO | 1654: exit 0 05:56:52 INFO - TEST-OK | dromaeo_css | took 1118323ms 05:56:52 INFO - TEST-START | kraken 05:56:52 INFO - operating with platform_type : mac_ 05:56:52 INFO - Initialising browser for kraken test... 05:56:52 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpE8XNqR/profile http://localhost:49281/getInfo.html) 05:56:55 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 05:56:55 INFO - PROCESS | 1663 | colorDepth:24 05:56:55 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 05:56:55 INFO - PROCESS | 1663 | __metrics 05:56:55 INFO - PROCESS | 1663 | 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" 05:56:55 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:56:55 INFO - TEST-INFO | 1663: exit 0 05:56:55 INFO - Browser initialized. 05:56:55 INFO - Running cycle 1/1 for kraken test... 05:56:55 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpE8XNqR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:07 INFO - PROCESS | 1665 | RSS: Main: 243519488 05:57:07 INFO - PROCESS | 1665 | 05:57:10 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:11 INFO - PROCESS | 1665 | RSS: Main: 294653952 05:57:11 INFO - PROCESS | 1665 | 05:57:14 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 05:57:14 INFO - PROCESS | 1665 | RSS: Main: 301346816 05:57:14 INFO - PROCESS | 1665 | 05:57:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 05:57:18 INFO - PROCESS | 1665 | RSS: Main: 394756096 05:57:18 INFO - PROCESS | 1665 | 05:57:21 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 05:57:21 INFO - PROCESS | 1665 | RSS: Main: 299397120 05:57:21 INFO - PROCESS | 1665 | 05:57:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:57:24 INFO - PROCESS | 1665 | RSS: Main: 304156672 05:57:24 INFO - PROCESS | 1665 | 05:57:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 05:57:27 INFO - PROCESS | 1665 | RSS: Main: 342781952 05:57:27 INFO - PROCESS | 1665 | 05:57:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 05:57:31 INFO - PROCESS | 1665 | RSS: Main: 341094400 05:57:31 INFO - PROCESS | 1665 | 05:57:34 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 05:57:34 INFO - PROCESS | 1665 | RSS: Main: 343572480 05:57:34 INFO - PROCESS | 1665 | 05:57:36 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:57:36 INFO - PROCESS | 1665 | RSS: Main: 315228160 05:57:36 INFO - PROCESS | 1665 | 05:57:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:57:39 INFO - PROCESS | 1665 | RSS: Main: 320557056 05:57:39 INFO - PROCESS | 1665 | 05:57:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:57:42 INFO - PROCESS | 1665 | RSS: Main: 321138688 05:57:42 INFO - PROCESS | 1665 | 05:57:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:57:45 INFO - PROCESS | 1665 | RSS: Main: 331669504 05:57:45 INFO - PROCESS | 1665 | 05:57:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:57:49 INFO - PROCESS | 1665 | RSS: Main: 332886016 05:57:49 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 05:57:51 INFO - PROCESS | 1665 | RSS: Main: 376799232 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | __start_tp_report 05:57:51 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 05:57:51 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 05:57:51 INFO - PROCESS | 1665 | |i|pagename|runs| 05:57:51 INFO - PROCESS | 1665 | |0;ai-astar;102;112;114;113;114;110;113;114;113;113 05:57:51 INFO - PROCESS | 1665 | |1;audio-beat-detection;144;110;131;203;105;112;160;137;161;109 05:57:51 INFO - PROCESS | 1665 | |2;audio-dft;182;182;185;185;182;183;180;180;184;182 05:57:51 INFO - PROCESS | 1665 | |3;audio-fft;81;74;59;108;83;81;64;61;82;57 05:57:51 INFO - PROCESS | 1665 | |4;audio-oscillator;83;88;92;73;69;67;96;70;68;102 05:57:51 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;117;129;91;86;87;85;89;90;88;83 05:57:51 INFO - PROCESS | 1665 | |6;imaging-darkroom;94;92;92;94;101;150;149;94;93;94 05:57:51 INFO - PROCESS | 1665 | |7;imaging-desaturate;85;85;87;87;86;84;84;86;85;89 05:57:51 INFO - PROCESS | 1665 | |8;json-parse-financial;53;52;53;52;54;53;53;53;54;52 05:57:51 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;66;69;78;76;73;70;72;72;71;72 05:57:51 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;83;85;85;92;85;104;107;81;82;78 05:57:51 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;136;136;137;167;150;150;136;135;136;137 05:57:51 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;165;165;165;166;165;171;163;163;164;171 05:57:51 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;69;66;66;68;66;67;66;65;64;65 05:57:51 INFO - PROCESS | 1665 | __end_tp_report 05:57:51 INFO - PROCESS | 1665 | __start_cc_report 05:57:51 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2019 05:57:51 INFO - PROCESS | 1665 | __end_cc_report 05:57:51 INFO - PROCESS | 1665 | __startTimestamp1470661071341__endTimestamp 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | ------- Summary: start ------- 05:57:51 INFO - PROCESS | 1665 | Number of tests: 14 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.65 (3.2%) stddev-sans-first:1.27 05:57:51 INFO - PROCESS | 1665 | Values: 102.0 112.0 114.0 113.0 114.0 110.0 113.0 114.0 113.0 113.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:137.20 Median:140.50 stddev:31.10 (22.1%) stddev-sans-first:32.89 05:57:51 INFO - PROCESS | 1665 | Values: 144.0 110.0 131.0 203.0 105.0 112.0 160.0 137.0 161.0 109.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:182.50 Median:182.50 stddev:1.78 (1.0%) stddev-sans-first:1.88 05:57:51 INFO - PROCESS | 1665 | Values: 182.0 182.0 185.0 185.0 182.0 183.0 180.0 180.0 184.0 182.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:75.00 Median:77.50 stddev:15.53 (20.0%) stddev-sans-first:16.32 05:57:51 INFO - PROCESS | 1665 | Values: 81.0 74.0 59.0 108.0 83.0 81.0 64.0 61.0 82.0 57.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:78.00 stddev:13.05 (16.7%) stddev-sans-first:13.82 05:57:51 INFO - PROCESS | 1665 | Values: 83.0 88.0 92.0 73.0 69.0 67.0 96.0 70.0 68.0 102.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:87.50 stddev:15.47 (17.7%) stddev-sans-first:14.10 05:57:51 INFO - PROCESS | 1665 | Values: 117.0 129.0 91.0 86.0 87.0 85.0 89.0 90.0 88.0 83.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:93.50 stddev:23.43 (25.1%) stddev-sans-first:24.50 05:57:51 INFO - PROCESS | 1665 | Values: 94.0 92.0 92.0 94.0 101.0 150.0 149.0 94.0 93.0 94.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:1.55 (1.8%) stddev-sans-first:1.62 05:57:51 INFO - PROCESS | 1665 | Values: 85.0 85.0 87.0 87.0 86.0 84.0 84.0 86.0 85.0 89.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 05:57:51 INFO - PROCESS | 1665 | Values: 53.0 52.0 53.0 52.0 54.0 53.0 53.0 53.0 54.0 52.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.00 stddev:3.38 (4.7%) stddev-sans-first:2.83 05:57:51 INFO - PROCESS | 1665 | Values: 66.0 69.0 78.0 76.0 73.0 70.0 72.0 72.0 71.0 72.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:84.00 stddev:9.83 (11.7%) stddev-sans-first:10.24 05:57:51 INFO - PROCESS | 1665 | Values: 83.0 85.0 85.0 92.0 85.0 104.0 107.0 81.0 82.0 78.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:137.00 stddev:10.52 (7.7%) stddev-sans-first:10.93 05:57:51 INFO - PROCESS | 1665 | Values: 136.0 136.0 137.0 167.0 150.0 150.0 136.0 135.0 136.0 137.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.80 Median:165.00 stddev:2.90 (1.8%) stddev-sans-first:3.06 05:57:51 INFO - PROCESS | 1665 | Values: 165.0 165.0 165.0 166.0 165.0 171.0 163.0 163.0 164.0 171.0 05:57:51 INFO - PROCESS | 1665 | 05:57:51 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:1.48 (2.2%) stddev-sans-first:1.17 05:57:51 INFO - PROCESS | 1665 | Values: 69.0 66.0 66.0 68.0 66.0 67.0 66.0 65.0 64.0 65.0 05:57:51 INFO - PROCESS | 1665 | -------- Summary: end -------- 05:57:51 INFO - PROCESS | 1665 | 05:57:52 INFO - TEST-INFO | 1665: exit 0 05:57:52 INFO - TEST-OK | kraken | took 59612ms 05:57:52 INFO - SUITE-END | took 1177s 05:57:52 INFO - Completed test suite (00:19:38) 05:57:52 INFO - javascript benchmark 05:57:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2825.5761635754648, 3371.629120796712, 3378.543896573962, 3383.0865666270106, 3441.741869359799, 5611.186377975191, 5711.942880571193, 5773.971130144343, 5791.21818554495, 5838.562107841907, 5687.7156142192825, 5737.540996720252, 5821.941780582193, 5863.618864773786, 5869.530437564995, 5149.0, 5175.715335656538, 5224.660397074197, 5231.869203269911, 5301.1783173608155, 5297.814576489824, 5298.973505132469, 5298.973505132488, 5314.202869569557, 5356.705381204032, 5168.3281173447585, 5216.217567364888, 5217.243499692561, 5272.92090618641, 5309.814156504543, 5248.950209958024, 5255.579553635738, 5258.13240815265, 5260.237265596467, 5262.894742105174, 4251.5748425157235, 5143.022825663112, 5221.451747566527, 5262.631615786897, 5332.120200166968, 5649.322081350264, 5804.2744656917885, 5825.7378417971295, 5854.326752423483, 5857.443542863463, 6266.934621114376, 6326.082718005863, 6326.462250708695, 6329.36706329368, 6330.208723909512, 6207.906881396782, 6272.749090036439, 6316.368363163693, 6393.488520918362, 6412.583182093195, 5882.558808089368, 5970.0746384310505, 5973.163757074013, 5975.073863551156, 5978.521718262528, 6243.406876346739, 6318.052292156213, 6319.873602527924, 6326.051092336186, 6334.809955701337, 6103.664298463538, 6162.630242185393, 6163.568550201443, 6253.6560489173535, 6265.96867015662, 6351.650659213695, 6371.936280637227, 6372.74509019634, 6380.106785050097, 6381.936180638227, 6072.696365181723, 6278.963970944835, 6298.212723409574, 6308.968455157695, 6319.8104056878365, 6140.513092197667, 6167.814965551041, 6220.533459990519, 6273.9372606273355, 6277.18396608438, 5015.097282489187, 5017.347744793154, 5029.044481548494, 5037.11850426174, 5067.619928505377, 4520.118576877478, 4524.208263553931, 4541.682082254277, 4555.954440455619, 4597.08058388317, 5600.52395546375, 5672.177534257592, 5686.744096515584, 5698.2592263005545, 5766.423357664284, 6345.270293916166, 6350.809475715736, 6351.555391122577, 6352.745890164341, 6352.777652782131, 4916.680415772963, 5671.602987790815, 5749.080147176432, 5787.363390027093, 5795.797147099831, 5382.058139825514, 5450.073487507133, 5458.6724796512335, 5503.669779813224, 5506.752196151103, 6273.027680709497, 6282.560220784684, 6321.494280457644, 6323.715432805627, 6363.840903977252, 6367.745290188526, 6370.140031095742, 6370.968145159244, 6378.266499352449, 6391.96804015977, 6246.656433896179, 6356.586821856565, 6374.649394283359, 6383.489320854228, 6398.424141827258, 6099.340048628031, 6118.479929206067, 6145.047517634774, 6147.0779383092895, 6160.476359509492, 6283.2460104787715, 6307.43233109004, 6319.273283572448, 6331.778387756406, 6339.1125242467015, 5001.724905130107, 6188.341252463911, 6198.380161983766, 6219.751209951552, 6222.471089957223, 5955.851103722268, 6019.759209631741, 6134.723937422916, 6221.06683997404, 6227.221597300338, 6019.518438524995, 6088.330119245226, 6095.876406903803, 6096.658735078276, 6115.3578874217565, 6201.255849297932, 6206.503479721701, 6213.285472170577, 6219.284782250099, 6226.221722284715, 5844.26946631671, 5922.141289512911, 6049.727762250797, 6068.0, 6077.058056001327], "value": 5747.479537429614, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.3033907021565, 522.9645302301441, 526.2632314759336, 545.4136802936841, 555.4639772619421, 26309.210723678298, 31586.0, 32733.18167045822, 41225.0, 42204.36693449593, 44016.77991610045, 44394.0, 44440.55559444405, 44461.333080003824, 44520.77739611297, 9690.46702431366, 9862.654807081753, 9866.506674666274, 9867.309288349818, 9873.851892221623, 8811.162939520767, 9012.594433250486, 9806.411615303105, 11756.23584467007, 12088.758224835541, 12152.878471215306, 12192.81710774339, 12232.388380580935, 12256.142070055099, 12296.5696200633, 12106.576269830557, 12188.81716774249, 12194.207376520499, 12203.145779795417, 12216.450259738333, 8723.825523489495, 10495.527701253463, 11823.881761182363, 11864.406779660982, 12088.274703517729, 41612.58387416148, 42022.949426264284, 42142.0, 42157.367639485434, 42174.73475795731, 3336.616289126733, 3644.344018076745, 4063.5733248009124, 4106.77412742299, 4272.558024078441, 26683.332916677045, 31940.680593194003, 32664.183395415068, 32828.6717132828, 33349.99950001504, 3177.507486339621, 3340.7160391366765, 3345.8327083645963, 3372.241245719718, 3385.1198688340965, 504482.0, 507255.0, 510761.0, 511222.0, 514277.0, 60527.0, 60927.69536152291, 61090.69454652787, 61100.0, 61126.69436652877, 63735.0, 63779.0, 63792.68103659545, 63874.0, 64046.35953640404, 14696.632584185267, 16668.083255420825, 16688.415905443253, 16704.6659066818, 16712.49862504127, 11514.539418423337, 12770.106092573431, 12826.422810973529, 12841.678958025937, 12847.550335738204, 12971.740565188644, 14049.367778449994, 16216.270267837739, 16384.508464746075, 16612.750808737877, 5766.952731780241, 5770.653760774367, 5915.935131676253, 6708.0, 6853.657317134123, 878.9208971192495, 915.514777168102, 922.5156792683895, 1040.1730624153815, 1143.3654321851452, 30387.0, 30499.69500305013, 30873.845630771702, 31163.0, 31247.843760781052, 1026.9127124194379, 1075.2634445404767, 1082.442542090824, 1083.3066837224183, 1085.5277954089995, 15553.30010149546, 15599.142047187514, 15661.765073523906, 15846.841531584767, 15876.0, 489482.0, 496446.0, 503645.0, 505227.0, 508581.0, 59807.70096149491, 59937.100943485886, 59985.10022349668, 59985.70007149936, 60120.699396502736, 1063.5426766490484, 1364.7406992671558, 1383.5365152673967, 1393.6028231953844, 1406.7749160134329, 30821.22946926345, 31376.0, 31380.529292061095, 31443.52834707527, 32269.193270168435, 50387.74806125946, 50934.74532627461, 50962.49037509726, 51003.979920402126, 51045.74477127591, 534347.0, 535662.0, 536353.3182334063, 538574.0, 539358.0, 26354.20937371882, 26583.335416613965, 26612.334691632863, 27570.448591027667, 40942.1811563773, 48857.5114248853, 48946.75526622344, 48959.02081958412, 49161.508384915694, 49325.506744933526, 39695.2060958785, 39869.800650996556, 40052.39921401123, 40261.59738402696, 41334.586654133076, 39177.0, 39541.0, 39718.00704982399, 40815.59184408118, 40855.795721021204, 38325.0, 38895.8331250063, 39043.609563905135, 39118.41322380109, 39248.411273830345, 33784.986450406526, 34206.65793342035, 35056.298874021864, 35244.47133292951, 35257.94226173219, 25360.365990849637, 25592.488150237263, 26179.869100654374, 26351.20946371612, 26577.867110664323], "value": 20344.8331343845, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.7260863917505, 379.4592705394813, 379.6905521999572, 386.7621412831109, 410.5996653263069, 15205.619859503518, 15407.614809629766, 20872.373828785123, 21156.047977840997, 21292.89353553234, 19628.70556941647, 20061.1975520979, 20214.292499762512, 20631.07160177792, 20710.68933965992, 4830.806767729296, 5827.533797296206, 5956.612820166675, 5971.492423144038, 5984.251968503937, 6459.51553633477, 6580.3748643879, 6600.93399066008, 6621.370969757865, 6648.5678430901835, 6393.904091438632, 6482.773102941398, 6524.510661700391, 6553.606783592976, 6569.211694596655, 6254.15568898202, 6498.9350106498805, 6529.2164940207, 6539.149910511603, 6567.835804104888, 5518.558515318765, 5628.465295796893, 5630.859228519279, 5631.267935168443, 5633.690147041911, 25544.0, 26126.216213513624, 26143.08499202519, 26273.3431664208, 26365.604515932275, 1975.5555000124857, 2362.70466191726, 2484.0808900706675, 2573.5882258838496, 2871.6697579778174, 8155.836883262361, 10184.694459166238, 10281.383117012929, 10384.32501887375, 12183.93908030463, 1848.805875383079, 1851.139220262571, 1867.8692491525599, 1875.6061227142316, 1923.3749031564798, 115926.42036789762, 120541.0, 122433.0, 123560.0, 124570.0, 10917.890821091847, 11453.14101442395, 11646.0, 11647.12646551512, 11988.700282492833, 14309.141451512734, 14347.210903400413, 14348.49780257686, 14348.641283967985, 14349.56951291463, 8132.512049277062, 8376.539290338967, 8416.158384161658, 8502.06477287498, 8538.957305213435, 8366.623501942428, 8370.372222083368, 8376.49741015541, 8534.487930724052, 8562.143785621513, 3722.8510859565854, 3741.0086327123336, 4381.255186618282, 4468.932966005513, 4558.133954548625, 1837.80703026183, 1917.8178073083034, 1919.539310565482, 1919.664058789734, 1923.4133589255412, 807.463037080335, 832.1636755061124, 902.5757893789909, 905.2983937448398, 944.6504793226413, 19718.507037323896, 20403.795962040487, 21082.051307691192, 21084.262050828147, 21090.57818843645, 816.5386556595505, 905.8460061789511, 925.893522244937, 930.1907340613709, 1009.6415772400838, 10410.739731506621, 10855.782884342269, 10892.945535272273, 10943.28868623537, 10978.474950761369, 533209.0, 565270.0, 588328.0, 618150.0, 619287.0, 73018.0, 106604.46697766462, 121630.0, 121632.0, 130625.0, 937.0863408176973, 974.2790335152079, 995.8556009378744, 996.7490799047149, 996.7707427291618, 18492.907535462775, 18666.533336666147, 18698.34555790541, 18721.531961700523, 18728.344507942158, 54267.72866135644, 54547.72726136344, 55410.06064787713, 55691.44308556862, 56265.0, 63809.0, 79458.80811787712, 81441.0, 83533.91165220918, 84507.73238401551, 7827.099883513241, 7894.21057894206, 8092.71675491355, 8157.918420815953, 8383.910091688042, 10724.410157441416, 12591.93704031474, 12935.288559129338, 12975.545855894998, 12994.610161695164, 10596.311239769699, 11010.614628487965, 11167.319843237727, 11232.887671123512, 11339.943300283447, 9716.854247186156, 11081.833772493257, 11093.667189984313, 11312.6040588579, 11618.76762464763, 9173.128552787475, 10699.411532365795, 11759.706007349885, 11908.225965312227, 11940.164188506722, 7748.7675369739, 8200.630971606173, 8215.080690038896, 8251.33989280868, 8275.83448331042, 9017.684381046633, 11064.944675276894, 11433.59982400612, 11457.197996140523, 11485.598004069818], "value": 9562.27242738823, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.4383230959152, 1529.923503824809, 1535.1249787621055, 1564.8185619857006, 1870.1958157992067, 7723.536587804736, 8195.672173113084, 8201.097879233286, 8204.548749818758, 8227.382946279024, 8311.168883111686, 8600.22597966183, 8605.096464871176, 8711.695090671828, 8713.912860871389, 2329.8252631052655, 2360.4570948681812, 2365.7161140663056, 2367.9289621311386, 2373.1812524679044, 2316.8494047886834, 2341.180586794617, 2362.5629258587132, 2365.9881700591473, 2367.6922000140044, 1954.3257576136211, 2330.720313562375, 2336.100601268507, 2349.95300093998, 2352.7411984681758, 2296.954060918789, 2323.8838058097112, 2328.557574060923, 2330.3242059802637, 2339.0643742502966, 2326.336993956731, 2327.639215921518, 2327.918522851692, 2329.6971393718877, 2334.0430423526273, 8724.214820666108, 8749.256313213384, 8834.337424693174, 8846.5134417607, 8861.069587693328, 5112.079825631385, 5112.693238405707, 5275.894482110375, 5276.92084618731, 5282.577393808525, 3549.8757543486113, 3640.9271814563826, 3648.799316037617, 3650.981745091284, 3660.4509323601405, 925.6343744220995, 1040.287403128859, 1050.863387759594, 1071.7588542577935, 1077.8006068877205, 11497.195196336259, 11586.652400428, 11592.942035289769, 11610.18728688992, 11670.474828632732, 4378.605925466723, 4530.22986092365, 4531.56950089741, 4643.698159619614, 4874.805007799649, 9003.189712925869, 9173.724788256362, 9176.44941303524, 9181.173694367406, 9186.86219706705, 5226.294450249167, 5232.947670523322, 5234.214867769865, 5245.10833158364, 5260.92108618371, 4351.390805287262, 4514.706544074625, 4686.695364801345, 4809.134355815917, 4911.140550403665, 3714.795686237283, 3735.6637902588895, 3817.10298079951, 3849.0, 3870.2453021661067, 1153.2907262033789, 1163.8021536338842, 1168.2523185161504, 1171.1801738782885, 1175.9823602645968, 847.7075408983891, 868.0, 894.4007514964859, 913.8263729891299, 943.1511639524346, 5498.45015498447, 5727.427257274239, 5977.760889564455, 6006.579539432285, 6112.083187521818, 750.816050067726, 758.7268583310004, 882.5278476015301, 916.8028873792167, 955.1212884146602, 3831.578526362098, 3873.9225215496094, 3965.2267807777785, 4017.296973029708, 4037.7779222143063, 10363.481825908675, 10846.891531084588, 10847.294925829796, 10882.564697412015, 10898.782024359469, 10396.688099357032, 10588.682339529827, 10775.245732798785, 10815.080718139046, 10821.29661572011, 8100.432969692183, 8162.510249385056, 8286.129956354616, 8303.667853285922, 8304.086550479442, 5185.559227465707, 5826.155207494974, 5923.674197919156, 5948.286205655176, 5948.8810223796145, 9539.427634341684, 9796.559154838193, 9952.55213515379, 10012.948640392653, 10090.243231757795, 10182.389056656624, 10241.897581024094, 10262.384256944608, 10264.230182736474, 10266.691999240034, 6571.5399922005, 6767.154105736783, 6915.101036865175, 7034.859302813813, 7166.964165179141, 8289.129641387808, 8353.331733461187, 8504.744857654281, 8522.147785221428, 8525.317974562144, 6312.526560508072, 7034.5427547209265, 7060.364567188978, 7547.094348677974, 7691.38468922496, 7308.342249197385, 7418.777436676693, 7427.129350051837, 7427.2201418850245, 7434.36807871337, 6992.300769922967, 6997.1253593300835, 7046.859062818818, 7068.399186069241, 7073.363397294268, 4846.176150054429, 4918.213085906238, 4951.851444456672, 4956.950430495793, 5034.018366418513, 4861.586765125004, 4892.0705066037335, 4988.301637770643, 5011.123053465556, 5040.319556859924], "value": 4507.408519021882, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.94600971825076, 303.9589655396521, 315.08939165810796, 318.3505648477107, 322.1688044844302, 4288.769839683363, 4671.27595222741, 5368.409474957756, 5427.891442171159, 5454.4545545445435, 4407.955920440794, 4621.237495813195, 5105.8767071244265, 5121.9487805121935, 5425.29103332451, 4075.694322925785, 4141.917161656765, 4146.543880173179, 4155.272827255233, 4173.958260417403, 3785.413260944558, 3830.080780612647, 4123.60825721556, 4173.3114036184, 4179.623833854953, 3393.915152121192, 3418.8974330770116, 4069.287874621944, 4139.668826493873, 4151.418801367811, 3535.515083664867, 4031.9798401007806, 4063.553009168989, 4069.2878746219294, 4071.246819338432, 2967.4065186962694, 3329.667033296651, 3530.2057037166433, 3531.2231309111953, 3535.8055306958363, 5113.667611605234, 5306.389765176977, 5309.283246761676, 5346.492083252085, 5346.705931173784, 4641.907161856778, 4643.721376717408, 4667.603253723438, 4674.2053850845095, 4697.459792123881, 5566.524870909212, 5682.346530149003, 5697.914531282034, 5717.827225654225, 5720.768900128953, 3719.2189640175598, 3909.257241124177, 3918.5689574146822, 3923.1565213478953, 3928.2143571285856, 5913.467787899067, 5927.229460170151, 5941.257342832146, 5949.524038076944, 5951.196588460589, 3333.6997471744417, 3920.3923391874305, 3934.4688467057154, 3936.291467535814, 3941.581030828899, 3526.2771131917893, 4018.738781979162, 4021.738586991836, 4024.8730355500506, 4034.919301613951, 3894.2990261753157, 4512.2554778461845, 4586.096769840479, 4627.565454709051, 4631.397918270603, 5016.297718319438, 5685.1909220822145, 5708.343540492813, 5715.857103572361, 5717.141429285357, 1278.8209650648917, 1278.859325474197, 1305.5169587252587, 1363.8363396392497, 1372.1218420211073, 763.2939530933864, 823.8146417056234, 860.3977215948861, 872.9611762003196, 877.8507653698887, 4582.518835522289, 5213.973930130325, 5229.470107677303, 5245.115186647405, 5297.470252974672, 5529.502344788988, 5696.943030569724, 5737.454941780524, 5769.846030793774, 5776.277965254343, 3863.141275456142, 4515.848458643025, 4840.298156767249, 5124.641275110781, 5147.485251474823, 2545.9307091021815, 2664.986675066651, 2989.697932591892, 3056.465118604235, 3094.8394352117957, 5980.192673989043, 6017.916774980546, 6019.277686677539, 6025.276966764016, 6037.426444487766, 5566.66600003993, 5646.2942132233475, 5788.507976821933, 6026.246719160105, 6073.939260607426, 6107.96946015276, 6137.225606252212, 6138.508919286446, 6145.927036481759, 6152.538559607958, 5418.322709661293, 5501.807436739695, 5844.327902291291, 6011.458968692838, 6036.215292012011, 5302.310699609026, 5850.970745146417, 5857.355690873916, 5859.355470898283, 5862.267216597926, 4863.90272194547, 6141.293751218488, 6150.231221097363, 6176.35148309421, 6181.660008699565, 3015.8190508569555, 3906.3749800032997, 3929.430232616312, 3951.2887680217837, 3987.641112299791, 5217.443452708069, 5625.353084395347, 5888.705564721832, 5923.911141332969, 5950.727100095017, 5269.578433725216, 5407.567394608501, 5689.431056894443, 5707.973669347321, 5827.329857066322, 5118.872028199325, 5676.4323567642905, 5735.483806457439, 5837.007708689618, 5853.824385268449, 4963.057019166491, 5317.271886636781, 5651.57613179005, 5666.405027472221, 5681.289838770153, 5751.677114263659, 5761.48146666802, 5763.769449222152, 5783.942160578508, 5803.361630220588, 4676.415448068991, 5065.088284108749, 5640.559258852015, 5741.1388291756575, 5756.539476841926], "value": 4142.528476436945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.7171816119069, 795.3835777272614, 803.7347675267163, 810.145296711969, 846.6655671009951, 15012.324445399956, 16157.43448979286, 16164.83835161648, 16225.83774162258, 16243.025418474885, 13117.081804273705, 14287.428502859899, 14341.28293585319, 14371.35328910199, 14373.137611743303, 835.6114406800837, 877.9253763430116, 882.74400423877, 883.9823203535925, 884.4030279561302, 856.5246288309985, 859.2181115185183, 859.4671303791658, 861.8276344731048, 862.0173002776808, 902.5351943748974, 905.0903841639143, 907.4827348411408, 909.2589539525299, 909.8726178335038, 898.2544488074948, 899.3344924755667, 899.9910000900005, 900.7207765592655, 903.2457897655399, 671.2012704881175, 676.4453148418299, 676.9153855768029, 677.2347247610218, 680.6392611915693, 13169.0, 13389.19664820114, 13408.26254555999, 13428.395722192525, 14105.717885642332, 11202.327860328325, 11734.647960561197, 12031.458584363725, 12077.456514456782, 12342.691432714164, 10812.51343689528, 11327.660170194908, 11330.773384532264, 11354.48904799286, 11805.055595552336, 3744.2324323513885, 3750.212455384345, 3778.9811050944845, 3909.0, 3913.3347330953807, 121428.0, 125802.37098814538, 127339.0, 127791.99999999907, 128275.0, 9264.073592640681, 9351.672691455766, 9457.858132127885, 9464.242860571137, 9680.370775899544, 20254.594908101757, 20256.493587660425, 20307.593848122953, 20312.0, 20337.694934575993, 7983.084059825716, 8118.75643730689, 8205.876911846328, 8312.8753068704, 8384.413091083565, 4372.278574035312, 4460.1525710114975, 4481.775911204492, 4485.304777759453, 4668.929966050512, 3551.0412188708906, 3631.854725810938, 3642.5446819147605, 3677.6138505457075, 3679.3745063339297, 645.9903101453482, 646.812424396929, 653.3825534869598, 656.4453037183572, 666.237158453568, 497.1647631978239, 633.6134957675814, 653.565379022954, 654.2182092399632, 684.8116767888871, 8918.73243802687, 9116.361854670242, 9460.24318054554, 9645.807083858284, 9661.420314781135, 551.1124398933716, 552.6739223857943, 623.2894500269696, 635.4131667885524, 675.3213020914048, 4423.970453913642, 4606.424196975378, 4714.222153344728, 4727.314539391769, 4816.903661926742, 82589.58705206435, 98004.0, 103801.0, 148710.25644871706, 154750.0, 88796.5560172195, 89373.0, 133782.0, 137018.0, 139461.3026934879, 108754.3686844698, 110417.34373984397, 110528.0, 112894.43552782183, 113369.43315283535, 9743.171830394496, 9986.400815951067, 10017.749556261151, 10020.599176032876, 10032.197424206191, 56089.0, 59480.0, 60717.0, 61653.45866353377, 62327.0, 90924.09075909156, 92201.85091671025, 95238.0, 95820.60448488843, 97729.0, 13369.799453008407, 13828.654283642587, 14031.29843507841, 14199.716005680035, 14500.984931055147, 14563.490277840225, 14571.85428145705, 15113.47102851395, 15195.0, 15278.0, 13676.384562694504, 14132.081414708011, 14144.292785360485, 14233.173778963426, 14335.713285734435, 12121.909028187505, 13532.929963856139, 13784.724305513633, 13980.930095349458, 14126.576202713935, 13557.389917453935, 13570.86429135696, 13647.79528307096, 13884.58346249614, 14234.928825355806, 11041.72395690082, 11356.545738170711, 11592.6522204334, 11702.4733886977, 11964.222325548812, 10596.841047384449, 11182.161337899528, 11551.0, 11564.595239166589, 11572.537098516303], "value": 7765.590871758645, "unit": "score"}], "name": "dromaeo_css", "value": 7384.44217643518, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 114.0, 113.0, 114.0, 110.0, 113.0, 114.0, 113.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 131.0, 203.0, 105.0, 112.0, 160.0, 137.0, 161.0, 109.0], "value": 137.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 185.0, 185.0, 182.0, 183.0, 180.0, 180.0, 184.0, 182.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 59.0, 108.0, 83.0, 81.0, 64.0, 61.0, 82.0, 57.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 92.0, 73.0, 69.0, 67.0, 96.0, 70.0, 68.0, 102.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 129.0, 91.0, 86.0, 87.0, 85.0, 89.0, 90.0, 88.0, 83.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 92.0, 94.0, 101.0, 150.0, 149.0, 94.0, 93.0, 94.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 87.0, 86.0, 84.0, 84.0, 86.0, 85.0, 89.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 52.0, 54.0, 53.0, 53.0, 53.0, 54.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 78.0, 76.0, 73.0, 70.0, 72.0, 72.0, 71.0, 72.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 85.0, 92.0, 85.0, 104.0, 107.0, 81.0, 82.0, 78.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 137.0, 167.0, 150.0, 150.0, 136.0, 135.0, 136.0, 137.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 166.0, 165.0, 171.0, 163.0, 163.0, 164.0, 171.0], "value": 165.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 66.0, 68.0, 66.0, 67.0, 66.0, 65.0, 64.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1459.8999999999999, "alertThreshold": 2.0}]} 05:57:52 INFO - Return code: 0 05:57:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:57:52 INFO - # TBPL SUCCESS # 05:57:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:57:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:57:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:57:52 INFO - 2016-08-08 05:57:52.681 screenresolution[1669:9728] starting screenresolution argv=screenresolution get 05:57:52 INFO - 2016-08-08 05:57:52.684 screenresolution[1669:9728] Display 0: 1600x1200x32@60 05:57:52 INFO - 2016-08-08 05:57:52.690 screenresolution[1670:9731] starting screenresolution argv=screenresolution list 05:57:52 INFO - Available Modes on Display 0 05:57:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:57:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:57:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:57:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:57:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:57:52 INFO - Intel Iris: 05:57:52 INFO - Chipset Model: Intel Iris 05:57:52 INFO - Type: GPU 05:57:52 INFO - Bus: Built-In 05:57:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:57:52 INFO - Vendor: Intel (0x8086) 05:57:52 INFO - Device ID: 0x0a2e 05:57:52 INFO - Revision ID: 0x0009 05:57:52 INFO - Displays: 05:57:52 INFO - Display: 05:57:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:57:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:57:52 INFO - Display Serial Number: 1600x1200 60 05:57:52 INFO - Main Display: Yes 05:57:52 INFO - Mirror: Off 05:57:52 INFO - Online: Yes 05:57:52 INFO - Rotation: Supported 05:57:52 INFO - Return code: 0 05:57:52 INFO - Running post-action listener: _resource_record_post_action 05:57:52 INFO - [mozharness: 2016-08-08 12:57:52.926934Z] Finished run-tests step (success) 05:57:52 INFO - Running post-run listener: _resource_record_post_run 05:57:52 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38175744; Write bytes: 705499136; Read time: 412; Write time: 2329 05:57:52 INFO - TinderboxPrint: CPU usage
53.5% 05:57:52 INFO - TinderboxPrint: I/O read bytes / time
38,175,744 / 412 05:57:52 INFO - TinderboxPrint: I/O write bytes / time
705,499,136 / 2,329 05:57:52 INFO - TinderboxPrint: CPU idle
2,258.3 (46.5%) 05:57:52 INFO - TinderboxPrint: CPU system
971.6 (20.0%) 05:57:52 INFO - TinderboxPrint: CPU user
1,623.7 (33.5%) 05:57:52 INFO - TinderboxPrint: Swap in / out
444,784,640 / 0 05:57:52 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 398434816; Write bytes: 391448576; Read time: 29599; Write time: 748 05:57:52 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 36271104; Write bytes: 294184960; Read time: 357; Write time: 1521 05:57:53 INFO - Running post-run listener: _upload_blobber_files 05:57:53 INFO - Blob upload gear active. 05:57:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:57:53 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 05:57:53 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'] 05:57:53 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 05:57:53 INFO - (blobuploader) - INFO - Open directory for files ... 05:57:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:57:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:57:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:54 INFO - (blobuploader) - INFO - Done attempting. 05:57:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:57:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:57:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:55 INFO - (blobuploader) - INFO - Done attempting. 05:57:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:57:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:57:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:58 INFO - (blobuploader) - INFO - Done attempting. 05:57:58 INFO - (blobuploader) - INFO - Iteration through files over. 05:57:58 INFO - Return code: 0 05:57:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:57:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:57:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9d2291e271e7377b104649c5a5c59a47621658a635fa7803ba72a2f240ca84ef2aee801395822708997cef322bb319317a0f470638bfbc38dd1d29dcd8c102b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca46dd1f32f593b56ae4c0328a5d6302368892ac961c7db835be71777288e84f1c816d82770ae1349b23308ccc4e6e589df70263b7832d33d8b94b0d74d61cd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64cb1a34874d2fb421e6615a89576d528a4953a4329f7912c3848a75114bef91fbbc706add2d053840beb29c0102cc3e61a34359f9eb044d1ee62ac8b00e7815"} 05:57:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:57:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:57:58 INFO - Contents: 05:57:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9d2291e271e7377b104649c5a5c59a47621658a635fa7803ba72a2f240ca84ef2aee801395822708997cef322bb319317a0f470638bfbc38dd1d29dcd8c102b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca46dd1f32f593b56ae4c0328a5d6302368892ac961c7db835be71777288e84f1c816d82770ae1349b23308ccc4e6e589df70263b7832d33d8b94b0d74d61cd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64cb1a34874d2fb421e6615a89576d528a4953a4329f7912c3848a75114bef91fbbc706add2d053840beb29c0102cc3e61a34359f9eb044d1ee62ac8b00e7815"} 05:57:58 INFO - Running post-run listener: copy_logs_to_upload_dir 05:57:58 INFO - Copying logs to upload dir... 05:57:58 INFO - mkdir: /builds/slave/test/build/upload/logs 05:57:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.528235 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-08 05:57:58.292171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 05:57:58.296278) ========= 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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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/c9d2291e271e7377b104649c5a5c59a47621658a635fa7803ba72a2f240ca84ef2aee801395822708997cef322bb319317a0f470638bfbc38dd1d29dcd8c102b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca46dd1f32f593b56ae4c0328a5d6302368892ac961c7db835be71777288e84f1c816d82770ae1349b23308ccc4e6e589df70263b7832d33d8b94b0d74d61cd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64cb1a34874d2fb421e6615a89576d528a4953a4329f7912c3848a75114bef91fbbc706add2d053840beb29c0102cc3e61a34359f9eb044d1ee62ac8b00e7815"} build_url:https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010558 build_url: 'https://queue.taskcluster.net/v1/task/NpD1PBiKS0aQKRn7OTXS-Q/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/c9d2291e271e7377b104649c5a5c59a47621658a635fa7803ba72a2f240ca84ef2aee801395822708997cef322bb319317a0f470638bfbc38dd1d29dcd8c102b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca46dd1f32f593b56ae4c0328a5d6302368892ac961c7db835be71777288e84f1c816d82770ae1349b23308ccc4e6e589df70263b7832d33d8b94b0d74d61cd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64cb1a34874d2fb421e6615a89576d528a4953a4329f7912c3848a75114bef91fbbc706add2d053840beb29c0102cc3e61a34359f9eb044d1ee62ac8b00e7815"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 05:57:58.329120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:57:58.329432) ========= 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.hqdoMXWXFO/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.RBJHGD8Dz4/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.005156 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:57:58.377463) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:57:58.377784) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:57:58.378097) ========= ========= Total master_lag: 0.18 =========