builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0033 starttime: 1472596968.29 results: success (0) buildid: 20160830142820 builduid: 37cdf555d4314cac82640a5f5a9c5051 revision: fa6f91ffb4965302f316635e91d456c8103e08c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:48.289223) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:48.289666) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-08-30 15:42:48.289992) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-08-30 15:42:52.940380) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:52.940694) ========= 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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-30 15:42:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 150M=0s 2016-08-30 15:42:53 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117084 ========= master_lag: 0.30 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:53.358480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:53.358790) ========= 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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.020234 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:53.399563) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 15:42:53.399911) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fa6f91ffb4965302f316635e91d456c8103e08c2 --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 fa6f91ffb4965302f316635e91d456c8103e08c2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aSKqjNXdkg/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.UI2dZd1QzM/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-30 15:42:53,480 truncating revision to first 12 chars 2016-08-30 15:42:53,480 Setting DEBUG logging. 2016-08-30 15:42:53,481 attempt 1/10 2016-08-30 15:42:53,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fa6f91ffb496?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 15:42:54,906 unpacking tar archive at: mozilla-central-fa6f91ffb496/testing/mozharness/ program finished with exit code 0 elapsedTime=1.681973 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 15:42:55.104545) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:55.104874) ========= script_repo_revision: fa6f91ffb4965302f316635e91d456c8103e08c2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:55.105275) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:55.105544) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 15:42:55.126495) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-08-30 15:42:55.126883) ========= /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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:42:55 INFO - MultiFileLogger online at 20160830 15:42:55 in /builds/slave/test 15:42:55 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 15:42:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:42:55 INFO - {'append_to_log': False, 15:42:55 INFO - 'base_work_dir': '/builds/slave/test', 15:42:55 INFO - 'blob_upload_branch': 'Firefox', 15:42:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:42:55 INFO - 'branch': 'Firefox', 15:42:55 INFO - 'buildbot_json_path': 'buildprops.json', 15:42:55 INFO - 'config_files': ('talos/mac_config.py',), 15:42:55 INFO - 'default_actions': ('clobber', 15:42:55 INFO - 'read-buildbot-config', 15:42:55 INFO - 'download-and-extract', 15:42:55 INFO - 'populate-webroot', 15:42:55 INFO - 'create-virtualenv', 15:42:55 INFO - 'install', 15:42:55 INFO - 'run-tests'), 15:42:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:42:55 INFO - 'download_minidump_stackwalk': True, 15:42:55 INFO - 'download_symbols': 'ondemand', 15:42:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:42:55 INFO - 'tooltool.py': '/tools/tooltool.py', 15:42:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:42:55 INFO - '/tools/misc-python/virtualenv.py')}, 15:42:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:42:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:42:55 INFO - 'installer_path': 'installer.exe', 15:42:55 INFO - 'log_level': 'info', 15:42:55 INFO - 'log_name': 'talos', 15:42:55 INFO - 'log_to_console': True, 15:42:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:42:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:42:55 INFO - 'opt_config_files': (), 15:42:55 INFO - 'pip_index': False, 15:42:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:42:55 INFO - 'cmd': ('bash', 15:42:55 INFO - '-c', 15:42:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:42:55 INFO - 'enabled': True, 15:42:55 INFO - 'halt_on_failure': False, 15:42:55 INFO - 'name': 'check_screen_resolution'},), 15:42:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:42:55 INFO - 'cmd': ('bash', 15:42:55 INFO - '-c', 15:42:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:42:55 INFO - 'enabled': True, 15:42:55 INFO - 'halt_on_failure': False, 15:42:55 INFO - 'name': 'check_screen_resolution'},), 15:42:55 INFO - 'run_cmd_checks_enabled': True, 15:42:55 INFO - 'sps_profile': False, 15:42:55 INFO - 'sps_profile_interval': 0, 15:42:55 INFO - 'suite': 'dromaeojs', 15:42:55 INFO - 'system_bits': '32', 15:42:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:42:55 INFO - 'title': 't-yosemite-r7-0033', 15:42:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:42:55 INFO - 'use_talos_json': True, 15:42:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:42:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:42:55 INFO - 'work_dir': 'build'} 15:42:55 INFO - [mozharness: 2016-08-30 22:42:55.322229Z] Running clobber step. 15:42:55 INFO - Running pre-action listener: _resource_record_pre_action 15:42:55 INFO - Running main action method: clobber 15:42:55 INFO - rmtree: /builds/slave/test/build 15:42:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:42:55 INFO - Running post-action listener: _resource_record_post_action 15:42:55 INFO - [mozharness: 2016-08-30 22:42:55.503416Z] Finished clobber step (success) 15:42:55 INFO - [mozharness: 2016-08-30 22:42:55.503575Z] Running read-buildbot-config step. 15:42:55 INFO - Running pre-action listener: _resource_record_pre_action 15:42:55 INFO - Running main action method: read_buildbot_config 15:42:55 INFO - Using buildbot properties: 15:42:55 INFO - { 15:42:55 INFO - "project": "", 15:42:55 INFO - "product": "firefox", 15:42:55 INFO - "script_repo_revision": "production", 15:42:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:42:55 INFO - "repository": "", 15:42:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:42:55 INFO - "buildid": "20160830142820", 15:42:55 INFO - "pgo_build": "False", 15:42:55 INFO - "basedir": "/builds/slave/test", 15:42:55 INFO - "buildnumber": 82, 15:42:55 INFO - "slavename": "t-yosemite-r7-0033", 15:42:55 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:42:55 INFO - "platform": "yosemite_r7", 15:42:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:42:55 INFO - "branch": "mozilla-central", 15:42:55 INFO - "repo_path": "mozilla-central", 15:42:55 INFO - "revision": "fa6f91ffb4965302f316635e91d456c8103e08c2", 15:42:55 INFO - "stage_platform": "macosx64", 15:42:55 INFO - "builduid": "37cdf555d4314cac82640a5f5a9c5051", 15:42:55 INFO - "slavebuilddir": "test" 15:42:55 INFO - } 15:42:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:42:55 INFO - Running post-action listener: _resource_record_post_action 15:42:55 INFO - [mozharness: 2016-08-30 22:42:55.506852Z] Finished read-buildbot-config step (success) 15:42:55 INFO - [mozharness: 2016-08-30 22:42:55.506959Z] Running download-and-extract step. 15:42:55 INFO - Running pre-action listener: _resource_record_pre_action 15:42:55 INFO - Running main action method: download_and_extract 15:42:55 INFO - mkdir: /builds/slave/test/build/tests 15:42:55 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')]} 15:42:55 INFO - trying https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:42:55 INFO - Downloading https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:42:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:42:56 INFO - Downloaded 1199 bytes. 15:42:56 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:42:56 INFO - Using the following test package requirements: 15:42:56 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:42:56 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:42:56 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:42:56 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:42:56 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:42:56 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:42:56 INFO - u'jsshell-mac.zip'], 15:42:56 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:42:56 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:42:56 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:42:56 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:42:56 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:42:56 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:42:56 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:42:56 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:42:56 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:42:56 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:42:56 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:42:56 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:56 INFO - trying https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:42:56 INFO - Downloading https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:42:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:42:57 INFO - Downloaded 16380082 bytes. 15:42:57 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:43:04 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 15:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:04 INFO - trying https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:43:04 INFO - Downloading https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:43:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:43:05 INFO - Downloaded 16380082 bytes. 15:43:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:43: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')]} 15:43:11 INFO - trying https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:43:11 INFO - Downloading https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:43:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:43:12 INFO - Downloaded 14413998 bytes. 15:43:12 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 15:43: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')]} 15:43:13 INFO - trying https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:43:13 INFO - Downloading https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:43:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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 15:43:15 INFO - Downloaded 111578048 bytes. 15:43:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:43:15 INFO - mkdir: /builds/slave/test/properties 15:43:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:43:15 INFO - Writing to file /builds/slave/test/properties/build_url 15:43:15 INFO - Contents: 15:43:15 INFO - build_url:https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:43:16 INFO - Running post-action listener: _resource_record_post_action 15:43:16 INFO - Running post-action listener: set_extra_try_arguments 15:43:16 INFO - [mozharness: 2016-08-30 22:43:16.938537Z] Finished download-and-extract step (success) 15:43:16 INFO - [mozharness: 2016-08-30 22:43:16.938684Z] Running populate-webroot step. 15:43:16 INFO - Running pre-action listener: _resource_record_pre_action 15:43:16 INFO - Running main action method: populate_webroot 15:43:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:43:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:43:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:43:16 INFO - '--tppagecycles', 15:43:16 INFO - '7'], 15:43:16 INFO - 'tests': ['tsvgm']}}, 15:43:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:43:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:43:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:43:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:43:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:43:16 INFO - 'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:43:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:43:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:43:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:43:16 INFO - 'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['damp', 'tps']}, 15:43:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:43:16 INFO - 'tests': ['damp', 'tps']}, 15:43:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['dromaeo_dom']}, 15:43:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:43:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['basic_compositor_video']}, 15:43:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:43:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['a11yr', 15:43:16 INFO - 'ts_paint', 15:43:16 INFO - 'tpaint', 15:43:16 INFO - 'sessionrestore', 15:43:16 INFO - 'sessionrestore_no_auto_restore', 15:43:16 INFO - 'tabpaint']}, 15:43:16 INFO - 'other-e10s': {'tests': ['a11yr', 15:43:16 INFO - 'ts_paint', 15:43:16 INFO - 'tpaint', 15:43:16 INFO - 'sessionrestore', 15:43:16 INFO - 'sessionrestore_no_auto_restore', 15:43:16 INFO - 'tabpaint']}, 15:43:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:43:16 INFO - 'ts_paint', 15:43:16 INFO - 'tpaint', 15:43:16 INFO - 'sessionrestore', 15:43:16 INFO - 'sessionrestore_no_auto_restore', 15:43:16 INFO - 'tabpaint']}, 15:43:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:43:16 INFO - 'ts_paint', 15:43:16 INFO - 'tpaint', 15:43:16 INFO - 'sessionrestore', 15:43:16 INFO - 'sessionrestore_no_auto_restore', 15:43:16 INFO - 'tabpaint']}, 15:43:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['a11yr', 15:43:16 INFO - 'ts_paint', 15:43:16 INFO - 'tpaint', 15:43:16 INFO - 'sessionrestore', 15:43:16 INFO - 'sessionrestore_no_auto_restore', 15:43:16 INFO - 'tabpaint']}, 15:43:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['a11yr', 15:43:16 INFO - 'ts_paint', 15:43:16 INFO - 'tpaint', 15:43:16 INFO - 'sessionrestore', 15:43:16 INFO - 'sessionrestore_no_auto_restore', 15:43:16 INFO - 'tabpaint']}, 15:43:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['tsvgx', 15:43:16 INFO - 'tsvgr_opacity', 15:43:16 INFO - 'tart', 15:43:16 INFO - 'tscrollx', 15:43:16 INFO - 'cart']}, 15:43:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:43:16 INFO - 'tsvgr_opacity', 15:43:16 INFO - 'tart', 15:43:16 INFO - 'tscrollx', 15:43:16 INFO - 'cart']}, 15:43:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:43:16 INFO - 'talos_options': ['--disable-e10s'], 15:43:16 INFO - 'tests': ['tp5o']}, 15:43:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:43:16 INFO - 'tests': ['tp5o']}, 15:43:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:43:16 INFO - 'talos_options': ['--disable-e10s', 15:43:16 INFO - '--xperf_path', 15:43:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:43:16 INFO - 'tests': ['tp5n']}, 15:43:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:43:16 INFO - 'talos_options': ['--xperf_path', 15:43:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:43:16 INFO - 'tests': ['tp5n']}}, 15:43:16 INFO - 'talos.zip': {'path': '', 15:43:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:43:16 INFO - Running post-action listener: _resource_record_post_action 15:43:16 INFO - [mozharness: 2016-08-30 22:43:16.957576Z] Finished populate-webroot step (success) 15:43:16 INFO - [mozharness: 2016-08-30 22:43:16.957683Z] Running create-virtualenv step. 15:43:16 INFO - Running pre-action listener: _resource_record_pre_action 15:43:16 INFO - Running main action method: create_virtualenv 15:43:16 INFO - Creating virtualenv /builds/slave/test/build/venv 15:43:16 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 15:43:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:43:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:43:17 INFO - Using real prefix '/tools/python27' 15:43:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:43:17 INFO - Installing distribute.............................................................................................................................................................................................done. 15:43:20 INFO - Installing pip.................done. 15:43:20 INFO - Return code: 0 15:43:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:20 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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:20 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 15:43:20 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 15:43:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:20 INFO - 'HOME': '/Users/cltbld', 15:43:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:20 INFO - 'LOGNAME': 'cltbld', 15:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:20 INFO - 'MOZ_NO_REMOTE': '1', 15:43:20 INFO - 'NO_EM_RESTART': '1', 15:43:20 INFO - 'PAGER': '/bin/cat', 15:43:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:20 INFO - 'PWD': '/builds/slave/test', 15:43:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:20 INFO - 'SHELL': '/bin/bash', 15:43:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:20 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:20 INFO - 'USER': 'cltbld', 15:43:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:20 INFO - 'XPC_FLAGS': '0x0', 15:43:20 INFO - 'XPC_SERVICE_NAME': '0', 15:43:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:20 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 15:43:20 INFO - Cleaning up... 15:43:20 INFO - Return code: 0 15:43:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:20 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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:20 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 15:43:20 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 15:43:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:20 INFO - 'HOME': '/Users/cltbld', 15:43:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:20 INFO - 'LOGNAME': 'cltbld', 15:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:20 INFO - 'MOZ_NO_REMOTE': '1', 15:43:20 INFO - 'NO_EM_RESTART': '1', 15:43:20 INFO - 'PAGER': '/bin/cat', 15:43:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:20 INFO - 'PWD': '/builds/slave/test', 15:43:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:20 INFO - 'SHELL': '/bin/bash', 15:43:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:20 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:20 INFO - 'USER': 'cltbld', 15:43:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:20 INFO - 'XPC_FLAGS': '0x0', 15:43:20 INFO - 'XPC_SERVICE_NAME': '0', 15:43:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:21 INFO - Downloading/unpacking psutil>=3.1.1 15:43:21 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 15:43:21 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 15:43:21 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 15:43:21 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 15:43:21 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 15:43:21 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 15:43:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:43:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:43:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:43:24 INFO - Installing collected packages: psutil 15:43:24 INFO - Running setup.py install for psutil 15:43:24 INFO - building 'psutil._psutil_osx' extension 15:43:24 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 15:43:24 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 15:43:24 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 15:43:24 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 15:43:24 INFO - building 'psutil._psutil_posix' extension 15:43:24 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 15:43:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:43:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:43:24 INFO - ^ 15:43:24 INFO - 1 warning generated. 15:43:24 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 15:43:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:43:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:43:24 INFO - Successfully installed psutil 15:43:24 INFO - Cleaning up... 15:43:24 INFO - Return code: 0 15:43:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:25 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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:25 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 15:43:25 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 15:43:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:25 INFO - 'HOME': '/Users/cltbld', 15:43:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:25 INFO - 'LOGNAME': 'cltbld', 15:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:25 INFO - 'MOZ_NO_REMOTE': '1', 15:43:25 INFO - 'NO_EM_RESTART': '1', 15:43:25 INFO - 'PAGER': '/bin/cat', 15:43:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:25 INFO - 'PWD': '/builds/slave/test', 15:43:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:25 INFO - 'SHELL': '/bin/bash', 15:43:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:25 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:25 INFO - 'USER': 'cltbld', 15:43:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:25 INFO - 'XPC_FLAGS': '0x0', 15:43:25 INFO - 'XPC_SERVICE_NAME': '0', 15:43:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:43:25 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 15:43:25 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 15:43:25 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 15:43:25 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 15:43:25 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 15:43:25 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 15:43:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:43:27 INFO - Installing collected packages: mozsystemmonitor 15:43:27 INFO - Running setup.py install for mozsystemmonitor 15:43:27 INFO - Successfully installed mozsystemmonitor 15:43:27 INFO - Cleaning up... 15:43:27 INFO - Return code: 0 15:43:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:43:27 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')]} 15:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43: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')]} 15:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:28 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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:28 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 15:43:28 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 15:43:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:28 INFO - 'HOME': '/Users/cltbld', 15:43:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:28 INFO - 'LOGNAME': 'cltbld', 15:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:28 INFO - 'MOZ_NO_REMOTE': '1', 15:43:28 INFO - 'NO_EM_RESTART': '1', 15:43:28 INFO - 'PAGER': '/bin/cat', 15:43:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:28 INFO - 'PWD': '/builds/slave/test', 15:43:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:28 INFO - 'SHELL': '/bin/bash', 15:43:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:28 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:28 INFO - 'USER': 'cltbld', 15:43:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:28 INFO - 'XPC_FLAGS': '0x0', 15:43:28 INFO - 'XPC_SERVICE_NAME': '0', 15:43:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:28 INFO - Downloading/unpacking jsonschema==2.5.1 15:43:28 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 15:43:28 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 15:43:28 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 15:43:28 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 15:43:28 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 15:43:28 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 15:43:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:43:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:43:30 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 15:43:30 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 15:43:30 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 15:43:30 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 15:43:30 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 15:43:30 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 15:43:31 INFO - Downloading functools32-3.2.3-2.tar.gz 15:43:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:43:31 INFO - warning: no files found matching '*.txt' 15:43:31 INFO - no previously-included directories found matching 'build' 15:43:31 INFO - no previously-included directories found matching 'dist' 15:43:31 INFO - no previously-included directories found matching '.git*' 15:43:31 INFO - Installing collected packages: jsonschema, functools32 15:43:31 INFO - Running setup.py install for functools32 15:43:31 INFO - warning: no files found matching '*.txt' 15:43:31 INFO - no previously-included directories found matching 'build' 15:43:31 INFO - no previously-included directories found matching 'dist' 15:43:31 INFO - no previously-included directories found matching '.git*' 15:43:31 INFO - Successfully installed jsonschema functools32 15:43:31 INFO - Cleaning up... 15:43:31 INFO - Return code: 0 15:43:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:43:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:31 INFO - 'HOME': '/Users/cltbld', 15:43:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:31 INFO - 'LOGNAME': 'cltbld', 15:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:31 INFO - 'MOZ_NO_REMOTE': '1', 15:43:31 INFO - 'NO_EM_RESTART': '1', 15:43:31 INFO - 'PAGER': '/bin/cat', 15:43:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:31 INFO - 'PWD': '/builds/slave/test', 15:43:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:31 INFO - 'SHELL': '/bin/bash', 15:43:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:31 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:31 INFO - 'USER': 'cltbld', 15:43:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:31 INFO - 'XPC_FLAGS': '0x0', 15:43:31 INFO - 'XPC_SERVICE_NAME': '0', 15:43:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:43:31 INFO - Cleaning up... 15:43:31 INFO - Return code: 0 15:43:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:43:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:31 INFO - 'HOME': '/Users/cltbld', 15:43:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:31 INFO - 'LOGNAME': 'cltbld', 15:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:31 INFO - 'MOZ_NO_REMOTE': '1', 15:43:31 INFO - 'NO_EM_RESTART': '1', 15:43:31 INFO - 'PAGER': '/bin/cat', 15:43:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:31 INFO - 'PWD': '/builds/slave/test', 15:43:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:31 INFO - 'SHELL': '/bin/bash', 15:43:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:31 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:31 INFO - 'USER': 'cltbld', 15:43:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:31 INFO - 'XPC_FLAGS': '0x0', 15:43:31 INFO - 'XPC_SERVICE_NAME': '0', 15:43:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:31 INFO - Downloading/unpacking blobuploader==1.2.4 15:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:43:34 INFO - Downloading blobuploader-1.2.4.tar.gz 15:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:43:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:43:34 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 15:43:34 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 15:43:34 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 15:43:34 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 15:43:34 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 15:43:34 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 15:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:43:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:43: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 15:43: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 15:43: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 15:43: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 15:43: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 15:43: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 15:43:35 INFO - Downloading docopt-0.6.1.tar.gz 15:43:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:43:35 INFO - Installing collected packages: blobuploader, requests, docopt 15:43:35 INFO - Running setup.py install for blobuploader 15:43:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:43:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:43:35 INFO - Running setup.py install for requests 15:43:36 INFO - Running setup.py install for docopt 15:43:36 INFO - Successfully installed blobuploader requests docopt 15:43:36 INFO - Cleaning up... 15:43:36 INFO - Return code: 0 15:43:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:36 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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:36 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 15:43:36 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 15:43:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:36 INFO - 'HOME': '/Users/cltbld', 15:43:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:36 INFO - 'LOGNAME': 'cltbld', 15:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:36 INFO - 'MOZ_NO_REMOTE': '1', 15:43:36 INFO - 'NO_EM_RESTART': '1', 15:43:36 INFO - 'PAGER': '/bin/cat', 15:43:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:36 INFO - 'PWD': '/builds/slave/test', 15:43:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:36 INFO - 'SHELL': '/bin/bash', 15:43:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:36 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:36 INFO - 'USER': 'cltbld', 15:43:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:36 INFO - 'XPC_FLAGS': '0x0', 15:43:36 INFO - 'XPC_SERVICE_NAME': '0', 15:43:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:43:36 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-FMOWkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:43:36 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-fIZIup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-iQpOHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Flvmra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Y7q1XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-4DzEDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-0GayS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-RxQ6YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-3Hb170-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-F9eVAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-aISOFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:43:37 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-qpWf2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:43:38 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-iLqV6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:43:38 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-yBeAka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:38 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GW8DZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:43:38 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-vowkNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:43:38 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-RZ__sV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:43:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:43:38 INFO - Running setup.py install for manifestparser 15:43:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:43:38 INFO - Running setup.py install for mozcrash 15:43:38 INFO - Running setup.py install for mozdebug 15:43:38 INFO - Running setup.py install for mozdevice 15:43:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:43:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:43:39 INFO - Running setup.py install for mozfile 15:43:39 INFO - Running setup.py install for mozhttpd 15:43:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:43:39 INFO - Running setup.py install for mozinfo 15:43:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:43:39 INFO - Running setup.py install for mozInstall 15:43:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:43:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:43:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:43:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:43:39 INFO - Running setup.py install for mozleak 15:43:39 INFO - Running setup.py install for mozlog 15:43:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:43:40 INFO - Running setup.py install for moznetwork 15:43:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:43:40 INFO - Running setup.py install for mozprocess 15:43:40 INFO - Running setup.py install for mozprofile 15:43:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:43:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:43:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:43:40 INFO - Running setup.py install for mozrunner 15:43:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:43:40 INFO - Running setup.py install for mozscreenshot 15:43:40 INFO - Running setup.py install for moztest 15:43:40 INFO - Running setup.py install for mozversion 15:43:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:43:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:43:41 INFO - Cleaning up... 15:43:41 INFO - Return code: 0 15:43:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:41 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')]} 15:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:41 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')]} 15:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:41 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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:41 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 15:43:41 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 15:43:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:41 INFO - 'HOME': '/Users/cltbld', 15:43:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:41 INFO - 'LOGNAME': 'cltbld', 15:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:41 INFO - 'MOZ_NO_REMOTE': '1', 15:43:41 INFO - 'NO_EM_RESTART': '1', 15:43:41 INFO - 'PAGER': '/bin/cat', 15:43:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:41 INFO - 'PWD': '/builds/slave/test', 15:43:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:41 INFO - 'SHELL': '/bin/bash', 15:43:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:41 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:41 INFO - 'USER': 'cltbld', 15:43:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:41 INFO - 'XPC_FLAGS': '0x0', 15:43:41 INFO - 'XPC_SERVICE_NAME': '0', 15:43:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-LHeolL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:43:42 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)) 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-8j1wQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:43:42 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)) 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-wwaPbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:43:42 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)) 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-7aK6cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:43:42 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)) 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ICPGRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:43:42 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)) 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GLmFuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:43:42 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)) 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-LOPmC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:43:42 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)) 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-74HYL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:43:42 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)) 15:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:43:42 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-RgxZ6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:43:43 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)) 15:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:43:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-1bBTOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:43:43 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)) 15:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:43:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-DNnxwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:43:43 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)) 15:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:43:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-TufPKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:43:43 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)) 15:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:43:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-B7I3nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:43:43 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)) 15:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:43:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-VJHOGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:43:43 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)) 15:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kjiB4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:43 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)) 15:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:43:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-wHi_qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:43:43 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)) 15:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:43:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-i6YXtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:43:43 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)) 15:43:43 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)) 15:43:43 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)) 15:43:43 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)) 15:43:43 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)) 15:43:43 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)) 15:43:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:43:43 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 15:43:43 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 15:43:43 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 15:43:43 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 15:43:43 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 15:43:43 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 15:43:46 INFO - Downloading blessings-1.6.tar.gz 15:43:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:43:46 INFO - Installing collected packages: blessings 15:43:46 INFO - Running setup.py install for blessings 15:43:47 INFO - Successfully installed blessings 15:43:47 INFO - Cleaning up... 15:43:47 INFO - Return code: 0 15:43:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:43:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:43:47 INFO - Reading from file tmpfile_stdout 15:43:47 INFO - Current package versions: 15:43:47 INFO - blessings == 1.6 15:43:47 INFO - blobuploader == 1.2.4 15:43:47 INFO - docopt == 0.6.1 15:43:47 INFO - functools32 == 3.2.3-2 15:43:47 INFO - jsonschema == 2.5.1 15:43:47 INFO - manifestparser == 1.1 15:43:47 INFO - mozInstall == 1.12 15:43:47 INFO - mozcrash == 0.17 15:43:47 INFO - mozdebug == 0.1 15:43:47 INFO - mozdevice == 0.48 15:43:47 INFO - mozfile == 1.2 15:43:47 INFO - mozhttpd == 0.7 15:43:47 INFO - mozinfo == 0.9 15:43:47 INFO - mozleak == 0.1 15:43:47 INFO - mozlog == 3.3 15:43:47 INFO - moznetwork == 0.27 15:43:47 INFO - mozprocess == 0.23 15:43:47 INFO - mozprofile == 0.28 15:43:47 INFO - mozrunner == 6.12 15:43:47 INFO - mozscreenshot == 0.1 15:43:47 INFO - mozsystemmonitor == 0.3 15:43:47 INFO - moztest == 0.7 15:43:47 INFO - mozversion == 1.4 15:43:47 INFO - psutil == 3.1.1 15:43:47 INFO - requests == 1.2.3 15:43:47 INFO - wsgiref == 0.1.2 15:43:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:47 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')]} 15:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:47 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')]} 15:43:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:47 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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:47 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 15:43:47 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 15:43:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:47 INFO - 'HOME': '/Users/cltbld', 15:43:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:47 INFO - 'LOGNAME': 'cltbld', 15:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:47 INFO - 'MOZ_NO_REMOTE': '1', 15:43:47 INFO - 'NO_EM_RESTART': '1', 15:43:47 INFO - 'PAGER': '/bin/cat', 15:43:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:47 INFO - 'PWD': '/builds/slave/test', 15:43:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:47 INFO - 'SHELL': '/bin/bash', 15:43:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:47 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:47 INFO - 'USER': 'cltbld', 15:43:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:47 INFO - 'XPC_FLAGS': '0x0', 15:43:47 INFO - 'XPC_SERVICE_NAME': '0', 15:43:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 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)) 15:43:47 INFO - Cleaning up... 15:43:47 INFO - Return code: 0 15:43:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:43:47 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')]} 15:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:47 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')]} 15:43:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:43:47 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 0x1081b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108245618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde959c8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, '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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:43:47 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 15:43:47 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 15:43:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:43:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:47 INFO - 'HOME': '/Users/cltbld', 15:43:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:47 INFO - 'LOGNAME': 'cltbld', 15:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:47 INFO - 'MOZ_NO_REMOTE': '1', 15:43:47 INFO - 'NO_EM_RESTART': '1', 15:43:47 INFO - 'PAGER': '/bin/cat', 15:43:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:47 INFO - 'PWD': '/builds/slave/test', 15:43:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:47 INFO - 'SHELL': '/bin/bash', 15:43:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:43:47 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:43:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:47 INFO - 'USER': 'cltbld', 15:43:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:47 INFO - 'XPC_FLAGS': '0x0', 15:43:47 INFO - 'XPC_SERVICE_NAME': '0', 15:43:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:43:47 INFO - Cleaning up... 15:43:47 INFO - Return code: 0 15:43:47 INFO - Running post-action listener: _resource_record_post_action 15:43:47 INFO - Running post-action listener: _start_resource_monitoring 15:43:47 INFO - Starting resource monitoring. 15:43:47 INFO - [mozharness: 2016-08-30 22:43:47.988285Z] Finished create-virtualenv step (success) 15:43:47 INFO - [mozharness: 2016-08-30 22:43:47.989293Z] Running install step. 15:43:47 INFO - Running pre-action listener: _resource_record_pre_action 15:43:47 INFO - Running main action method: install 15:43:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:43:48 INFO - Reading from file tmpfile_stdout 15:43:48 INFO - Detecting whether we're running mozinstall >=1.0... 15:43:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:43:48 INFO - Reading from file tmpfile_stdout 15:43:48 INFO - Output received: 15:43:48 INFO - Usage: mozinstall [options] installer 15:43:48 INFO - Options: 15:43:48 INFO - -h, --help show this help message and exit 15:43:48 INFO - -d DEST, --destination=DEST 15:43:48 INFO - Directory to install application into. [default: 15:43:48 INFO - "/builds/slave/test"] 15:43:48 INFO - --app=APP Application being installed. [default: firefox] 15:43:48 INFO - mkdir: /builds/slave/test/build/application 15:43:48 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'] 15:43:48 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 15:44:22 INFO - Reading from file tmpfile_stdout 15:44:22 INFO - Output received: 15:44:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:44:22 INFO - Running post-action listener: _resource_record_post_action 15:44:22 INFO - [mozharness: 2016-08-30 22:44:22.248335Z] Finished install step (success) 15:44:22 INFO - [mozharness: 2016-08-30 22:44:22.248452Z] Running run-tests step. 15:44:22 INFO - Running pre-action listener: _resource_record_pre_action 15:44:22 INFO - Running main action method: run_tests 15:44:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:44:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:44:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:44:22 INFO - 2016-08-30 15:44:22.261 screenresolution[1900:8953] starting screenresolution argv=screenresolution get 15:44:22 INFO - 2016-08-30 15:44:22.263 screenresolution[1900:8953] Display 0: 1600x1200x32@60 15:44:22 INFO - 2016-08-30 15:44:22.268 screenresolution[1901:8955] starting screenresolution argv=screenresolution list 15:44:22 INFO - Available Modes on Display 0 15:44:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:44:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:44:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:44:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:44:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:44:22 INFO - Intel Iris: 15:44:22 INFO - Chipset Model: Intel Iris 15:44:22 INFO - Type: GPU 15:44:22 INFO - Bus: Built-In 15:44:22 INFO - VRAM (Dynamic, Max): 1536 MB 15:44:22 INFO - Vendor: Intel (0x8086) 15:44:22 INFO - Device ID: 0x0a2e 15:44:22 INFO - Revision ID: 0x0009 15:44:22 INFO - Displays: 15:44:22 INFO - Display: 15:44:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:44:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:44:22 INFO - Display Serial Number: 1600x1200 60 15:44:22 INFO - Main Display: Yes 15:44:22 INFO - Mirror: Off 15:44:22 INFO - Online: Yes 15:44:22 INFO - Rotation: Supported 15:44:22 INFO - Return code: 0 15:44:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:44:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:44:22 INFO - Python 2.7.3 15:44:22 INFO - Return code: 0 15:44:22 INFO - grabbing minidump binary from tooltool 15:44:22 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')]} 15:44:22 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 0x108114d30>, '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 0x107e79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10810cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:44:22 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 15:44:22 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 15:44:22 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 15:44:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:44:22 INFO - Return code: 0 15:44:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:44:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:44:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:44:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:44:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:44:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 15:44:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 15:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSKqjNXdkg/Render', 15:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:22 INFO - 'HOME': '/Users/cltbld', 15:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:22 INFO - 'LOGNAME': 'cltbld', 15:44:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:44:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:22 INFO - 'MOZ_NO_REMOTE': '1', 15:44:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:44:22 INFO - 'NO_EM_RESTART': '1', 15:44:22 INFO - 'PAGER': '/bin/cat', 15:44:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:22 INFO - 'PWD': '/builds/slave/test', 15:44:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:22 INFO - 'SHELL': '/bin/bash', 15:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UI2dZd1QzM/Listeners', 15:44:22 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:22 INFO - 'USER': 'cltbld', 15:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:22 INFO - 'XPC_FLAGS': '0x0', 15:44:22 INFO - 'XPC_SERVICE_NAME': '0', 15:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 15:44:22 INFO - mozversion application_buildid: 20160830142820 15:44:22 INFO - mozversion application_changeset: fa6f91ffb4965302f316635e91d456c8103e08c2 15:44:22 INFO - mozversion application_display_name: Nightly 15:44:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:44:22 INFO - mozversion application_name: Firefox 15:44:22 INFO - mozversion application_remotingname: firefox 15:44:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:44:22 INFO - mozversion application_vendor: Mozilla 15:44:22 INFO - mozversion application_version: 51.0a1 15:44:22 INFO - mozversion platform_buildid: 20160830142820 15:44:22 INFO - mozversion platform_changeset: fa6f91ffb4965302f316635e91d456c8103e08c2 15:44:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:44:22 INFO - mozversion platform_version: 51.0a1 15:44:22 INFO - using testdate: 1472597062 15:44:22 INFO - actual date: 1472597062 15:44:22 INFO - starting webserver on 'localhost:49456' 15:44:22 INFO - SUITE-START | Running 2 tests 15:44:22 INFO - TEST-START | dromaeo_css 15:44:22 INFO - operating with platform_type : mac_ 15:44:22 INFO - Initialising browser for dromaeo_css test... 15:44:22 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpbUn6jT/profile http://localhost:49456/getInfo.html) 15:44:25 INFO - PROCESS | 1911 | __metrics Screen width/height:1600/1200 15:44:25 INFO - PROCESS | 1911 | colorDepth:24 15:44:25 INFO - PROCESS | 1911 | Browser inner width/height: 1024/689 15:44:25 INFO - PROCESS | 1911 | __metrics 15:44:25 INFO - PROCESS | 1911 | 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" 15:44:25 INFO - PROCESS | 1911 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:44:25 INFO - TEST-INFO | 1911: exit 0 15:44:25 INFO - Browser initialized. 15:44:25 INFO - Running cycle 1/1 for dromaeo_css test... 15:44:26 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpbUn6jT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:44:37 INFO - PROCESS | 1913 | RSS: Main: 234315776 15:44:37 INFO - PROCESS | 1913 | 15:45:29 INFO - PROCESS | 1913 | 1472597129828 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] 15:47:40 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49456/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49456/tests/dromaeo/cssquery-ext.html) 15:47:42 INFO - PROCESS | 1913 | RSS: Main: 339582976 15:47:42 INFO - PROCESS | 1913 | 15:50:45 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49456/tests/dromaeo/cssquery-ext.html (next: http://localhost:49456/tests/dromaeo/cssquery-jquery.html) 15:50:46 INFO - PROCESS | 1913 | RSS: Main: 368852992 15:50:46 INFO - PROCESS | 1913 | 15:53:48 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49456/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49456/tests/dromaeo/cssquery-mootools.html) 15:53:49 INFO - PROCESS | 1913 | RSS: Main: 343228416 15:53:49 INFO - PROCESS | 1913 | 15:56:51 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49456/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49456/tests/dromaeo/cssquery-prototype.html) 15:56:52 INFO - PROCESS | 1913 | RSS: Main: 331440128 15:56:52 INFO - PROCESS | 1913 | 15:59:55 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49456/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49456/tests/dromaeo/cssquery-yui.html) 15:59:56 INFO - PROCESS | 1913 | RSS: Main: 324521984 15:59:56 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49456/tests/dromaeo/cssquery-yui.html (next: http://localhost:49456/tests/dromaeo/cssquery-dojo.html) 16:02:59 INFO - PROCESS | 1913 | RSS: Main: 431542272 16:02:59 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | __start_tp_report 16:02:59 INFO - PROCESS | 1913 | _x_x_mozilla_page_load 16:02:59 INFO - PROCESS | 1913 | _x_x_mozilla_page_load_details 16:02:59 INFO - PROCESS | 1913 | |i|pagename|runs| 16:02:59 INFO - PROCESS | 1913 | |0;dojo.html;3275.7051865332123;3299.3731191073693;3342.9498557521642;3373.713234375078;3388.8136152511615;5558.77764889405;5653.773849046039;5690.687012214332;5698.487136157745;5727.226824378713;5696.544276457894;5711.086226203808;5821.446962538552;5841.503472204857;5850.619709718876;5123.795048198068;5161.767720452589;5192.688438693691;5197.636165468419;5207.791688332462;5294.52349288564;5300.92048619271;5302.734863256841;5330.626856120073;5352.2239275305055;5110.66780659256;5169.612279079047;5170;5202.661826981253;5237.528622423962;5092.312537807423;5176.145935920568;5187.507186817246;5221.138512145491;5225.425203227642;5130.358705161855;5144.511271429208;5162.277281180638;5197.350331208599;5223.947760522384;5707.686077265748;5734.111212762028;5738.225339579145;5740.339860916007;5743.798967036176;5029.245613158019;6120.234970628671;6177.969110154466;6240.625562466221;6264.404881536247;6175.07373893915;6255.843603909893;6301.527385446064;6318.178636777236;6318.589291696071;5817.650940943557;5819.563532735062;5825.388334224887;5832.708364581754;5839.883202335929;6163.075538669235;6168.444839964424;6219;6228.34602366754;6263.71813268404;6112.0526318419825;6142.508599312045;6161.907571386433;6182.319944806068;6183.81448556535;6289.245290565162;6296.118543403927;6299.842503937438;6306;6312.5581209314905;6085.665288409092;6130.581587307925;6143.784967526115;6154.846128846725;6231.719572619243;6196.845078873064;6205.379462053849;6229.408206220402;6246.031865060922;6246.375362463808;4888.682235654671;4891.951080489221;4897.094037603055;4901.142300097468;4934.062528119646;4377.430933978626;4405.185040767468;4419.447569053868;4433.357163211381;4467.977660111678;5710.143478478261;5727.942720572741;5737.426257374229;5792.82621521355;5804.100364443518;6253.374662533801;6278.121063051177;6283.842903927347;6284;6295.685215739195;5592.888142237214;5652.406497317805;5659.405762394971;5714.828555143269;5737.483626473638;5354.839354819362;5372.57019438444;5410.3507579090765;5413.133898576209;5474.452554744494;6246.46905013079;6286.245650521967;6292.716827742855;6296.275928268308;6308.873822523616;6123.465646421035;6274.717637706223;6291.528135389955;6293.590916590407;6304.590201636879;6223.3465486125115;6235.407636274546;6243.656598887105;6312.400321969406;6324.936750632619;6162.322144564184;6183.201557471435;6192.380761923771;6196.938030619816;6234.657093859882;6127.050307202391;6249.531285153723;6270.184875966278;6276.058591211355;6283.968580157253;6039.48664363534;6076.756929722762;6112.816615501542;6114.571104522668;6147.754089836357;6082.817515474543;6102.9694851525455;6103.664298463627;6118.143459915526;6122.969385153046;5686.630369026;5877.441643043904;5944.554158438221;5969.522438204846;5985.581009329305;6042.848928776816;6053.757849685963;6085.939140608537;6136.917362303904;6143.419763699168;5924.851878703067;5930.703464826827;5940.851478713067;5949.25634295713;5994.730237139252 16:02:59 INFO - PROCESS | 1913 | |1;ext.html;465.7717718318023;468.6414892607154;502.36701755787703;518.8469401526551;534.9812756553519;30547.541786873186;32118.197045073885;32235.67764322359;39214.41178382326;40721.18557628846;42722.78638606802;42909.71270861871;43051.138977220435;43093.707188784385;43096;8431.241188293052;9604.807903841918;9685.612575496973;9782;9818.165455936254;8726.825463490759;8926.330525210602;9588.568514416833;9683.370580912218;11721.58974435895;11696.356700381475;11998.160128790989;12057.698557536089;12063.974562162226;12067.637970860887;11963.40182990847;12031.518739250507;12040.759184816343;12045.096617753703;12053.57812476559;11597.536098556047;11631.941840290745;11640.301581905112;11657.475413606408;11666;37346.69286574984;37851.43222851659;39866.20267594661;40126.39810402817;40171.995700107436;3394.389009978203;3418.453047512411;3490.1798077451776;3787.9431808522895;4178.8328466861285;30295.697043029508;31245;31494.740210391556;32588.83705581457;32704.182395440068;3233.96766032339;3264.5592844965863;3281.0648965044848;3282.179455136216;3380.5098260752297;486685;488623;490573.54713226564;492573;492740;61612;62158;62312;62322.68838655778;62354.37645623576;64844;65129;65146.67426662836;65178;65204;14582.708345833236;16762.83237167637;16768.748468772978;16769.24538395775;16776.32894684199;12767.872321276669;12837.972962163005;12843.165194262343;12843.48626054966;12856.357182140855;14052.578422647337;16228.026318420933;16512.552478218386;16575.585610359838;16636.251368688438;6370.044493325945;6953.513254072166;7834.725784597512;7971;8065.153158918347;880.5201165364891;910.5993362920294;923.2521657457481;1024.0680980307884;1064.680595821266;23104.884475577852;29565;29726.40547189044;29838.40323193524;30138.84930575333;1000.4597517340554;1040.843873418993;1063.803196408667;1073.409624706424;1120.439780109945;14092.87257019436;15405.768913466307;15451.227438628024;15628.218589070728;15884.444044458389;197587;200573;200634;200703;200912;48848;55803.441965580634;56682;57153.714231428574;57724.71137644369;1348.5212749473992;1371.0882263294798;1395.6301580081285;1403.7543429899727;1404.1154072933987;23633.763662363155;30024;31012.68987310188;31400.726955798244;31467.370652586364;50803.22188723377;51137.232941505164;51467.45597632077;51525.7423712879;51545.7422712884;460684;479780;480008;597175;597727;40670.79664601658;41222.58777412187;41363.79318103511;41386.75839724813;41926.79036604797;46066;46149.098312105096;46187.538124618324;46507.53492465032;47166;37988.81005594954;38449.80775096107;38610.42084368793;39231.80384098175;39613.40579891361;37398.81300593589;37913.62086379101;38225.61774382221;38962;39479.21041579095;37356.06609834667;38032.049198770255;38053.42919856149;38308.616913830505;38450.61549384471;31028;32902.177445564055;33675.66324336823;33872.66127338695;34766.65233347735;24338.87830560836;24507.142250021163;24583.262502124966;24663.01347946062;24858.627120592842 16:02:59 INFO - PROCESS | 1913 | |2;jquery.html;343.1592598134571;363.2970202657857;370.7756807131685;374.0984228010494;396.1937457274447;14859;15627.843721562782;20359.28742494013;21050.684239736416;21107.15571377145;20878.269260575882;21166.68249976248;21176.576468470623;21247.468813279676;21260.574788504222;5636.859078523039;5743.626664266831;5806.303243610751;5890;5890.175375447441;5710.600257981943;5886.587938844283;5888.352281249059;5888.676122813244;5903.616264942786;5648.548116150719;5776.403075178765;5784.971075144618;5842.152887831262;5849.941500585003;5788.305403351604;5846.327672317695;5849.38581448946;5851.76592936282;5874.706264686749;4837.927431088537;5082.364704411949;5124.461931497176;5137;5141.15170996785;25828.354291142685;25917.870410648015;25943;26047.479050419075;26268.86865565679;2180.258712037915;2267.036509483463;2285.223024171773;2436.817238707104;3030.8029978051354;13941.86058139426;14597.70804583913;15210;15812.446564370191;16010.359585616561;1404.9227292498908;1878.4928069421317;1901.1539864760186;1917.3289348727974;1940.670086085369;105998;116197.99999999916;116889.99999999916;117180;117622;8047.436679432501;8301.46040507365;9880.258980576486;9938.254630902711;10571.15004874144;12297;12338.691532711575;12370.690732731753;12370.938145309396;12372.44324005422;7966.960165199137;8071.798205044921;8086.661290162029;8093.635786389626;8229.46508476947;6876.518643694993;8509.319254459628;8669.306455483546;8697.478151310941;8711.60797764102;3259.071164718044;3794.6774524165753;3850.3454412749898;3867.3812190049457;3933.704972127102;1853.7497437845718;1908.885466871992;1912.7609048868892;1913.722510236008;1918.3669389101563;838.9790255243546;876.9956150219208;910.8815853939078;927.3369540778241;934.5514153206499;19902.800971990386;20281.391558253275;20712.48218794512;21447.463813404476;21652.4586885326;420.2414641571931;883.8055627761883;922.3543519536352;976.2385339435252;989.8960609136082;5563.888722225533;5870.471657550841;5911.6944391687275;6071.878562428727;6079.3920607939735;587006;600502;614973;622914;623478;78480.70991883404;104979;112911.43544282226;133809.2738407699;139647;445.56557356577724;447.708989157044;456.64838074682973;456.82868924153445;459.4073644997982;11514.424278786195;11527.020203282815;11711.590094346657;11737.29576225395;11826.408679566159;51882;54323.641908952595;54893;56093.15860262018;56971;80360.79458808005;80526;80754.19245807467;82492.35015299541;83087;6671.633060181736;7928;8018.719344823136;8104.5137291762685;8124.918750812652;10482.895171048496;12081.395930203631;12509.93665538403;12762.298073605669;12951.740965180832;10780.407077610807;10788;11116.666500005012;11132.833007504732;11178.27341222818;9237.67668131639;9927.255455840985;10897;11198.776024479303;11207.991280784769;10286.691399258034;10655.680329590125;10667.89332106669;10753.085987691135;11504.884951150716;8081.393895457982;8110.716124935599;8283.254507094149;8299.377546684143;8463.265030668575;8968;11031.282966607145;11176.720581985515;11337.603183888854;11572.884271157182 16:02:59 INFO - PROCESS | 1913 | |3;mootools.html;1505.0367764630637;1507.5402002389274;1702.4977631598676;1844.9631007379844;1874.8968806715625;6975;7862.764117076483;8396.538190399526;8989.235914947229;9001.639934402616;8396.160383961616;8714.91285087149;8719.084496127894;8783.516906570136;8803.075677053897;2372.335745991121;2401.3396316013063;2401.6157414813633;2401.7238017627933;2412.203972689017;2117.2060477321006;2200.0869639099756;2203.504211552396;2349.1308215960034;2377.9286621401297;1887.8206570375792;2175.6845257437662;2204.9889750551306;2379.869107199112;2397.61638137898;2321.6981792367055;2338.847974881627;2342.051469154995;2351.141833230867;2354.0701422938014;2347;2347.847389919667;2349.189529612281;2350.6944097267415;2351.1888398502488;8768.868466973001;8835.072317406644;8847.911520884772;8859.424137431111;8862.291016718647;5113.974430127825;5351.7056561889085;5353.678779273256;5358.142697168473;5358.544523715489;2873.339131999631;3232.7575431842706;3276.1154488288266;3400.8129552874584;3416.24842534642;1087.0977089016098;1097.8133717268083;1100.9889901100967;1117.1788735279563;1122.0630773304301;11590.536378544848;11741.4716337765;11762.353070581115;11785.233959792586;11802.94098529502;4524.751138687404;4592.540745925381;4759.833405830779;4793.712377257376;4804;9953;9972.252081093802;9973.950130249448;9988.151007164472;9990.900090999143;5347.919781203285;5356.598255130881;5358.571314294847;5363.758630861621;5364.463553644682;4690.624750019991;4824.324594556765;4907.411110666742;4912.484189160086;4926.778294976734;3720.107174278208;3728.813559322023;3784.148566572499;3819.2170605025904;3826.1391186982705;1096.199774164864;1111.2943281016612;1124.2186680257144;1125.3023125662141;1130.7003644034337;723.4754802768057;766.4634755671052;850.6937502499095;882.4837470079947;958.4919992404037;5503.944960550423;5648.887022259614;5721.027425337703;5790.652560846278;5853.0635098384055;809.8380323935237;919.6965001549472;931.5528546297679;937.7561833923093;983.9901600984042;3280.8851690190727;3762.755420897633;3766.9058273543383;3888.3389823730035;3931.410288456754;8108.43240973138;10727;10851.78296434067;10862.239643224899;10881.075108615856;10073.546690398949;10799.352038877692;10825.13398928084;10827.729306767395;10865;7803.882941755878;8243.464174828618;8247.381446391424;8251.546164960864;8252.174782521699;5799.130130480462;5840.415958404125;5985.730642121202;6001.759929602942;6031.396860314109;9858.950705246429;10041.347312424668;10071.34536255141;10075.294729368874;10106.039926206999;9608.95195524018;10068.194544436574;10094.79810403773;10241.07830295277;10253.487325633838;7407.851842963219;7565.962170189114;7570.507916985378;7579.393648507996;7588.8482230353975;8140.633671484916;8143.348532117535;8144.267015968354;8155.592220389076;8248.13394593583;6279.686015699105;7373.395017441259;7450.403967682681;7515.173330933702;7621.847563048819;7338.596377199306;7372.852542949218;7392.4825262231125;7409.888851667121;7440.218777028334;6847.9385695217325;6873.347032031949;6880;6930.792076237721;6963.1644202696;4786.114568804782;4794.424669039738;4839.903201936012;4854.75726213695;4862.319275301531;4893.09477746618;4941.184704523714;4972.453030166751;5020.673656212334;5156.767945442389 16:02:59 INFO - PROCESS | 1913 | |4;prototype.html;250.3228766187463;260.2764315203733;297.90169244149433;313.15448289618035;313.55005567011324;5182.896342073156;5198.220266959958;5212.582993360532;5229.777344628527;5262.210668399742;4531.614812740921;4707.505711900244;5023.572996295319;5069.338597236453;5161.587073034158;4018.5177778666603;4030.1133750574973;4042.5148982122187;4053.817578208973;4062.329715596923;3712.3874560697586;4027.8791636250958;4036.777977211252;4071.1857628474277;4113.280175969208;3662.3773958427;3788.2423515296914;4023.356262997921;4029.1465925674224;4053.27041132596;3532.763532763538;3812.1994381179984;3946.125590344592;3954.8643732384476;3990.8204130814183;2820.605115283862;3205.3108581655056;3474.6525347465304;3482.2339085401422;3493.9650603494147;4539.251023581105;4582.404287442611;4860.878478038042;5005.974970125126;5017.34774479319;3791.639794219545;4242.045539753562;4302.806373713159;4357.649128770091;4551.795169217393;4640.559146881041;5015.673981191211;5144.09668422686;5443.727813609304;5469.206964990054;3836.0985168485386;3841.769493830351;3843.538775346948;3845.3270677631303;3887.436321733333;4727.841678788719;5356.598255130881;5647.887042259173;5677.659340439587;5708.115242137475;3336.5328853960464;3525.471179323071;3723.236736469017;3765.322241996467;3770.1328694399895;3818.961810381916;3837.846486140523;3863.6715879150024;3864.7487913285545;3920.3923391874305;4122.072533679958;4441.000774825703;4460.687751857404;4522.389477420572;4531.750753708512;4544.613707834871;4709.858704238878;4972.254161875748;5336.813211537577;5500.779968801203;1274.7386785708907;1293.6701141208932;1328.7708869296057;1328.9169326998465;1331.2627021736726;800.2397722163968;826.8677011678103;840.8486472434898;858.6994551906782;881.0220655072864;4185;4563.247064234364;5104.642675012714;5119.0273847968765;5160.200168973815;5543.584231182677;5587.888242235133;5608;5630.577706672016;5670.829875103754;4440.600345968814;4917.434495033117;5130.050940576006;5142.202958541432;5319.882824606876;2584.0490429725437;2938.017911819505;2985.208919636298;3010.969890301069;3021.743151832075;4995.525425084611;5241.977814326246;5868.002439585281;5879.970600146972;5984.281886173687;4537.455505339381;5829.446202610745;5902.343945224666;5909.763609455659;5937.495312898365;5903.498202652737;5918.852028699231;5920.644761314335;5939.732712028055;5941.079132734433;4793.856184314476;4852.927206091841;5490.835274941598;5711.857203569944;5729.369769325454;4744.59670927975;5402.378726446509;5576.518449290027;5666.206731057738;5678.375378708256;5898.321693005188;5905.734241959036;5918.763249469973;5936.317323507852;5949.553783466344;3525;3762.830672619684;3814.332491813977;3837.5586807517493;3841.366174581163;4994.80020799164;5665.263515743092;5669.824898057102;5675.31896172462;5688.800891968761;4961.7519124044375;5289.492494639163;5532.668039917457;5555.555555555465;5560.7219639018695;5282.894342113059;5510.752016159363;5518.420565840529;5522.211283301906;5563.415841336601;5154.278401023935;5389.8113566025;5403.891922161456;5414.7021913795115;5423.620346575702;5342.13025441108;5427.83716488506;5440.7833928703985;5657.377688454185;5663.207150998781;5428.891422171613;5432.619716619799;5462.207979842821;5468.808591699271;5469.535089517435 16:02:59 INFO - PROCESS | 1913 | |5;yui.html;706.0891450029462;708.6563016936786;721.5382155420529;767.4627760567604;815.2499700275744;14860;15585.454509092184;15598.53204403867;15631.218439078044;15641.765373519378;12325.506979720823;13978.650533736662;14072.577822665311;14119.223442710647;14140.71718565628;690.6201589125985;717.2576383443137;717.630420333526;718.7807718645811;793.2345286798253;706.8621618784339;709.5068927095671;842.2083241752763;876.570480464571;884.5090974509177;919.0717375450776;930.7254359963827;936.4053825820585;937.5687183895416;938.2822141062059;911.8313112074287;912.6942474271127;913.7167478081783;914.419343716738;914.6661468563926;689.3141324382221;690.4614400767409;691.3915754136328;691.7959202035362;692.9029935808991;9810.754731131707;13959.581212563638;15284.082955022623;15376.077435353915;15384.30770615325;14306.28468576567;14838.25808709571;14992.100473971597;14999.925000374928;15017.399304027826;11901.226420282654;11975.520979160823;12015.399230038463;12085.93957030218;12241.816372754416;3799.0692280391204;3823.4073718573663;3824.2160357126722;3841.9039524011846;3960.485136932209;98489.0151098487;99299.0070099297;99417.5029124857;105495;119788.40105799503;11259.943700281447;11346.3759493227;11410.087193024538;11788.351640659846;11901.880981190077;23991;24089.156879509133;24091.638625420634;24095;24158.912848921842;7142.250063743232;8081.555514446762;8091.55496447701;8139.226773456512;8204.794880127925;3974.28462876685;4163.875083747432;4250.277452833026;4514.616257618073;4690;3558.430651095812;3691.0956815580353;3691.53855768029;3691.833867475916;3697.5932647408763;652.9967350163218;654.509118161379;659.9967000164968;660.579170463098;669.8325418645338;438.8880835386956;645.3675398109879;658.3613894522306;665.6538599928107;684.750066225824;9116.179543841086;9223.169914707709;9240.768980775534;9359.766005849908;9366.157045865904;425.33435173952665;610.4170517156106;625.5496042849142;652.5790864892118;664.2792570061441;4469.821207151677;4505.324201369755;4553.54464553542;4612.30815377696;4693.647976401783;151944;152891;154722;157148;169213.30786691976;129982.35008824896;131742;132787;134640.3267983654;135213;108394.3740843888;112509;113108.43445782883;113388.79500844964;116287;9350.345475816628;9744.947901397889;9745.966442786912;9748.697380880254;9754.694049656304;57568;59728;63186;64995;66469;92249.53875230582;95510.52244738732;96426.55360169463;97453;98973;12604.55884044054;13039.608811735663;13372.130811497222;13376;13448.46206151743;14590.927045364706;14794.186319752087;14946;14994.100353978796;15524.68950621004;13706.93146534261;13925.442982280596;13940.372683229076;14068.859311407165;14276;11413.60052398162;13113.475460981454;13381.866181338062;13664.863351366359;13781.448742050206;13356.465741370237;13390.464381424634;13569.592912212649;14026.368813403626;14083.366248518636;11102.056325212125;11170.385628790495;11220.719482012688;11275.098508233019;11778.587749428729;10206.387616743019;10542.472876355998;10734.409607471664;11221;11421.428928553705 16:02:59 INFO - PROCESS | 1913 | __end_tp_report 16:02:59 INFO - PROCESS | 1913 | __start_cc_report 16:02:59 INFO - PROCESS | 1913 | _x_x_mozilla_cycle_collect,1399 16:02:59 INFO - PROCESS | 1913 | __end_cc_report 16:02:59 INFO - PROCESS | 1913 | __startTimestamp1472598179827__endTimestamp 16:02:59 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | ------- Summary: start ------- 16:02:59 INFO - PROCESS | 1913 | Number of tests: 6 16:02:59 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | [#0] dojo.html Cycles:165 Average:5741.44 Median:5949.26 stddev:643.30 (10.8%) stddev-sans-first:615.51 16:02:59 INFO - PROCESS | 1913 | Values: 3275.7 3299.4 3342.9 3373.7 3388.8 5558.8 5653.8 5690.7 5698.5 5727.2 5696.5 5711.1 5821.4 5841.5 5850.6 5123.8 5161.8 5192.7 5197.6 5207.8 5294.5 5300.9 5302.7 5330.6 5352.2 5110.7 5169.6 5170.0 5202.7 5237.5 5092.3 5176.1 5187.5 5221.1 5225.4 5130.4 5144.5 5162.3 5197.4 5223.9 5707.7 5734.1 5738.2 5740.3 5743.8 5029.2 6120.2 6178.0 6240.6 6264.4 6175.1 6255.8 6301.5 6318.2 6318.6 5817.7 5819.6 5825.4 5832.7 5839.9 6163.1 6168.4 6219.0 6228.3 6263.7 6112.1 6142.5 6161.9 6182.3 6183.8 6289.2 6296.1 6299.8 6306.0 6312.6 6085.7 6130.6 6143.8 6154.8 6231.7 6196.8 6205.4 6229.4 6246.0 6246.4 4888.7 4892.0 4897.1 4901.1 4934.1 4377.4 4405.2 4419.4 4433.4 4468.0 5710.1 5727.9 5737.4 5792.8 5804.1 6253.4 6278.1 6283.8 6284.0 6295.7 5592.9 5652.4 5659.4 5714.8 5737.5 5354.8 5372.6 5410.4 5413.1 5474.5 6246.5 6286.2 6292.7 6296.3 6308.9 6123.5 6274.7 6291.5 6293.6 6304.6 6223.3 6235.4 6243.7 6312.4 6324.9 6162.3 6183.2 6192.4 6196.9 6234.7 6127.1 6249.5 6270.2 6276.1 6284.0 6039.5 6076.8 6112.8 6114.6 6147.8 6082.8 6103.0 6103.7 6118.1 6123.0 5686.6 5877.4 5944.6 5969.5 5985.6 6042.8 6053.8 6085.9 6136.9 6143.4 5924.9 5930.7 5940.9 5949.3 5994.7 16:02:59 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | [#1] ext.html Cycles:180 Average:56894.66 Median:37377.44 stddev:115055.64 (307.8%) stddev-sans-first:115300.40 16:02:59 INFO - PROCESS | 1913 | Values: 465.8 468.6 502.4 518.8 535.0 30547.5 32118.2 32235.7 39214.4 40721.2 42722.8 42909.7 43051.1 43093.7 43096.0 8431.2 9604.8 9685.6 9782.0 9818.2 8726.8 8926.3 9588.6 9683.4 11721.6 11696.4 11998.2 12057.7 12064.0 12067.6 11963.4 12031.5 12040.8 12045.1 12053.6 11597.5 11631.9 11640.3 11657.5 11666.0 37346.7 37851.4 39866.2 40126.4 40172.0 3394.4 3418.5 3490.2 3787.9 4178.8 30295.7 31245.0 31494.7 32588.8 32704.2 3234.0 3264.6 3281.1 3282.2 3380.5 486685.0 488623.0 490573.5 492573.0 492740.0 61612.0 62158.0 62312.0 62322.7 62354.4 64844.0 65129.0 65146.7 65178.0 65204.0 14582.7 16762.8 16768.7 16769.2 16776.3 12767.9 12838.0 12843.2 12843.5 12856.4 14052.6 16228.0 16512.6 16575.6 16636.3 6370.0 6953.5 7834.7 7971.0 8065.2 880.5 910.6 923.3 1024.1 1064.7 23104.9 29565.0 29726.4 29838.4 30138.8 1000.5 1040.8 1063.8 1073.4 1120.4 14092.9 15405.8 15451.2 15628.2 15884.4 197587.0 200573.0 200634.0 200703.0 200912.0 48848.0 55803.4 56682.0 57153.7 57724.7 1348.5 1371.1 1395.6 1403.8 1404.1 23633.8 30024.0 31012.7 31400.7 31467.4 50803.2 51137.2 51467.5 51525.7 51545.7 460684.0 479780.0 480008.0 597175.0 597727.0 40670.8 41222.6 41363.8 41386.8 41926.8 46066.0 46149.1 46187.5 46507.5 47166.0 37988.8 38449.8 38610.4 39231.8 39613.4 37398.8 37913.6 38225.6 38962.0 39479.2 37356.1 38032.0 38053.4 38308.6 38450.6 31028.0 32902.2 33675.7 33872.7 34766.7 24338.9 24507.1 24583.3 24663.0 24858.6 16:02:59 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | [#2] jquery.html Cycles:180 Average:34559.55 Median:2164.95 stddev:101517.74 (4689.2%) stddev-sans-first:101770.01 16:02:59 INFO - PROCESS | 1913 | Values: 343.2 363.3 370.8 374.1 396.2 14859.0 15627.8 20359.3 21050.7 21107.2 20878.3 21166.7 21176.6 21247.5 21260.6 5636.9 5743.6 5806.3 5890.0 5890.2 5710.6 5886.6 5888.4 5888.7 5903.6 5648.5 5776.4 5785.0 5842.2 5849.9 5788.3 5846.3 5849.4 5851.8 5874.7 4837.9 5082.4 5124.5 5137.0 5141.2 25828.4 25917.9 25943.0 26047.5 26268.9 2180.3 2267.0 2285.2 2436.8 3030.8 13941.9 14597.7 15210.0 15812.4 16010.4 1404.9 1878.5 1901.2 1917.3 1940.7 105998.0 116198.0 116890.0 117180.0 117622.0 8047.4 8301.5 9880.3 9938.3 10571.2 12297.0 12338.7 12370.7 12370.9 12372.4 7967.0 8071.8 8086.7 8093.6 8229.5 6876.5 8509.3 8669.3 8697.5 8711.6 3259.1 3794.7 3850.3 3867.4 3933.7 1853.7 1908.9 1912.8 1913.7 1918.4 839.0 877.0 910.9 927.3 934.6 19902.8 20281.4 20712.5 21447.5 21652.5 420.2 883.8 922.4 976.2 989.9 5563.9 5870.5 5911.7 6071.9 6079.4 587006.0 600502.0 614973.0 622914.0 623478.0 78480.7 104979.0 112911.4 133809.3 139647.0 445.6 447.7 456.6 456.8 459.4 11514.4 11527.0 11711.6 11737.3 11826.4 51882.0 54323.6 54893.0 56093.2 56971.0 80360.8 80526.0 80754.2 82492.4 83087.0 6671.6 7928.0 8018.7 8104.5 8124.9 10482.9 12081.4 12509.9 12762.3 12951.7 10780.4 10788.0 11116.7 11132.8 11178.3 9237.7 9927.3 10897.0 11198.8 11208.0 10286.7 10655.7 10667.9 10753.1 11504.9 8081.4 8110.7 8283.3 8299.4 8463.3 8968.0 11031.3 11176.7 11337.6 11572.9 16:02:59 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | [#3] mootools.html Cycles:180 Average:5654.27 Median:4909.95 stddev:3203.00 (65.2%) stddev-sans-first:3196.81 16:02:59 INFO - PROCESS | 1913 | Values: 1505.0 1507.5 1702.5 1845.0 1874.9 6975.0 7862.8 8396.5 8989.2 9001.6 8396.2 8714.9 8719.1 8783.5 8803.1 2372.3 2401.3 2401.6 2401.7 2412.2 2117.2 2200.1 2203.5 2349.1 2377.9 1887.8 2175.7 2205.0 2379.9 2397.6 2321.7 2338.8 2342.1 2351.1 2354.1 2347.0 2347.8 2349.2 2350.7 2351.2 8768.9 8835.1 8847.9 8859.4 8862.3 5114.0 5351.7 5353.7 5358.1 5358.5 2873.3 3232.8 3276.1 3400.8 3416.2 1087.1 1097.8 1101.0 1117.2 1122.1 11590.5 11741.5 11762.4 11785.2 11802.9 4524.8 4592.5 4759.8 4793.7 4804.0 9953.0 9972.3 9974.0 9988.2 9990.9 5347.9 5356.6 5358.6 5363.8 5364.5 4690.6 4824.3 4907.4 4912.5 4926.8 3720.1 3728.8 3784.1 3819.2 3826.1 1096.2 1111.3 1124.2 1125.3 1130.7 723.5 766.5 850.7 882.5 958.5 5503.9 5648.9 5721.0 5790.7 5853.1 809.8 919.7 931.6 937.8 984.0 3280.9 3762.8 3766.9 3888.3 3931.4 8108.4 10727.0 10851.8 10862.2 10881.1 10073.5 10799.4 10825.1 10827.7 10865.0 7803.9 8243.5 8247.4 8251.5 8252.2 5799.1 5840.4 5985.7 6001.8 6031.4 9859.0 10041.3 10071.3 10075.3 10106.0 9609.0 10068.2 10094.8 10241.1 10253.5 7407.9 7566.0 7570.5 7579.4 7588.8 8140.6 8143.3 8144.3 8155.6 8248.1 6279.7 7373.4 7450.4 7515.2 7621.8 7338.6 7372.9 7392.5 7409.9 7440.2 6847.9 6873.3 6880.0 6930.8 6963.2 4786.1 4794.4 4839.9 4854.8 4862.3 4893.1 4941.2 4972.5 5020.7 5156.8 16:02:59 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | [#4] prototype.html Cycles:180 Average:4461.70 Median:5000.75 stddev:1377.76 (27.6%) stddev-sans-first:1344.88 16:02:59 INFO - PROCESS | 1913 | Values: 250.3 260.3 297.9 313.2 313.6 5182.9 5198.2 5212.6 5229.8 5262.2 4531.6 4707.5 5023.6 5069.3 5161.6 4018.5 4030.1 4042.5 4053.8 4062.3 3712.4 4027.9 4036.8 4071.2 4113.3 3662.4 3788.2 4023.4 4029.1 4053.3 3532.8 3812.2 3946.1 3954.9 3990.8 2820.6 3205.3 3474.7 3482.2 3494.0 4539.3 4582.4 4860.9 5006.0 5017.3 3791.6 4242.0 4302.8 4357.6 4551.8 4640.6 5015.7 5144.1 5443.7 5469.2 3836.1 3841.8 3843.5 3845.3 3887.4 4727.8 5356.6 5647.9 5677.7 5708.1 3336.5 3525.5 3723.2 3765.3 3770.1 3819.0 3837.8 3863.7 3864.7 3920.4 4122.1 4441.0 4460.7 4522.4 4531.8 4544.6 4709.9 4972.3 5336.8 5500.8 1274.7 1293.7 1328.8 1328.9 1331.3 800.2 826.9 840.8 858.7 881.0 4185.0 4563.2 5104.6 5119.0 5160.2 5543.6 5587.9 5608.0 5630.6 5670.8 4440.6 4917.4 5130.1 5142.2 5319.9 2584.0 2938.0 2985.2 3011.0 3021.7 4995.5 5242.0 5868.0 5880.0 5984.3 4537.5 5829.4 5902.3 5909.8 5937.5 5903.5 5918.9 5920.6 5939.7 5941.1 4793.9 4852.9 5490.8 5711.9 5729.4 4744.6 5402.4 5576.5 5666.2 5678.4 5898.3 5905.7 5918.8 5936.3 5949.6 3525.0 3762.8 3814.3 3837.6 3841.4 4994.8 5665.3 5669.8 5675.3 5688.8 4961.8 5289.5 5532.7 5555.6 5560.7 5282.9 5510.8 5518.4 5522.2 5563.4 5154.3 5389.8 5403.9 5414.7 5423.6 5342.1 5427.8 5440.8 5657.4 5663.2 5428.9 5432.6 5462.2 5468.8 5469.5 16:02:59 INFO - PROCESS | 1913 | 16:02:59 INFO - PROCESS | 1913 | [#5] yui.html Cycles:180 Average:25345.02 Median:3748.33 stddev:40715.41 (1086.2%) stddev-sans-first:40787.60 16:02:59 INFO - PROCESS | 1913 | Values: 706.1 708.7 721.5 767.5 815.2 14860.0 15585.5 15598.5 15631.2 15641.8 12325.5 13978.7 14072.6 14119.2 14140.7 690.6 717.3 717.6 718.8 793.2 706.9 709.5 842.2 876.6 884.5 919.1 930.7 936.4 937.6 938.3 911.8 912.7 913.7 914.4 914.7 689.3 690.5 691.4 691.8 692.9 9810.8 13959.6 15284.1 15376.1 15384.3 14306.3 14838.3 14992.1 14999.9 15017.4 11901.2 11975.5 12015.4 12085.9 12241.8 3799.1 3823.4 3824.2 3841.9 3960.5 98489.0 99299.0 99417.5 105495.0 119788.4 11259.9 11346.4 11410.1 11788.4 11901.9 23991.0 24089.2 24091.6 24095.0 24158.9 7142.3 8081.6 8091.6 8139.2 8204.8 3974.3 4163.9 4250.3 4514.6 4690.0 3558.4 3691.1 3691.5 3691.8 3697.6 653.0 654.5 660.0 660.6 669.8 438.9 645.4 658.4 665.7 684.8 9116.2 9223.2 9240.8 9359.8 9366.2 425.3 610.4 625.5 652.6 664.3 4469.8 4505.3 4553.5 4612.3 4693.6 151944.0 152891.0 154722.0 157148.0 169213.3 129982.4 131742.0 132787.0 134640.3 135213.0 108394.4 112509.0 113108.4 113388.8 116287.0 9350.3 9744.9 9746.0 9748.7 9754.7 57568.0 59728.0 63186.0 64995.0 66469.0 92249.5 95510.5 96426.6 97453.0 98973.0 12604.6 13039.6 13372.1 13376.0 13448.5 14590.9 14794.2 14946.0 14994.1 15524.7 13706.9 13925.4 13940.4 14068.9 14276.0 11413.6 13113.5 13381.9 13664.9 13781.4 13356.5 13390.5 13569.6 14026.4 14083.4 11102.1 11170.4 11220.7 11275.1 11778.6 10206.4 10542.5 10734.4 11221.0 11421.4 16:02:59 INFO - PROCESS | 1913 | -------- Summary: end -------- 16:02:59 INFO - PROCESS | 1913 | 16:03:00 INFO - TEST-INFO | 1913: exit 0 16:03:00 INFO - TEST-OK | dromaeo_css | took 1117588ms 16:03:00 INFO - TEST-START | kraken 16:03:00 INFO - operating with platform_type : mac_ 16:03:00 INFO - Initialising browser for kraken test... 16:03:00 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmppg2Atb/profile http://localhost:49456/getInfo.html) 16:03:03 INFO - PROCESS | 1918 | __metrics Screen width/height:1600/1200 16:03:03 INFO - PROCESS | 1918 | colorDepth:24 16:03:03 INFO - PROCESS | 1918 | Browser inner width/height: 1024/689 16:03:03 INFO - PROCESS | 1918 | __metrics 16:03:03 INFO - PROCESS | 1918 | 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" 16:03:03 INFO - PROCESS | 1918 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:03:03 INFO - TEST-INFO | 1918: exit 0 16:03:03 INFO - Browser initialized. 16:03:03 INFO - Running cycle 1/1 for kraken test... 16:03:03 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmppg2Atb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:15 INFO - PROCESS | 1919 | RSS: Main: 234717184 16:03:15 INFO - PROCESS | 1919 | 16:03:18 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49456/tests/kraken/driver.html?testName=audio-beat-detection) 16:03:19 INFO - PROCESS | 1919 | RSS: Main: 292073472 16:03:19 INFO - PROCESS | 1919 | 16:03:22 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49456/tests/kraken/driver.html?testName=audio-dft) 16:03:22 INFO - PROCESS | 1919 | RSS: Main: 288063488 16:03:22 INFO - PROCESS | 1919 | 16:03:26 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49456/tests/kraken/driver.html?testName=audio-fft) 16:03:26 INFO - PROCESS | 1919 | RSS: Main: 377933824 16:03:26 INFO - PROCESS | 1919 | 16:03:28 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49456/tests/kraken/driver.html?testName=audio-oscillator) 16:03:29 INFO - PROCESS | 1919 | RSS: Main: 282034176 16:03:29 INFO - PROCESS | 1919 | 16:03:31 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49456/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:03:32 INFO - PROCESS | 1919 | RSS: Main: 285368320 16:03:32 INFO - PROCESS | 1919 | 16:03:34 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49456/tests/kraken/driver.html?testName=imaging-darkroom) 16:03:35 INFO - PROCESS | 1919 | RSS: Main: 324923392 16:03:35 INFO - PROCESS | 1919 | 16:03:38 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49456/tests/kraken/driver.html?testName=imaging-desaturate) 16:03:39 INFO - PROCESS | 1919 | RSS: Main: 324890624 16:03:39 INFO - PROCESS | 1919 | 16:03:41 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49456/tests/kraken/driver.html?testName=json-parse-financial) 16:03:42 INFO - PROCESS | 1919 | RSS: Main: 323448832 16:03:42 INFO - PROCESS | 1919 | 16:03:44 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49456/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:03:44 INFO - PROCESS | 1919 | RSS: Main: 298237952 16:03:44 INFO - PROCESS | 1919 | 16:03:46 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49456/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:03:47 INFO - PROCESS | 1919 | RSS: Main: 305180672 16:03:47 INFO - PROCESS | 1919 | 16:03:49 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49456/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:03:50 INFO - PROCESS | 1919 | RSS: Main: 298500096 16:03:50 INFO - PROCESS | 1919 | 16:03:53 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49456/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:03:53 INFO - PROCESS | 1919 | RSS: Main: 319909888 16:03:53 INFO - PROCESS | 1919 | 16:03:56 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49456/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:03:57 INFO - PROCESS | 1919 | RSS: Main: 316035072 16:03:57 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:49456/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49456/tests/kraken/driver.html?testName=ai-astar) 16:03:59 INFO - PROCESS | 1919 | RSS: Main: 359026688 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | __start_tp_report 16:03:59 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 16:03:59 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 16:03:59 INFO - PROCESS | 1919 | |i|pagename|runs| 16:03:59 INFO - PROCESS | 1919 | |0;ai-astar;108;113;133;114;113;117;114;112;113;113 16:03:59 INFO - PROCESS | 1919 | |1;audio-beat-detection;146;115;130;161;108;131;128;108;159;106 16:03:59 INFO - PROCESS | 1919 | |2;audio-dft;186;187;185;184;184;185;181;181;183;186 16:03:59 INFO - PROCESS | 1919 | |3;audio-fft;72;80;76;153;58;58;61;68;142;58 16:03:59 INFO - PROCESS | 1919 | |4;audio-oscillator;84;84;136;70;71;76;118;68;69;118 16:03:59 INFO - PROCESS | 1919 | |5;imaging-gaussian-blur;86;127;97;85;88;85;89;86;88;86 16:03:59 INFO - PROCESS | 1919 | |6;imaging-darkroom;94;94;94;95;92;115;150;145;93;93 16:03:59 INFO - PROCESS | 1919 | |7;imaging-desaturate;79;80;85;86;84;84;86;91;86;71 16:03:59 INFO - PROCESS | 1919 | |8;json-parse-financial;56;56;58;57;58;57;57;56;57;57 16:03:59 INFO - PROCESS | 1919 | |9;json-stringify-tinderbox;67;66;69;73;74;91;107;103;80;71 16:03:59 INFO - PROCESS | 1919 | |10;stanford-crypto-aes;88;86;86;83;96;105;106;83;85;84 16:03:59 INFO - PROCESS | 1919 | |11;stanford-crypto-ccm;146;153;135;136;137;138;138;134;183;159 16:03:59 INFO - PROCESS | 1919 | |12;stanford-crypto-pbkdf2;168;168;168;170;203;166;169;167;168;176 16:03:59 INFO - PROCESS | 1919 | |13;stanford-crypto-sha256-iterative;66;67;67;65;67;67;66;65;66;64 16:03:59 INFO - PROCESS | 1919 | __end_tp_report 16:03:59 INFO - PROCESS | 1919 | __start_cc_report 16:03:59 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,2126 16:03:59 INFO - PROCESS | 1919 | __end_cc_report 16:03:59 INFO - PROCESS | 1919 | __startTimestamp1472598239468__endTimestamp 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | ------- Summary: start ------- 16:03:59 INFO - PROCESS | 1919 | Number of tests: 14 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#0] ai-astar Cycles:10 Average:115.00 Median:113.50 stddev:6.70 (5.9%) stddev-sans-first:6.61 16:03:59 INFO - PROCESS | 1919 | Values: 108.0 113.0 133.0 114.0 113.0 117.0 114.0 112.0 113.0 113.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:130.50 stddev:20.62 (15.8%) stddev-sans-first:20.95 16:03:59 INFO - PROCESS | 1919 | Values: 146.0 115.0 130.0 161.0 108.0 131.0 128.0 108.0 159.0 106.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:2.04 (1.1%) stddev-sans-first:2.06 16:03:59 INFO - PROCESS | 1919 | Values: 186.0 187.0 185.0 184.0 184.0 185.0 181.0 181.0 183.0 186.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#3] audio-fft Cycles:10 Average:82.60 Median:64.50 stddev:35.19 (54.6%) stddev-sans-first:37.11 16:03:59 INFO - PROCESS | 1919 | Values: 72.0 80.0 76.0 153.0 58.0 58.0 61.0 68.0 142.0 58.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:70.50 stddev:25.02 (35.5%) stddev-sans-first:26.46 16:03:59 INFO - PROCESS | 1919 | Values: 84.0 84.0 136.0 70.0 71.0 76.0 118.0 68.0 69.0 118.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:87.00 stddev:12.89 (14.8%) stddev-sans-first:13.51 16:03:59 INFO - PROCESS | 1919 | Values: 86.0 127.0 97.0 85.0 88.0 85.0 89.0 86.0 88.0 86.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#6] imaging-darkroom Cycles:10 Average:106.50 Median:93.50 stddev:22.66 (24.2%) stddev-sans-first:23.58 16:03:59 INFO - PROCESS | 1919 | Values: 94.0 94.0 94.0 95.0 92.0 115.0 150.0 145.0 93.0 93.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#7] imaging-desaturate Cycles:10 Average:83.20 Median:85.50 stddev:5.43 (6.4%) stddev-sans-first:5.55 16:03:59 INFO - PROCESS | 1919 | Values: 79.0 80.0 85.0 86.0 84.0 84.0 86.0 91.0 86.0 71.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.74 (1.3%) stddev-sans-first:0.71 16:03:59 INFO - PROCESS | 1919 | Values: 56.0 56.0 58.0 57.0 58.0 57.0 57.0 56.0 57.0 57.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#9] json-stringify-tinderbox Cycles:10 Average:80.10 Median:72.00 stddev:15.02 (20.9%) stddev-sans-first:15.17 16:03:59 INFO - PROCESS | 1919 | Values: 67.0 66.0 69.0 73.0 74.0 91.0 107.0 103.0 80.0 71.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:85.50 stddev:8.89 (10.4%) stddev-sans-first:9.40 16:03:59 INFO - PROCESS | 1919 | Values: 88.0 86.0 86.0 83.0 96.0 105.0 106.0 83.0 85.0 84.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:142.00 stddev:15.50 (10.9%) stddev-sans-first:16.44 16:03:59 INFO - PROCESS | 1919 | Values: 146.0 153.0 135.0 136.0 137.0 138.0 138.0 134.0 183.0 159.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:168.50 stddev:11.13 (6.6%) stddev-sans-first:11.69 16:03:59 INFO - PROCESS | 1919 | Values: 168.0 168.0 168.0 170.0 203.0 166.0 169.0 167.0 168.0 176.0 16:03:59 INFO - PROCESS | 1919 | 16:03:59 INFO - PROCESS | 1919 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.05 (1.6%) stddev-sans-first:1.12 16:03:59 INFO - PROCESS | 1919 | Values: 66.0 67.0 67.0 65.0 67.0 67.0 66.0 65.0 66.0 64.0 16:03:59 INFO - PROCESS | 1919 | -------- Summary: end -------- 16:03:59 INFO - PROCESS | 1919 | 16:04:00 INFO - TEST-INFO | 1919: exit 0 16:04:00 INFO - TEST-OK | kraken | took 59911ms 16:04:00 INFO - SUITE-END | took 1177s 16:04:00 INFO - Completed test suite (00:19:37) 16:04:00 INFO - javascript benchmark 16:04:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3275.7051865332123, 3299.3731191073693, 3342.9498557521642, 3373.713234375078, 3388.8136152511615, 5558.77764889405, 5653.773849046039, 5690.687012214332, 5698.487136157745, 5727.226824378713, 5696.544276457894, 5711.086226203808, 5821.446962538552, 5841.503472204857, 5850.619709718876, 5123.795048198068, 5161.767720452589, 5192.688438693691, 5197.636165468419, 5207.791688332462, 5294.52349288564, 5300.92048619271, 5302.734863256841, 5330.626856120073, 5352.2239275305055, 5110.66780659256, 5169.612279079047, 5170.0, 5202.661826981253, 5237.528622423962, 5092.312537807423, 5176.145935920568, 5187.507186817246, 5221.138512145491, 5225.425203227642, 5130.358705161855, 5144.511271429208, 5162.277281180638, 5197.350331208599, 5223.947760522384, 5707.686077265748, 5734.111212762028, 5738.225339579145, 5740.339860916007, 5743.798967036176, 5029.245613158019, 6120.234970628671, 6177.969110154466, 6240.625562466221, 6264.404881536247, 6175.07373893915, 6255.843603909893, 6301.527385446064, 6318.178636777236, 6318.589291696071, 5817.650940943557, 5819.563532735062, 5825.388334224887, 5832.708364581754, 5839.883202335929, 6163.075538669235, 6168.444839964424, 6219.0, 6228.34602366754, 6263.71813268404, 6112.0526318419825, 6142.508599312045, 6161.907571386433, 6182.319944806068, 6183.81448556535, 6289.245290565162, 6296.118543403927, 6299.842503937438, 6306.0, 6312.5581209314905, 6085.665288409092, 6130.581587307925, 6143.784967526115, 6154.846128846725, 6231.719572619243, 6196.845078873064, 6205.379462053849, 6229.408206220402, 6246.031865060922, 6246.375362463808, 4888.682235654671, 4891.951080489221, 4897.094037603055, 4901.142300097468, 4934.062528119646, 4377.430933978626, 4405.185040767468, 4419.447569053868, 4433.357163211381, 4467.977660111678, 5710.143478478261, 5727.942720572741, 5737.426257374229, 5792.82621521355, 5804.100364443518, 6253.374662533801, 6278.121063051177, 6283.842903927347, 6284.0, 6295.685215739195, 5592.888142237214, 5652.406497317805, 5659.405762394971, 5714.828555143269, 5737.483626473638, 5354.839354819362, 5372.57019438444, 5410.3507579090765, 5413.133898576209, 5474.452554744494, 6246.46905013079, 6286.245650521967, 6292.716827742855, 6296.275928268308, 6308.873822523616, 6123.465646421035, 6274.717637706223, 6291.528135389955, 6293.590916590407, 6304.590201636879, 6223.3465486125115, 6235.407636274546, 6243.656598887105, 6312.400321969406, 6324.936750632619, 6162.322144564184, 6183.201557471435, 6192.380761923771, 6196.938030619816, 6234.657093859882, 6127.050307202391, 6249.531285153723, 6270.184875966278, 6276.058591211355, 6283.968580157253, 6039.48664363534, 6076.756929722762, 6112.816615501542, 6114.571104522668, 6147.754089836357, 6082.817515474543, 6102.9694851525455, 6103.664298463627, 6118.143459915526, 6122.969385153046, 5686.630369026, 5877.441643043904, 5944.554158438221, 5969.522438204846, 5985.581009329305, 6042.848928776816, 6053.757849685963, 6085.939140608537, 6136.917362303904, 6143.419763699168, 5924.851878703067, 5930.703464826827, 5940.851478713067, 5949.25634295713, 5994.730237139252], "value": 5699.363498700201, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.7717718318023, 468.6414892607154, 502.36701755787703, 518.8469401526551, 534.9812756553519, 30547.541786873186, 32118.197045073885, 32235.67764322359, 39214.41178382326, 40721.18557628846, 42722.78638606802, 42909.71270861871, 43051.138977220435, 43093.707188784385, 43096.0, 8431.241188293052, 9604.807903841918, 9685.612575496973, 9782.0, 9818.165455936254, 8726.825463490759, 8926.330525210602, 9588.568514416833, 9683.370580912218, 11721.58974435895, 11696.356700381475, 11998.160128790989, 12057.698557536089, 12063.974562162226, 12067.637970860887, 11963.40182990847, 12031.518739250507, 12040.759184816343, 12045.096617753703, 12053.57812476559, 11597.536098556047, 11631.941840290745, 11640.301581905112, 11657.475413606408, 11666.0, 37346.69286574984, 37851.43222851659, 39866.20267594661, 40126.39810402817, 40171.995700107436, 3394.389009978203, 3418.453047512411, 3490.1798077451776, 3787.9431808522895, 4178.8328466861285, 30295.697043029508, 31245.0, 31494.740210391556, 32588.83705581457, 32704.182395440068, 3233.96766032339, 3264.5592844965863, 3281.0648965044848, 3282.179455136216, 3380.5098260752297, 486685.0, 488623.0, 490573.54713226564, 492573.0, 492740.0, 61612.0, 62158.0, 62312.0, 62322.68838655778, 62354.37645623576, 64844.0, 65129.0, 65146.67426662836, 65178.0, 65204.0, 14582.708345833236, 16762.83237167637, 16768.748468772978, 16769.24538395775, 16776.32894684199, 12767.872321276669, 12837.972962163005, 12843.165194262343, 12843.48626054966, 12856.357182140855, 14052.578422647337, 16228.026318420933, 16512.552478218386, 16575.585610359838, 16636.251368688438, 6370.044493325945, 6953.513254072166, 7834.725784597512, 7971.0, 8065.153158918347, 880.5201165364891, 910.5993362920294, 923.2521657457481, 1024.0680980307884, 1064.680595821266, 23104.884475577852, 29565.0, 29726.40547189044, 29838.40323193524, 30138.84930575333, 1000.4597517340554, 1040.843873418993, 1063.803196408667, 1073.409624706424, 1120.439780109945, 14092.87257019436, 15405.768913466307, 15451.227438628024, 15628.218589070728, 15884.444044458389, 197587.0, 200573.0, 200634.0, 200703.0, 200912.0, 48848.0, 55803.441965580634, 56682.0, 57153.714231428574, 57724.71137644369, 1348.5212749473992, 1371.0882263294798, 1395.6301580081285, 1403.7543429899727, 1404.1154072933987, 23633.763662363155, 30024.0, 31012.68987310188, 31400.726955798244, 31467.370652586364, 50803.22188723377, 51137.232941505164, 51467.45597632077, 51525.7423712879, 51545.7422712884, 460684.0, 479780.0, 480008.0, 597175.0, 597727.0, 40670.79664601658, 41222.58777412187, 41363.79318103511, 41386.75839724813, 41926.79036604797, 46066.0, 46149.098312105096, 46187.538124618324, 46507.53492465032, 47166.0, 37988.81005594954, 38449.80775096107, 38610.42084368793, 39231.80384098175, 39613.40579891361, 37398.81300593589, 37913.62086379101, 38225.61774382221, 38962.0, 39479.21041579095, 37356.06609834667, 38032.049198770255, 38053.42919856149, 38308.616913830505, 38450.61549384471, 31028.0, 32902.177445564055, 33675.66324336823, 33872.66127338695, 34766.65233347735, 24338.87830560836, 24507.142250021163, 24583.262502124966, 24663.01347946062, 24858.627120592842], "value": 19711.55905136692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.1592598134571, 363.2970202657857, 370.7756807131685, 374.0984228010494, 396.1937457274447, 14859.0, 15627.843721562782, 20359.28742494013, 21050.684239736416, 21107.15571377145, 20878.269260575882, 21166.68249976248, 21176.576468470623, 21247.468813279676, 21260.574788504222, 5636.859078523039, 5743.626664266831, 5806.303243610751, 5890.0, 5890.175375447441, 5710.600257981943, 5886.587938844283, 5888.352281249059, 5888.676122813244, 5903.616264942786, 5648.548116150719, 5776.403075178765, 5784.971075144618, 5842.152887831262, 5849.941500585003, 5788.305403351604, 5846.327672317695, 5849.38581448946, 5851.76592936282, 5874.706264686749, 4837.927431088537, 5082.364704411949, 5124.461931497176, 5137.0, 5141.15170996785, 25828.354291142685, 25917.870410648015, 25943.0, 26047.479050419075, 26268.86865565679, 2180.258712037915, 2267.036509483463, 2285.223024171773, 2436.817238707104, 3030.8029978051354, 13941.86058139426, 14597.70804583913, 15210.0, 15812.446564370191, 16010.359585616561, 1404.9227292498908, 1878.4928069421317, 1901.1539864760186, 1917.3289348727974, 1940.670086085369, 105998.0, 116197.99999999916, 116889.99999999916, 117180.0, 117622.0, 8047.436679432501, 8301.46040507365, 9880.258980576486, 9938.254630902711, 10571.15004874144, 12297.0, 12338.691532711575, 12370.690732731753, 12370.938145309396, 12372.44324005422, 7966.960165199137, 8071.798205044921, 8086.661290162029, 8093.635786389626, 8229.46508476947, 6876.518643694993, 8509.319254459628, 8669.306455483546, 8697.478151310941, 8711.60797764102, 3259.071164718044, 3794.6774524165753, 3850.3454412749898, 3867.3812190049457, 3933.704972127102, 1853.7497437845718, 1908.885466871992, 1912.7609048868892, 1913.722510236008, 1918.3669389101563, 838.9790255243546, 876.9956150219208, 910.8815853939078, 927.3369540778241, 934.5514153206499, 19902.800971990386, 20281.391558253275, 20712.48218794512, 21447.463813404476, 21652.4586885326, 420.2414641571931, 883.8055627761883, 922.3543519536352, 976.2385339435252, 989.8960609136082, 5563.888722225533, 5870.471657550841, 5911.6944391687275, 6071.878562428727, 6079.3920607939735, 587006.0, 600502.0, 614973.0, 622914.0, 623478.0, 78480.70991883404, 104979.0, 112911.43544282226, 133809.2738407699, 139647.0, 445.56557356577724, 447.708989157044, 456.64838074682973, 456.82868924153445, 459.4073644997982, 11514.424278786195, 11527.020203282815, 11711.590094346657, 11737.29576225395, 11826.408679566159, 51882.0, 54323.641908952595, 54893.0, 56093.15860262018, 56971.0, 80360.79458808005, 80526.0, 80754.19245807467, 82492.35015299541, 83087.0, 6671.633060181736, 7928.0, 8018.719344823136, 8104.5137291762685, 8124.918750812652, 10482.895171048496, 12081.395930203631, 12509.93665538403, 12762.298073605669, 12951.740965180832, 10780.407077610807, 10788.0, 11116.666500005012, 11132.833007504732, 11178.27341222818, 9237.67668131639, 9927.255455840985, 10897.0, 11198.776024479303, 11207.991280784769, 10286.691399258034, 10655.680329590125, 10667.89332106669, 10753.085987691135, 11504.884951150716, 8081.393895457982, 8110.716124935599, 8283.254507094149, 8299.377546684143, 8463.265030668575, 8968.0, 11031.282966607145, 11176.720581985515, 11337.603183888854, 11572.884271157182], "value": 8895.490590205667, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.0367764630637, 1507.5402002389274, 1702.4977631598676, 1844.9631007379844, 1874.8968806715625, 6975.0, 7862.764117076483, 8396.538190399526, 8989.235914947229, 9001.639934402616, 8396.160383961616, 8714.91285087149, 8719.084496127894, 8783.516906570136, 8803.075677053897, 2372.335745991121, 2401.3396316013063, 2401.6157414813633, 2401.7238017627933, 2412.203972689017, 2117.2060477321006, 2200.0869639099756, 2203.504211552396, 2349.1308215960034, 2377.9286621401297, 1887.8206570375792, 2175.6845257437662, 2204.9889750551306, 2379.869107199112, 2397.61638137898, 2321.6981792367055, 2338.847974881627, 2342.051469154995, 2351.141833230867, 2354.0701422938014, 2347.0, 2347.847389919667, 2349.189529612281, 2350.6944097267415, 2351.1888398502488, 8768.868466973001, 8835.072317406644, 8847.911520884772, 8859.424137431111, 8862.291016718647, 5113.974430127825, 5351.7056561889085, 5353.678779273256, 5358.142697168473, 5358.544523715489, 2873.339131999631, 3232.7575431842706, 3276.1154488288266, 3400.8129552874584, 3416.24842534642, 1087.0977089016098, 1097.8133717268083, 1100.9889901100967, 1117.1788735279563, 1122.0630773304301, 11590.536378544848, 11741.4716337765, 11762.353070581115, 11785.233959792586, 11802.94098529502, 4524.751138687404, 4592.540745925381, 4759.833405830779, 4793.712377257376, 4804.0, 9953.0, 9972.252081093802, 9973.950130249448, 9988.151007164472, 9990.900090999143, 5347.919781203285, 5356.598255130881, 5358.571314294847, 5363.758630861621, 5364.463553644682, 4690.624750019991, 4824.324594556765, 4907.411110666742, 4912.484189160086, 4926.778294976734, 3720.107174278208, 3728.813559322023, 3784.148566572499, 3819.2170605025904, 3826.1391186982705, 1096.199774164864, 1111.2943281016612, 1124.2186680257144, 1125.3023125662141, 1130.7003644034337, 723.4754802768057, 766.4634755671052, 850.6937502499095, 882.4837470079947, 958.4919992404037, 5503.944960550423, 5648.887022259614, 5721.027425337703, 5790.652560846278, 5853.0635098384055, 809.8380323935237, 919.6965001549472, 931.5528546297679, 937.7561833923093, 983.9901600984042, 3280.8851690190727, 3762.755420897633, 3766.9058273543383, 3888.3389823730035, 3931.410288456754, 8108.43240973138, 10727.0, 10851.78296434067, 10862.239643224899, 10881.075108615856, 10073.546690398949, 10799.352038877692, 10825.13398928084, 10827.729306767395, 10865.0, 7803.882941755878, 8243.464174828618, 8247.381446391424, 8251.546164960864, 8252.174782521699, 5799.130130480462, 5840.415958404125, 5985.730642121202, 6001.759929602942, 6031.396860314109, 9858.950705246429, 10041.347312424668, 10071.34536255141, 10075.294729368874, 10106.039926206999, 9608.95195524018, 10068.194544436574, 10094.79810403773, 10241.07830295277, 10253.487325633838, 7407.851842963219, 7565.962170189114, 7570.507916985378, 7579.393648507996, 7588.8482230353975, 8140.633671484916, 8143.348532117535, 8144.267015968354, 8155.592220389076, 8248.13394593583, 6279.686015699105, 7373.395017441259, 7450.403967682681, 7515.173330933702, 7621.847563048819, 7338.596377199306, 7372.852542949218, 7392.4825262231125, 7409.888851667121, 7440.218777028334, 6847.9385695217325, 6873.347032031949, 6880.0, 6930.792076237721, 6963.1644202696, 4786.114568804782, 4794.424669039738, 4839.903201936012, 4854.75726213695, 4862.319275301531, 4893.09477746618, 4941.184704523714, 4972.453030166751, 5020.673656212334, 5156.767945442389], "value": 4520.295189269561, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.3228766187463, 260.2764315203733, 297.90169244149433, 313.15448289618035, 313.55005567011324, 5182.896342073156, 5198.220266959958, 5212.582993360532, 5229.777344628527, 5262.210668399742, 4531.614812740921, 4707.505711900244, 5023.572996295319, 5069.338597236453, 5161.587073034158, 4018.5177778666603, 4030.1133750574973, 4042.5148982122187, 4053.817578208973, 4062.329715596923, 3712.3874560697586, 4027.8791636250958, 4036.777977211252, 4071.1857628474277, 4113.280175969208, 3662.3773958427, 3788.2423515296914, 4023.356262997921, 4029.1465925674224, 4053.27041132596, 3532.763532763538, 3812.1994381179984, 3946.125590344592, 3954.8643732384476, 3990.8204130814183, 2820.605115283862, 3205.3108581655056, 3474.6525347465304, 3482.2339085401422, 3493.9650603494147, 4539.251023581105, 4582.404287442611, 4860.878478038042, 5005.974970125126, 5017.34774479319, 3791.639794219545, 4242.045539753562, 4302.806373713159, 4357.649128770091, 4551.795169217393, 4640.559146881041, 5015.673981191211, 5144.09668422686, 5443.727813609304, 5469.206964990054, 3836.0985168485386, 3841.769493830351, 3843.538775346948, 3845.3270677631303, 3887.436321733333, 4727.841678788719, 5356.598255130881, 5647.887042259173, 5677.659340439587, 5708.115242137475, 3336.5328853960464, 3525.471179323071, 3723.236736469017, 3765.322241996467, 3770.1328694399895, 3818.961810381916, 3837.846486140523, 3863.6715879150024, 3864.7487913285545, 3920.3923391874305, 4122.072533679958, 4441.000774825703, 4460.687751857404, 4522.389477420572, 4531.750753708512, 4544.613707834871, 4709.858704238878, 4972.254161875748, 5336.813211537577, 5500.779968801203, 1274.7386785708907, 1293.6701141208932, 1328.7708869296057, 1328.9169326998465, 1331.2627021736726, 800.2397722163968, 826.8677011678103, 840.8486472434898, 858.6994551906782, 881.0220655072864, 4185.0, 4563.247064234364, 5104.642675012714, 5119.0273847968765, 5160.200168973815, 5543.584231182677, 5587.888242235133, 5608.0, 5630.577706672016, 5670.829875103754, 4440.600345968814, 4917.434495033117, 5130.050940576006, 5142.202958541432, 5319.882824606876, 2584.0490429725437, 2938.017911819505, 2985.208919636298, 3010.969890301069, 3021.743151832075, 4995.525425084611, 5241.977814326246, 5868.002439585281, 5879.970600146972, 5984.281886173687, 4537.455505339381, 5829.446202610745, 5902.343945224666, 5909.763609455659, 5937.495312898365, 5903.498202652737, 5918.852028699231, 5920.644761314335, 5939.732712028055, 5941.079132734433, 4793.856184314476, 4852.927206091841, 5490.835274941598, 5711.857203569944, 5729.369769325454, 4744.59670927975, 5402.378726446509, 5576.518449290027, 5666.206731057738, 5678.375378708256, 5898.321693005188, 5905.734241959036, 5918.763249469973, 5936.317323507852, 5949.553783466344, 3525.0, 3762.830672619684, 3814.332491813977, 3837.5586807517493, 3841.366174581163, 4994.80020799164, 5665.263515743092, 5669.824898057102, 5675.31896172462, 5688.800891968761, 4961.7519124044375, 5289.492494639163, 5532.668039917457, 5555.555555555465, 5560.7219639018695, 5282.894342113059, 5510.752016159363, 5518.420565840529, 5522.211283301906, 5563.415841336601, 5154.278401023935, 5389.8113566025, 5403.891922161456, 5414.7021913795115, 5423.620346575702, 5342.13025441108, 5427.83716488506, 5440.7833928703985, 5657.377688454185, 5663.207150998781, 5428.891422171613, 5432.619716619799, 5462.207979842821, 5468.808591699271, 5469.535089517435], "value": 4015.5395452813177, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.0891450029462, 708.6563016936786, 721.5382155420529, 767.4627760567604, 815.2499700275744, 14860.0, 15585.454509092184, 15598.53204403867, 15631.218439078044, 15641.765373519378, 12325.506979720823, 13978.650533736662, 14072.577822665311, 14119.223442710647, 14140.71718565628, 690.6201589125985, 717.2576383443137, 717.630420333526, 718.7807718645811, 793.2345286798253, 706.8621618784339, 709.5068927095671, 842.2083241752763, 876.570480464571, 884.5090974509177, 919.0717375450776, 930.7254359963827, 936.4053825820585, 937.5687183895416, 938.2822141062059, 911.8313112074287, 912.6942474271127, 913.7167478081783, 914.419343716738, 914.6661468563926, 689.3141324382221, 690.4614400767409, 691.3915754136328, 691.7959202035362, 692.9029935808991, 9810.754731131707, 13959.581212563638, 15284.082955022623, 15376.077435353915, 15384.30770615325, 14306.28468576567, 14838.25808709571, 14992.100473971597, 14999.925000374928, 15017.399304027826, 11901.226420282654, 11975.520979160823, 12015.399230038463, 12085.93957030218, 12241.816372754416, 3799.0692280391204, 3823.4073718573663, 3824.2160357126722, 3841.9039524011846, 3960.485136932209, 98489.0151098487, 99299.0070099297, 99417.5029124857, 105495.0, 119788.40105799503, 11259.943700281447, 11346.3759493227, 11410.087193024538, 11788.351640659846, 11901.880981190077, 23991.0, 24089.156879509133, 24091.638625420634, 24095.0, 24158.912848921842, 7142.250063743232, 8081.555514446762, 8091.55496447701, 8139.226773456512, 8204.794880127925, 3974.28462876685, 4163.875083747432, 4250.277452833026, 4514.616257618073, 4690.0, 3558.430651095812, 3691.0956815580353, 3691.53855768029, 3691.833867475916, 3697.5932647408763, 652.9967350163218, 654.509118161379, 659.9967000164968, 660.579170463098, 669.8325418645338, 438.8880835386956, 645.3675398109879, 658.3613894522306, 665.6538599928107, 684.750066225824, 9116.179543841086, 9223.169914707709, 9240.768980775534, 9359.766005849908, 9366.157045865904, 425.33435173952665, 610.4170517156106, 625.5496042849142, 652.5790864892118, 664.2792570061441, 4469.821207151677, 4505.324201369755, 4553.54464553542, 4612.30815377696, 4693.647976401783, 151944.0, 152891.0, 154722.0, 157148.0, 169213.30786691976, 129982.35008824896, 131742.0, 132787.0, 134640.3267983654, 135213.0, 108394.3740843888, 112509.0, 113108.43445782883, 113388.79500844964, 116287.0, 9350.345475816628, 9744.947901397889, 9745.966442786912, 9748.697380880254, 9754.694049656304, 57568.0, 59728.0, 63186.0, 64995.0, 66469.0, 92249.53875230582, 95510.52244738732, 96426.55360169463, 97453.0, 98973.0, 12604.55884044054, 13039.608811735663, 13372.130811497222, 13376.0, 13448.46206151743, 14590.927045364706, 14794.186319752087, 14946.0, 14994.100353978796, 15524.68950621004, 13706.93146534261, 13925.442982280596, 13940.372683229076, 14068.859311407165, 14276.0, 11413.60052398162, 13113.475460981454, 13381.866181338062, 13664.863351366359, 13781.448742050206, 13356.465741370237, 13390.464381424634, 13569.592912212649, 14026.368813403626, 14083.366248518636, 11102.056325212125, 11170.385628790495, 11220.719482012688, 11275.098508233019, 11778.587749428729, 10206.387616743019, 10542.472876355998, 10734.409607471664, 11221.0, 11421.428928553705], "value": 7848.381202102682, "unit": "score"}], "name": "dromaeo_css", "value": 7226.183838190603, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 113.0, 133.0, 114.0, 113.0, 117.0, 114.0, 112.0, 113.0, 113.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 130.0, 161.0, 108.0, 131.0, 128.0, 108.0, 159.0, 106.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 185.0, 184.0, 184.0, 185.0, 181.0, 181.0, 183.0, 186.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 80.0, 76.0, 153.0, 58.0, 58.0, 61.0, 68.0, 142.0, 58.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 136.0, 70.0, 71.0, 76.0, 118.0, 68.0, 69.0, 118.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 127.0, 97.0, 85.0, 88.0, 85.0, 89.0, 86.0, 88.0, 86.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 92.0, 115.0, 150.0, 145.0, 93.0, 93.0], "value": 106.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 85.0, 86.0, 84.0, 84.0, 86.0, 91.0, 86.0, 71.0], "value": 83.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 58.0, 57.0, 58.0, 57.0, 57.0, 56.0, 57.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 73.0, 74.0, 91.0, 107.0, 103.0, 80.0, 71.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 83.0, 96.0, 105.0, 106.0, 83.0, 85.0, 84.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 153.0, 135.0, 136.0, 137.0, 138.0, 138.0, 134.0, 183.0, 159.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 168.0, 170.0, 203.0, 166.0, 169.0, 167.0, 168.0, 176.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 65.0, 67.0, 67.0, 66.0, 65.0, 66.0, 64.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1493.2, "alertThreshold": 2.0}]} 16:04:00 INFO - Return code: 0 16:04:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:04:00 INFO - # TBPL SUCCESS # 16:04:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:04:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:04:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:04:00 INFO - 2016-08-30 16:04:00.793 screenresolution[1923:10980] starting screenresolution argv=screenresolution get 16:04:00 INFO - 2016-08-30 16:04:00.796 screenresolution[1923:10980] Display 0: 1600x1200x32@60 16:04:00 INFO - 2016-08-30 16:04:00.802 screenresolution[1924:10983] starting screenresolution argv=screenresolution list 16:04:00 INFO - Available Modes on Display 0 16:04:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:04:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:04:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:04:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:04:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:04:01 INFO - Intel Iris: 16:04:01 INFO - Chipset Model: Intel Iris 16:04:01 INFO - Type: GPU 16:04:01 INFO - Bus: Built-In 16:04:01 INFO - VRAM (Dynamic, Max): 1536 MB 16:04:01 INFO - Vendor: Intel (0x8086) 16:04:01 INFO - Device ID: 0x0a2e 16:04:01 INFO - Revision ID: 0x0009 16:04:01 INFO - Displays: 16:04:01 INFO - Display: 16:04:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:04:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:04:01 INFO - Display Serial Number: 1600x1200 60 16:04:01 INFO - Main Display: Yes 16:04:01 INFO - Mirror: Off 16:04:01 INFO - Online: Yes 16:04:01 INFO - Rotation: Supported 16:04:01 INFO - Return code: 0 16:04:01 INFO - Running post-action listener: _resource_record_post_action 16:04:01 INFO - [mozharness: 2016-08-30 23:04:01.036060Z] Finished run-tests step (success) 16:04:01 INFO - Running post-run listener: _resource_record_post_run 16:04:01 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1847296; Write bytes: 705524736; Read time: 23; Write time: 2204 16:04:01 INFO - TinderboxPrint: CPU usage
53.5% 16:04:01 INFO - TinderboxPrint: I/O read bytes / time
1,847,296 / 23 16:04:01 INFO - TinderboxPrint: I/O write bytes / time
705,524,736 / 2,204 16:04:01 INFO - TinderboxPrint: CPU idle
2,254.0 (46.5%) 16:04:01 INFO - TinderboxPrint: CPU system
972.2 (20.0%) 16:04:01 INFO - TinderboxPrint: CPU user
1,623.5 (33.5%) 16:04:01 INFO - TinderboxPrint: Swap in / out
408,088,576 / 0 16:04:01 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404804096; Write bytes: 396679168; Read time: 29738; Write time: 660 16:04:01 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 897024; Write bytes: 296098816; Read time: 13; Write time: 1511 16:04:01 INFO - Running post-run listener: _upload_blobber_files 16:04:01 INFO - Blob upload gear active. 16:04:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:04:01 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 16:04:01 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'] 16:04:01 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 16:04:01 INFO - (blobuploader) - INFO - Open directory for files ... 16:04:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:04:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:04:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:02 INFO - (blobuploader) - INFO - Done attempting. 16:04:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:04:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:04:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:03 INFO - (blobuploader) - INFO - Done attempting. 16:04:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:04:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:04:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:05 INFO - (blobuploader) - INFO - Done attempting. 16:04:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:04:05 INFO - Return code: 0 16:04:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:04:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:04:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d2ec81a46d70f1b607dffd334b462d72bc0d975edcd45770d16e062a838855e56faf726f92c42e6e1bde9854c9bcd11506d7df600beb3823a0ddeae108a7d10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8418f75c093415d53ca74358552bd2c97d83d55231d6c234132d69695d4ef7717fc1319a1556ebba1b5fa0675a89c443518d575cd29ab85d5e2a710e19c7cb37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/115bb9f460815097b1c4634c3af9904e374caf0381916b2e43711915c157e3cfd52e36aec2cd6b818058fbfbbaeda0137a49f86f75a0936511476cb8b7754722"} 16:04:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:04:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:04:05 INFO - Contents: 16:04:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d2ec81a46d70f1b607dffd334b462d72bc0d975edcd45770d16e062a838855e56faf726f92c42e6e1bde9854c9bcd11506d7df600beb3823a0ddeae108a7d10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8418f75c093415d53ca74358552bd2c97d83d55231d6c234132d69695d4ef7717fc1319a1556ebba1b5fa0675a89c443518d575cd29ab85d5e2a710e19c7cb37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/115bb9f460815097b1c4634c3af9904e374caf0381916b2e43711915c157e3cfd52e36aec2cd6b818058fbfbbaeda0137a49f86f75a0936511476cb8b7754722"} 16:04:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:04:05 INFO - Copying logs to upload dir... 16:04:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:04:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.887841 ========= master_lag: 25.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-08-30 16:04:31.278477) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-08-30 16:04:31.282690) ========= 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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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/8d2ec81a46d70f1b607dffd334b462d72bc0d975edcd45770d16e062a838855e56faf726f92c42e6e1bde9854c9bcd11506d7df600beb3823a0ddeae108a7d10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8418f75c093415d53ca74358552bd2c97d83d55231d6c234132d69695d4ef7717fc1319a1556ebba1b5fa0675a89c443518d575cd29ab85d5e2a710e19c7cb37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/115bb9f460815097b1c4634c3af9904e374caf0381916b2e43711915c157e3cfd52e36aec2cd6b818058fbfbbaeda0137a49f86f75a0936511476cb8b7754722"} build_url:https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/BC-4hSxMS3qi2pdHrQBeKQ/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/8d2ec81a46d70f1b607dffd334b462d72bc0d975edcd45770d16e062a838855e56faf726f92c42e6e1bde9854c9bcd11506d7df600beb3823a0ddeae108a7d10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8418f75c093415d53ca74358552bd2c97d83d55231d6c234132d69695d4ef7717fc1319a1556ebba1b5fa0675a89c443518d575cd29ab85d5e2a710e19c7cb37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/115bb9f460815097b1c4634c3af9904e374caf0381916b2e43711915c157e3cfd52e36aec2cd6b818058fbfbbaeda0137a49f86f75a0936511476cb8b7754722"}' ========= master_lag: 4.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-08-30 16:04:35.540231) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 16:04:35.540571) ========= 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.aSKqjNXdkg/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.UI2dZd1QzM/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005269 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 16:04:35.583990) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 16:04:35.584453) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 16:04:35.585920) ========= ========= Total master_lag: 29.89 =========