builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0342 starttime: 1484898229.4 results: success (0) buildid: 20170119223124 builduid: 25c8bca62c634893bcf928fde825b34c revision: 4a274293090db1dce0d2bbce05ebb6f4cf7f45ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:49.396655) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:49.397095) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:49.397386) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:49.423625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:49.423895) ========= 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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 --2017-01-19 23:43:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-01-19 23:43:49 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153280 ========= master_lag: 0.36 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:49.932318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:49.932625) ========= 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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.025017 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:49.979506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-19 23:43:49.979834) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4a274293090db1dce0d2bbce05ebb6f4cf7f45ec --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4a274293090db1dce0d2bbce05ebb6f4cf7f45ec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tvaTzk1FX7/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.YD0el8PdnJ/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 2017-01-19 23:43:50,091 truncating revision to first 12 chars 2017-01-19 23:43:50,091 Setting DEBUG logging. 2017-01-19 23:43:50,091 attempt 1/10 2017-01-19 23:43:50,091 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4a274293090d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-19 23:43:52,387 unpacking tar archive at: mozilla-aurora-4a274293090d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.550303 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-19 23:43:52.553180) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:52.553639) ========= script_repo_revision: 4a274293090db1dce0d2bbce05ebb6f4cf7f45ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:52.554109) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:52.554375) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 23:43:52.574035) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-19 23:43:52.574307) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tvaTzk1FX7/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.YD0el8PdnJ/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:43:52 INFO - MultiFileLogger online at 20170119 23:43:52 in /builds/slave/test 23:43:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:43:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:43:52 INFO - {'append_to_log': False, 23:43:52 INFO - 'base_work_dir': '/builds/slave/test', 23:43:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:43:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:43:52 INFO - 'branch': 'Mozilla-Aurora', 23:43:52 INFO - 'buildbot_json_path': 'buildprops.json', 23:43:52 INFO - 'config_files': ('talos/mac_config.py',), 23:43:52 INFO - 'default_actions': ('clobber', 23:43:52 INFO - 'read-buildbot-config', 23:43:52 INFO - 'download-and-extract', 23:43:52 INFO - 'populate-webroot', 23:43:52 INFO - 'create-virtualenv', 23:43:52 INFO - 'install', 23:43:52 INFO - 'run-tests'), 23:43:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:43:52 INFO - 'download_minidump_stackwalk': True, 23:43:52 INFO - 'download_symbols': 'ondemand', 23:43:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:43:52 INFO - 'tooltool.py': '/tools/tooltool.py', 23:43:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:43:52 INFO - '/tools/misc-python/virtualenv.py')}, 23:43:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:43:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:43:52 INFO - 'installer_path': 'installer.exe', 23:43:52 INFO - 'log_level': 'info', 23:43:52 INFO - 'log_name': 'talos', 23:43:52 INFO - 'log_to_console': True, 23:43:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:43:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:43:52 INFO - 'opt_config_files': (), 23:43:52 INFO - 'pip_index': False, 23:43:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:43:52 INFO - 'cmd': ('bash', 23:43:52 INFO - '-c', 23:43:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:43:52 INFO - 'enabled': True, 23:43:52 INFO - 'halt_on_failure': False, 23:43:52 INFO - 'name': 'check_screen_resolution'},), 23:43:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:43:52 INFO - 'cmd': ('bash', 23:43:52 INFO - '-c', 23:43:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:43:52 INFO - 'enabled': True, 23:43:52 INFO - 'halt_on_failure': False, 23:43:52 INFO - 'name': 'check_screen_resolution'},), 23:43:52 INFO - 'run_cmd_checks_enabled': True, 23:43:52 INFO - 'sps_profile': False, 23:43:52 INFO - 'sps_profile_interval': 0, 23:43:52 INFO - 'suite': 'dromaeojs', 23:43:52 INFO - 'system_bits': '32', 23:43:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:43:52 INFO - 'title': 't-yosemite-r7-0342', 23:43:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:43:52 INFO - 'use_talos_json': True, 23:43:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:43:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:43:52 INFO - 'work_dir': 'build'} 23:43:52 INFO - [mozharness: 2017-01-20 07:43:52.792405Z] Running clobber step. 23:43:52 INFO - Running pre-action listener: _resource_record_pre_action 23:43:52 INFO - Running main action method: clobber 23:43:52 INFO - rmtree: /builds/slave/test/build 23:43:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:43:55 INFO - Running post-action listener: _resource_record_post_action 23:43:55 INFO - [mozharness: 2017-01-20 07:43:55.131564Z] Finished clobber step (success) 23:43:55 INFO - [mozharness: 2017-01-20 07:43:55.131708Z] Running read-buildbot-config step. 23:43:55 INFO - Running pre-action listener: _resource_record_pre_action 23:43:55 INFO - Running main action method: read_buildbot_config 23:43:55 INFO - Using buildbot properties: 23:43:55 INFO - { 23:43:55 INFO - "project": "", 23:43:55 INFO - "product": "firefox", 23:43:55 INFO - "script_repo_revision": "production", 23:43:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 23:43:55 INFO - "repository": "", 23:43:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 23:43:55 INFO - "buildid": "20170119223124", 23:43:55 INFO - "pgo_build": "False", 23:43:55 INFO - "basedir": "/builds/slave/test", 23:43:55 INFO - "buildnumber": 43, 23:43:55 INFO - "slavename": "t-yosemite-r7-0342", 23:43:55 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 23:43:55 INFO - "platform": "yosemite_r7", 23:43:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 23:43:55 INFO - "branch": "mozilla-aurora", 23:43:55 INFO - "repo_path": "releases/mozilla-aurora", 23:43:55 INFO - "revision": "4a274293090db1dce0d2bbce05ebb6f4cf7f45ec", 23:43:55 INFO - "stage_platform": "macosx64", 23:43:55 INFO - "builduid": "25c8bca62c634893bcf928fde825b34c", 23:43:55 INFO - "slavebuilddir": "test" 23:43:55 INFO - } 23:43:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 23:43:55 INFO - Running post-action listener: _resource_record_post_action 23:43:55 INFO - [mozharness: 2017-01-20 07:43:55.135031Z] Finished read-buildbot-config step (success) 23:43:55 INFO - [mozharness: 2017-01-20 07:43:55.135133Z] Running download-and-extract step. 23:43:55 INFO - Running pre-action listener: _resource_record_pre_action 23:43:55 INFO - Running main action method: download_and_extract 23:43:55 INFO - mkdir: /builds/slave/test/build/tests 23:43: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')]} 23:43:55 INFO - trying https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 23:43:55 INFO - Downloading https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 23:43:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 23:43:55 INFO - Downloaded 1199 bytes. 23:43:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 23:43:55 INFO - Using the following test package requirements: 23:43:55 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 23:43:55 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:43:55 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 23:43:55 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:43:55 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 23:43:55 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:43:55 INFO - u'jsshell-mac.zip'], 23:43:55 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:43:55 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 23:43:55 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 23:43:55 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:43:55 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 23:43:55 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:43:55 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 23:43:55 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:43:55 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 23:43:55 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 23:43:55 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 23:43:55 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 23:43:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 23:43:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:43:55 INFO - Fetch https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 23:43:56 INFO - Http code: 200 23:43:56 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 23:43:56 INFO - x-amz-cf-id: 7hsSYT1ktu4ie3b546vXL5P8NcGg93O32CK8RoDhLYNSnb89IZu30w== 23:43:56 INFO - x-amz-version-id: 4WVnQ22JAYGCwa7_fvljTsYDDnrGwb4z 23:43:56 INFO - Expected file size: 16276117 23:43:56 INFO - Obtained file size: 16276117 23:44:03 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 23:44:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 23:44:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:44:03 INFO - Fetch https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 23:44:04 INFO - Http code: 200 23:44:04 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 23:44:04 INFO - x-amz-cf-id: o4dX6XSXWA1xUTv0vDBSFVqHMBziXHBuBOU8fonMY67d5lXhhD5MbA== 23:44:04 INFO - x-amz-version-id: 4WVnQ22JAYGCwa7_fvljTsYDDnrGwb4z 23:44:04 INFO - Expected file size: 16276117 23:44:04 INFO - Obtained file size: 16276117 23:44:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 23:44:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 23:44:10 INFO - Fetch https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 23:44:11 INFO - Http code: 200 23:44:11 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 23:44:11 INFO - x-amz-cf-id: mgyAd9670ELXCw--zhFZCCKcIsFfmo-uPNGIsiMTbtSixPPN5GCOJA== 23:44:11 INFO - x-amz-version-id: SnTXPssgM32qSGvo57KF9BDSh3xvRQrW 23:44:11 INFO - Expected file size: 14409038 23:44:11 INFO - Obtained file size: 14409038 23:44: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')]} 23:44:11 INFO - trying https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 23:44:11 INFO - Downloading https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 23:44:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 23:44:14 INFO - Downloaded 109970410 bytes. 23:44:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 23:44:14 INFO - mkdir: /builds/slave/test/properties 23:44:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:44:14 INFO - Writing to file /builds/slave/test/properties/build_url 23:44:14 INFO - Contents: 23:44:14 INFO - build_url:https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 23:44:15 INFO - Running post-action listener: _resource_record_post_action 23:44:15 INFO - Running post-action listener: set_extra_try_arguments 23:44:15 INFO - [mozharness: 2017-01-20 07:44:15.587378Z] Finished download-and-extract step (success) 23:44:15 INFO - [mozharness: 2017-01-20 07:44:15.587506Z] Running populate-webroot step. 23:44:15 INFO - Running pre-action listener: _resource_record_pre_action 23:44:15 INFO - Running main action method: populate_webroot 23:44:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:44:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:44:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:44:15 INFO - '--tppagecycles', 23:44:15 INFO - '7'], 23:44:15 INFO - 'tests': ['tsvgm']}}, 23:44:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:44:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:44:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:44:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:44:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:44:15 INFO - 'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:44:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:44:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:44:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:44:15 INFO - 'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['damp', 'tps']}, 23:44:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:44:15 INFO - 'tests': ['damp', 'tps']}, 23:44:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['dromaeo_dom']}, 23:44:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:44:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['basic_compositor_video']}, 23:44:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:44:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['a11yr', 23:44:15 INFO - 'ts_paint', 23:44:15 INFO - 'tpaint', 23:44:15 INFO - 'sessionrestore', 23:44:15 INFO - 'sessionrestore_no_auto_restore', 23:44:15 INFO - 'tabpaint']}, 23:44:15 INFO - 'other-e10s': {'tests': ['a11yr', 23:44:15 INFO - 'ts_paint', 23:44:15 INFO - 'tpaint', 23:44:15 INFO - 'sessionrestore', 23:44:15 INFO - 'sessionrestore_no_auto_restore', 23:44:15 INFO - 'tabpaint']}, 23:44:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:44:15 INFO - 'ts_paint', 23:44:15 INFO - 'tpaint', 23:44:15 INFO - 'sessionrestore', 23:44:15 INFO - 'sessionrestore_no_auto_restore', 23:44:15 INFO - 'tabpaint']}, 23:44:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:44:15 INFO - 'ts_paint', 23:44:15 INFO - 'tpaint', 23:44:15 INFO - 'sessionrestore', 23:44:15 INFO - 'sessionrestore_no_auto_restore', 23:44:15 INFO - 'tabpaint']}, 23:44:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['a11yr', 23:44:15 INFO - 'ts_paint', 23:44:15 INFO - 'tpaint', 23:44:15 INFO - 'sessionrestore', 23:44:15 INFO - 'sessionrestore_no_auto_restore', 23:44:15 INFO - 'tabpaint']}, 23:44:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['a11yr', 23:44:15 INFO - 'ts_paint', 23:44:15 INFO - 'tpaint', 23:44:15 INFO - 'sessionrestore', 23:44:15 INFO - 'sessionrestore_no_auto_restore', 23:44:15 INFO - 'tabpaint']}, 23:44:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['tsvgx', 23:44:15 INFO - 'tsvgr_opacity', 23:44:15 INFO - 'tart', 23:44:15 INFO - 'tscrollx', 23:44:15 INFO - 'cart']}, 23:44:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:44:15 INFO - 'tsvgr_opacity', 23:44:15 INFO - 'tart', 23:44:15 INFO - 'tscrollx', 23:44:15 INFO - 'cart']}, 23:44:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:44:15 INFO - 'talos_options': ['--disable-e10s'], 23:44:15 INFO - 'tests': ['tp5o']}, 23:44:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:44:15 INFO - 'tests': ['tp5o']}, 23:44:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:44:15 INFO - 'talos_options': ['--disable-e10s', 23:44:15 INFO - '--xperf_path', 23:44:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:44:15 INFO - 'tests': ['tp5n']}, 23:44:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:44:15 INFO - 'talos_options': ['--xperf_path', 23:44:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:44:15 INFO - 'tests': ['tp5n']}}, 23:44:15 INFO - 'talos.zip': {'path': '', 23:44:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:44:15 INFO - Running post-action listener: _resource_record_post_action 23:44:15 INFO - [mozharness: 2017-01-20 07:44:15.605715Z] Finished populate-webroot step (success) 23:44:15 INFO - [mozharness: 2017-01-20 07:44:15.605860Z] Running create-virtualenv step. 23:44:15 INFO - Running pre-action listener: _resource_record_pre_action 23:44:15 INFO - Running main action method: create_virtualenv 23:44:15 INFO - Creating virtualenv /builds/slave/test/build/venv 23:44:15 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 23:44:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:44:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:44:15 INFO - Using real prefix '/tools/python27' 23:44:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:44:16 INFO - Installing distribute.............................................................................................................................................................................................done. 23:44:19 INFO - Installing pip.................done. 23:44:19 INFO - Return code: 0 23:44:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:44:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:44:19 INFO - Reading from file tmpfile_stdout 23:44:19 INFO - Output received: 23:44:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:44:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:44:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:44:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:19 INFO - 'HOME': '/Users/cltbld', 23:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:19 INFO - 'LOGNAME': 'cltbld', 23:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:19 INFO - 'MOZ_NO_REMOTE': '1', 23:44:19 INFO - 'NO_EM_RESTART': '1', 23:44:19 INFO - 'PAGER': '/bin/cat', 23:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:19 INFO - 'PWD': '/builds/slave/test', 23:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:19 INFO - 'SHELL': '/bin/bash', 23:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:19 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:19 INFO - 'USER': 'cltbld', 23:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:19 INFO - 'XPC_FLAGS': '0x0', 23:44:19 INFO - 'XPC_SERVICE_NAME': '0', 23:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:19 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 23:44:19 INFO - Cleaning up... 23:44:19 INFO - Return code: 0 23:44:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:44:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:44:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:19 INFO - 'HOME': '/Users/cltbld', 23:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:19 INFO - 'LOGNAME': 'cltbld', 23:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:19 INFO - 'MOZ_NO_REMOTE': '1', 23:44:19 INFO - 'NO_EM_RESTART': '1', 23:44:19 INFO - 'PAGER': '/bin/cat', 23:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:19 INFO - 'PWD': '/builds/slave/test', 23:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:19 INFO - 'SHELL': '/bin/bash', 23:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:19 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:19 INFO - 'USER': 'cltbld', 23:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:19 INFO - 'XPC_FLAGS': '0x0', 23:44:19 INFO - 'XPC_SERVICE_NAME': '0', 23:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:20 INFO - Downloading/unpacking psutil>=3.1.1 23:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:44:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:44:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:44:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:44:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:44:23 INFO - Installing collected packages: psutil 23:44:23 INFO - Running setup.py install for psutil 23:44:23 INFO - building 'psutil._psutil_osx' extension 23:44:23 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 23:44: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 23:44: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 23:44: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 23:44:24 INFO - building 'psutil._psutil_posix' extension 23:44: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 23:44:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:44:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:44:24 INFO - ^ 23:44:24 INFO - 1 warning generated. 23:44: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 23:44:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:44:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:44:24 INFO - Successfully installed psutil 23:44:24 INFO - Cleaning up... 23:44:24 INFO - Return code: 0 23:44:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:24 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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44:24 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 23:44:24 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 23:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:24 INFO - 'HOME': '/Users/cltbld', 23:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:24 INFO - 'LOGNAME': 'cltbld', 23:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:24 INFO - 'MOZ_NO_REMOTE': '1', 23:44:24 INFO - 'NO_EM_RESTART': '1', 23:44:24 INFO - 'PAGER': '/bin/cat', 23:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:24 INFO - 'PWD': '/builds/slave/test', 23:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:24 INFO - 'SHELL': '/bin/bash', 23:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:24 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:24 INFO - 'USER': 'cltbld', 23:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:24 INFO - 'XPC_FLAGS': '0x0', 23:44:24 INFO - 'XPC_SERVICE_NAME': '0', 23:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:44:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:44:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:44:27 INFO - Installing collected packages: mozsystemmonitor 23:44:27 INFO - Running setup.py install for mozsystemmonitor 23:44:27 INFO - Successfully installed mozsystemmonitor 23:44:27 INFO - Cleaning up... 23:44:28 INFO - Return code: 0 23:44:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:44: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')]} 23:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44: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')]} 23:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44: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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44: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 23:44: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 23:44:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:28 INFO - 'HOME': '/Users/cltbld', 23:44:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:28 INFO - 'LOGNAME': 'cltbld', 23:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:28 INFO - 'MOZ_NO_REMOTE': '1', 23:44:28 INFO - 'NO_EM_RESTART': '1', 23:44:28 INFO - 'PAGER': '/bin/cat', 23:44:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:28 INFO - 'PWD': '/builds/slave/test', 23:44:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:28 INFO - 'SHELL': '/bin/bash', 23:44:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:28 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:28 INFO - 'USER': 'cltbld', 23:44:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:28 INFO - 'XPC_FLAGS': '0x0', 23:44:28 INFO - 'XPC_SERVICE_NAME': '0', 23:44:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:28 INFO - Downloading/unpacking jsonschema==2.5.1 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:44:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44:31 INFO - Downloading functools32-3.2.3-2.tar.gz 23:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:44:31 INFO - warning: no files found matching '*.txt' 23:44:31 INFO - no previously-included directories found matching 'build' 23:44:31 INFO - no previously-included directories found matching 'dist' 23:44:31 INFO - no previously-included directories found matching '.git*' 23:44:31 INFO - Installing collected packages: jsonschema, functools32 23:44:31 INFO - Running setup.py install for functools32 23:44:31 INFO - warning: no files found matching '*.txt' 23:44:31 INFO - no previously-included directories found matching 'build' 23:44:31 INFO - no previously-included directories found matching 'dist' 23:44:31 INFO - no previously-included directories found matching '.git*' 23:44:31 INFO - Successfully installed jsonschema functools32 23:44:31 INFO - Cleaning up... 23:44:31 INFO - Return code: 0 23:44:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:44: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')]} 23:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44: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')]} 23:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44: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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44: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 23:44: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 23:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:31 INFO - 'HOME': '/Users/cltbld', 23:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:31 INFO - 'LOGNAME': 'cltbld', 23:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:31 INFO - 'MOZ_NO_REMOTE': '1', 23:44:31 INFO - 'NO_EM_RESTART': '1', 23:44:31 INFO - 'PAGER': '/bin/cat', 23:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:31 INFO - 'PWD': '/builds/slave/test', 23:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:31 INFO - 'SHELL': '/bin/bash', 23:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:31 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:31 INFO - 'USER': 'cltbld', 23:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:31 INFO - 'XPC_FLAGS': '0x0', 23:44:31 INFO - 'XPC_SERVICE_NAME': '0', 23:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:44:31 INFO - Cleaning up... 23:44:31 INFO - Return code: 0 23:44:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:44: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')]} 23:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44: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')]} 23:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44: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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44: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 23:44: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 23:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:31 INFO - 'HOME': '/Users/cltbld', 23:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:31 INFO - 'LOGNAME': 'cltbld', 23:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:31 INFO - 'MOZ_NO_REMOTE': '1', 23:44:31 INFO - 'NO_EM_RESTART': '1', 23:44:31 INFO - 'PAGER': '/bin/cat', 23:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:31 INFO - 'PWD': '/builds/slave/test', 23:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:31 INFO - 'SHELL': '/bin/bash', 23:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:31 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:31 INFO - 'USER': 'cltbld', 23:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:31 INFO - 'XPC_FLAGS': '0x0', 23:44:31 INFO - 'XPC_SERVICE_NAME': '0', 23:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:32 INFO - Downloading/unpacking blobuploader==1.2.4 23:44:32 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 23:44:32 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 23:44:32 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 23:44:32 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 23:44:32 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 23:44:32 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 23:44:34 INFO - Downloading blobuploader-1.2.4.tar.gz 23:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:44:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:44:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44: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 23:44:36 INFO - Downloading docopt-0.6.1.tar.gz 23:44:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:44:36 INFO - Installing collected packages: blobuploader, requests, docopt 23:44:36 INFO - Running setup.py install for blobuploader 23:44:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:44:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:44:36 INFO - Running setup.py install for requests 23:44:36 INFO - Running setup.py install for docopt 23:44:36 INFO - Successfully installed blobuploader requests docopt 23:44:36 INFO - Cleaning up... 23:44:36 INFO - Return code: 0 23:44:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:44: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')]} 23:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44: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')]} 23:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44: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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44: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 23:44: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 23:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:36 INFO - 'HOME': '/Users/cltbld', 23:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:36 INFO - 'LOGNAME': 'cltbld', 23:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:36 INFO - 'MOZ_NO_REMOTE': '1', 23:44:36 INFO - 'NO_EM_RESTART': '1', 23:44:36 INFO - 'PAGER': '/bin/cat', 23:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:36 INFO - 'PWD': '/builds/slave/test', 23:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:36 INFO - 'SHELL': '/bin/bash', 23:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:36 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:36 INFO - 'USER': 'cltbld', 23:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:36 INFO - 'XPC_FLAGS': '0x0', 23:44:36 INFO - 'XPC_SERVICE_NAME': '0', 23:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:44:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-oCFCoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:44:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-llVzBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:44:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-y5hzat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:44:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Tc57YL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:44:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-r3MjL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:44:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-tIu9JR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:44:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fti9Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:44:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ZULnTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-aeOajQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ngb_Of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-6nFABJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-IJdd16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-0rXidF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-yEvTWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-N0KK5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-RPuX0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:44:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-mb8nqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:44:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:44:39 INFO - Running setup.py install for manifestparser 23:44:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:44:39 INFO - Running setup.py install for mozcrash 23:44:39 INFO - Running setup.py install for mozdebug 23:44:39 INFO - Running setup.py install for mozdevice 23:44:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:44:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:44:39 INFO - Running setup.py install for mozfile 23:44:39 INFO - Running setup.py install for mozhttpd 23:44:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:44:39 INFO - Running setup.py install for mozinfo 23:44:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Running setup.py install for mozInstall 23:44:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Running setup.py install for mozleak 23:44:40 INFO - Running setup.py install for mozlog 23:44:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Running setup.py install for moznetwork 23:44:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Running setup.py install for mozprocess 23:44:40 INFO - Running setup.py install for mozprofile 23:44:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:44:40 INFO - Running setup.py install for mozrunner 23:44:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:44:41 INFO - Running setup.py install for mozscreenshot 23:44:41 INFO - Running setup.py install for moztest 23:44:41 INFO - Running setup.py install for mozversion 23:44:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:44:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:44:41 INFO - Cleaning up... 23:44:41 INFO - Return code: 0 23:44:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:44: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')]} 23:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:42 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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44:42 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 23:44:42 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 23:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:42 INFO - 'HOME': '/Users/cltbld', 23:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:42 INFO - 'LOGNAME': 'cltbld', 23:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:42 INFO - 'MOZ_NO_REMOTE': '1', 23:44:42 INFO - 'NO_EM_RESTART': '1', 23:44:42 INFO - 'PAGER': '/bin/cat', 23:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:42 INFO - 'PWD': '/builds/slave/test', 23:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:42 INFO - 'SHELL': '/bin/bash', 23:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:42 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:42 INFO - 'USER': 'cltbld', 23:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:42 INFO - 'XPC_FLAGS': '0x0', 23:44:42 INFO - 'XPC_SERVICE_NAME': '0', 23:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:44:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-7rsJoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:44: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)) 23:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:44:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Fmw6gJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:44:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-a31I4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:44:43 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)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-5d6fST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:44:43 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)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Dzu44S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:44:43 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)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-SoKb3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:44:43 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)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-mMS2Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:44:43 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)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-w317R4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:44:43 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)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-a8TSqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:44: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)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ExRlrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-l4q7AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:44: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)) 23:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:44:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-PG19ZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:44:44 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)) 23:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:44:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-zoewsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:44:44 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)) 23:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:44:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-BCbKVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:44:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-IRpHUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:44:44 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)) 23:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:44:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-AS0zcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 23:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:44:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-IKfYDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:44:44 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)) 23:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:44:44 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)) 23:44:44 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)) 23:44:44 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)) 23:44:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:44:44 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 23:44:44 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 23:44:44 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 23:44:44 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 23:44:44 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 23:44:44 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 23:44:47 INFO - Downloading blessings-1.6.tar.gz 23:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:44:47 INFO - Installing collected packages: blessings 23:44:47 INFO - Running setup.py install for blessings 23:44:47 INFO - Successfully installed blessings 23:44:47 INFO - Cleaning up... 23:44:47 INFO - Return code: 0 23:44:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:44:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:44:48 INFO - Reading from file tmpfile_stdout 23:44:48 INFO - Current package versions: 23:44:48 INFO - blessings == 1.6 23:44:48 INFO - blobuploader == 1.2.4 23:44:48 INFO - docopt == 0.6.1 23:44:48 INFO - functools32 == 3.2.3-2 23:44:48 INFO - jsonschema == 2.5.1 23:44:48 INFO - manifestparser == 1.1 23:44:48 INFO - mozInstall == 1.12 23:44:48 INFO - mozcrash == 1.0 23:44:48 INFO - mozdebug == 0.1 23:44:48 INFO - mozdevice == 0.48 23:44:48 INFO - mozfile == 1.2 23:44:48 INFO - mozhttpd == 0.7 23:44:48 INFO - mozinfo == 0.9 23:44:48 INFO - mozleak == 0.1 23:44:48 INFO - mozlog == 3.4 23:44:48 INFO - moznetwork == 0.27 23:44:48 INFO - mozprocess == 0.23 23:44:48 INFO - mozprofile == 0.28 23:44:48 INFO - mozrunner == 6.13 23:44:48 INFO - mozscreenshot == 0.1 23:44:48 INFO - mozsystemmonitor == 0.3 23:44:48 INFO - moztest == 0.8 23:44:48 INFO - mozversion == 1.4 23:44:48 INFO - psutil == 3.1.1 23:44:48 INFO - requests == 1.2.3 23:44:48 INFO - wsgiref == 0.1.2 23:44:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:44:48 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')]} 23:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:48 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')]} 23:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:48 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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44:48 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 23:44:48 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 23:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:48 INFO - 'HOME': '/Users/cltbld', 23:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:48 INFO - 'LOGNAME': 'cltbld', 23:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:48 INFO - 'MOZ_NO_REMOTE': '1', 23:44:48 INFO - 'NO_EM_RESTART': '1', 23:44:48 INFO - 'PAGER': '/bin/cat', 23:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:48 INFO - 'PWD': '/builds/slave/test', 23:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:48 INFO - 'SHELL': '/bin/bash', 23:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:48 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:48 INFO - 'USER': 'cltbld', 23:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:48 INFO - 'XPC_FLAGS': '0x0', 23:44:48 INFO - 'XPC_SERVICE_NAME': '0', 23:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 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)) 23:44:48 INFO - Cleaning up... 23:44:48 INFO - Return code: 0 23:44:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:44:48 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')]} 23:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:48 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')]} 23:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:48 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 0x10d254490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ba588f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, '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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:44:48 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 23:44:48 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 23:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:48 INFO - 'HOME': '/Users/cltbld', 23:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:48 INFO - 'LOGNAME': 'cltbld', 23:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:48 INFO - 'MOZ_NO_REMOTE': '1', 23:44:48 INFO - 'NO_EM_RESTART': '1', 23:44:48 INFO - 'PAGER': '/bin/cat', 23:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:48 INFO - 'PWD': '/builds/slave/test', 23:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:48 INFO - 'SHELL': '/bin/bash', 23:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:44:48 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:48 INFO - 'USER': 'cltbld', 23:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:48 INFO - 'XPC_FLAGS': '0x0', 23:44:48 INFO - 'XPC_SERVICE_NAME': '0', 23:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:44:49 INFO - Cleaning up... 23:44:49 INFO - Return code: 0 23:44:49 INFO - Running post-action listener: _resource_record_post_action 23:44:49 INFO - Running post-action listener: _start_resource_monitoring 23:44:49 INFO - Starting resource monitoring. 23:44:49 INFO - [mozharness: 2017-01-20 07:44:49.172504Z] Finished create-virtualenv step (success) 23:44:49 INFO - [mozharness: 2017-01-20 07:44:49.173558Z] Running install step. 23:44:49 INFO - Running pre-action listener: _resource_record_pre_action 23:44:49 INFO - Running main action method: install 23:44:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:44:49 INFO - Reading from file tmpfile_stdout 23:44:49 INFO - Detecting whether we're running mozinstall >=1.0... 23:44:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:44:49 INFO - Reading from file tmpfile_stdout 23:44:49 INFO - Output received: 23:44:49 INFO - Usage: mozinstall [options] installer 23:44:49 INFO - Options: 23:44:49 INFO - -h, --help show this help message and exit 23:44:49 INFO - -d DEST, --destination=DEST 23:44:49 INFO - Directory to install application into. [default: 23:44:49 INFO - "/builds/slave/test"] 23:44:49 INFO - --app=APP Application being installed. [default: firefox] 23:44:49 INFO - mkdir: /builds/slave/test/build/application 23:44:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 23:45:21 INFO - Reading from file tmpfile_stdout 23:45:21 INFO - Output received: 23:45:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:45:21 INFO - Running post-action listener: _resource_record_post_action 23:45:21 INFO - [mozharness: 2017-01-20 07:45:21.422237Z] Finished install step (success) 23:45:21 INFO - [mozharness: 2017-01-20 07:45:21.422369Z] Running run-tests step. 23:45:21 INFO - Running pre-action listener: _resource_record_pre_action 23:45:21 INFO - Running main action method: run_tests 23:45:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:45:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:45:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:45:21 INFO - 2017-01-19 23:45:21.435 screenresolution[1624:7307] starting screenresolution argv=screenresolution get 23:45:21 INFO - 2017-01-19 23:45:21.437 screenresolution[1624:7307] Display 0: 1600x1200x32@60 23:45:21 INFO - 2017-01-19 23:45:21.442 screenresolution[1625:7309] starting screenresolution argv=screenresolution list 23:45:21 INFO - Available Modes on Display 0 23:45:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:45:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:45:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:45:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:45:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:45:21 INFO - Intel Iris: 23:45:21 INFO - Chipset Model: Intel Iris 23:45:21 INFO - Type: GPU 23:45:21 INFO - Bus: Built-In 23:45:21 INFO - VRAM (Dynamic, Max): 1536 MB 23:45:21 INFO - Vendor: Intel (0x8086) 23:45:21 INFO - Device ID: 0x0a2e 23:45:21 INFO - Revision ID: 0x0009 23:45:21 INFO - Displays: 23:45:21 INFO - Display: 23:45:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:45:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:45:21 INFO - Display Serial Number: 1600x1200 60 23:45:21 INFO - Main Display: Yes 23:45:21 INFO - Mirror: Off 23:45:21 INFO - Online: Yes 23:45:21 INFO - Rotation: Supported 23:45:21 INFO - Return code: 0 23:45:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:45:21 INFO - Python 2.7.3 23:45:21 INFO - Return code: 0 23:45:21 INFO - grabbing minidump binary from tooltool 23:45:21 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')]} 23:45:21 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 0x10d068d30>, '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 0x10cecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d060b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:45:21 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 23:45:21 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 23:45:21 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 23:45:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:45:21 INFO - Return code: 0 23:45:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:45:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:45:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:45:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:45:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:45:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 23:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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 23:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvaTzk1FX7/Render', 23:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:45:21 INFO - 'HOME': '/Users/cltbld', 23:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:45:21 INFO - 'LOGNAME': 'cltbld', 23:45:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:45:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:21 INFO - 'MOZ_NO_REMOTE': '1', 23:45:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:45:21 INFO - 'NO_EM_RESTART': '1', 23:45:21 INFO - 'PAGER': '/bin/cat', 23:45:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:21 INFO - 'PWD': '/builds/slave/test', 23:45:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:45:21 INFO - 'SHELL': '/bin/bash', 23:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YD0el8PdnJ/Listeners', 23:45:21 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:45:21 INFO - 'USER': 'cltbld', 23:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:21 INFO - 'XPC_FLAGS': '0x0', 23:45:21 INFO - 'XPC_SERVICE_NAME': '0', 23:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:45:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 23:45:22 INFO - mozversion application_buildid: 20170119223124 23:45:22 INFO - mozversion application_changeset: 4a274293090db1dce0d2bbce05ebb6f4cf7f45ec 23:45:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:45:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:45:22 INFO - mozversion application_name: Firefox 23:45:22 INFO - mozversion application_remotingname: firefox-dev 23:45:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:45:22 INFO - mozversion application_vendor: Mozilla 23:45:22 INFO - mozversion application_version: 52.0a2 23:45:22 INFO - mozversion platform_buildid: 20170119223124 23:45:22 INFO - mozversion platform_changeset: 4a274293090db1dce0d2bbce05ebb6f4cf7f45ec 23:45:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:45:22 INFO - mozversion platform_version: 52.0a2 23:45:22 INFO - using testdate: 1484898322 23:45:22 INFO - actual date: 1484898322 23:45:22 INFO - starting webserver on 'localhost:49241' 23:45:22 INFO - SUITE-START | Running 2 tests 23:45:22 INFO - TEST-START | dromaeo_css 23:45:22 INFO - operating with platform_type : mac_ 23:45:22 INFO - Initialising browser for dromaeo_css test... 23:45:22 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmphiLmNj/profile http://localhost:49241/getInfo.html) 23:45:24 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 23:45:24 INFO - PROCESS | 1635 | colorDepth:24 23:45:24 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 23:45:24 INFO - PROCESS | 1635 | __metrics 23:45:24 INFO - PROCESS | 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:45:24 INFO - PROCESS | 1635 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:45:25 INFO - TEST-INFO | 1635: exit 0 23:45:25 INFO - Browser initialized. 23:45:25 INFO - Running cycle 1/1 for dromaeo_css test... 23:45:25 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmphiLmNj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:45:37 INFO - PROCESS | 1637 | RSS: Main: 222294016 23:45:37 INFO - PROCESS | 1637 | 23:48:42 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 23:48:43 INFO - PROCESS | 1637 | RSS: Main: 326680576 23:48:43 INFO - PROCESS | 1637 | 23:51:46 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 23:51:47 INFO - PROCESS | 1637 | RSS: Main: 349233152 23:51:47 INFO - PROCESS | 1637 | 23:54:49 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 23:54:50 INFO - PROCESS | 1637 | RSS: Main: 314929152 23:54:50 INFO - PROCESS | 1637 | 23:57:52 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 23:57:53 INFO - PROCESS | 1637 | RSS: Main: 307793920 23:57:53 INFO - PROCESS | 1637 | 00:00:56 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 00:00:57 INFO - PROCESS | 1637 | RSS: Main: 308281344 00:00:57 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 00:04:00 INFO - PROCESS | 1637 | RSS: Main: 403492864 00:04:00 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | __start_tp_report 00:04:00 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 00:04:00 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 00:04:00 INFO - PROCESS | 1637 | |i|pagename|runs| 00:04:00 INFO - PROCESS | 1637 | |0;dojo.html;3361.075704181348;3371.1403592084016;3374.3588718143556;3387.898363049108;3421.5551978242825;5748.827535173947;5818.301803783541;5819.82540523785;5844.941550584493;5845.912311315323;5703.201551782743;5725.770969161239;5825.417458254173;5835.35811060784;5839.153322768196;5236.73816309184;5286.603504737141;5296.417394086648;5298.761555730001;5336.786528538853;5152.793888244466;5154.561862241714;5159.484051594829;5237.423883372826;5352.946470535284;5290;5356.3572371315495;5358.678479291236;5362.034833729927;5406.729663516828;4726.291056341576;5340.492653197959;5351.7056561889085;5358.223057656657;5372.355317361941;5093.286939828427;5191.454897235812;5279.208118782175;5294.100002999499;5305.31030965972;5666.801661941853;5706.80026199081;5744.482996530291;5794.565407594448;5796.478316951452;5451.155070964007;6028.884656338591;6240.500759939239;6333.524985626051;6333.904991425133;6319.5576309658345;6322.272938612073;6351.142595749561;6353.269374022;6354.173957385557;5712.400197979152;5734.827955161393;5848.152017957373;5873.177755114288;5887.264091988502;6331.145295385064;6346.619202847844;6349.619022858551;6355.8093257201435;6358.682065896686;5481.890362192814;5985.820425387245;6294.65379404128;6294.905576416358;6306.306306306303;6146.108814221912;6232.158658581124;6270.592411493239;6322.304546499881;6392.296847346788;6324.525660575475;6337.144485494491;6342.460890824239;6345.777897773556;6356.904646430307;6243.344448832898;6274.874502510015;6326.114343991845;6333.429991300805;6339.778107766206;4869.9269510957365;4919.3604831371695;4926.679765815211;4936.827211047596;5010.073136469765;4437.889052773642;4475.843345482893;4478.619317357996;4490.393796837385;4537.4328208973875;5942.37605051472;5945.048792193228;5951.791687291011;5952.226210592596;5954.404559544011;6185.165002724574;6210.125463599654;6301.275353334334;6318.368163183645;6328.367163283635;5843.795467158629;5884.08796636522;5912.615679980788;5963.612365196248;6026.969865150646;5425.810096646677;5486.067368547357;5487.917681234785;5497.092979658311;5500.037493438632;6218.9378106218355;6416.550841441054;6428.935710643021;6432.517561182836;6437.871242575216;6217.191765070512;6267.310595834363;6338.841528961812;6339.651319177489;6343.0485427186295;4811.591014763644;6269.216347956505;6301.275353334242;6323.905141422791;6340.302566717565;6167.84580385494;6183.783567575113;6186.876262474815;6188.288346840171;6198.2871969722255;5823.679697616672;6224.346443623354;6235.563510554218;6238.407351301619;6256.937430625636;6137.7851775187655;6199.070139479114;6201.906971395523;6207.875842483216;6238.344973777688;6193.473554747717;6203.720832562455;6204.317525072329;6210.782622608187;6216.595921265103;4946.826861059845;5512.6692398456225;5960.254968128984;5973.014452615444;5978.192943952511;6229.937700622936;6241.594296370721;6258.155149054819;6259.6870156492905;6260.68696565179;5852.18069470266;6107.755689772359;6112.724927378367;6130.877382452415;6133.417325354084 00:04:00 INFO - PROCESS | 1637 | |1;ext.html;453.9319102134679;467.69833457419963;509.46506168523047;519.6440438299765;548.7640314664693;30344.393112137743;31905.840470797644;39451.80274098633;40166.397504037464;40993.180136397255;40836.38745418821;41082.79458602703;41195.58804411955;41326.38010429838;41384;9222.723318300461;9412.482313472756;9418.1994530465;9419.434833909952;9423.010583888661;8426.705065322714;8632.827343453127;11164.832527512093;11428.599999000036;11438.71403214922;11357.204995650267;11434.71413214672;11436.656900292963;11459.427028648575;11530.538778448852;8440.324774018063;11326.886731132707;11382.487788049475;11382.829257561143;11407.030402415805;7928.524288542706;8451.83096338076;11282.94358528202;11329.773404531863;11457.197996140274;31649.8417507911;39856.40215396771;39922.800385998176;39967;40054.39918401226;3273.885414010522;3532.5584301962253;3650.7809531428197;3794.2793789379316;3861.2084522672785;28460.430791384057;28461.146165615064;29824.552631710543;30027.69972300271;31016.53475197874;2670.5994100885023;3150.2596889730894;3172.7779055466126;3212.3735871505064;3241.6272128705264;494977;498598.0140198588;500180.4990975022;501072;508149;59633.403665963655;59914;59926.70036649876;60036;60182.69908650516;34689.65310346864;35522.28955420929;35582.93251202468;36334.454983175274;36398.454023189144;15736;15880.285387157606;15897.682046359008;15907.522774316789;15914.92042539803;11260.605878794202;11337.546498140147;12096.939515302543;12146.271223726604;12147.574834880737;18895.716564251547;19091.427257182306;19377.709334359715;19381.806181938282;19651.901740491205;5461.672299662033;7232.566046037255;7241.963790181016;7307.744228951961;7320.597367144858;805.7341077444429;911.4941207629696;929.9953500232456;953.7329547726648;966.6133546581283;29123.56314655282;29508.704912951023;29633.555496667566;30045.849770751007;30297.697023029486;1020.5764607687875;1049.2445439283704;1109.3177695717238;1133.8015847226702;1173.3370645585217;15261.389544418322;15293;15460.5361839145;15486.457973970857;15995.680086398206;668380;672349;673425;674963.3762818278;675749;57376.13935790966;57890.4210957885;57938;58085.419145808846;58092;1225.8345123408403;1266.2592383455597;1348.82465279515;1365.3651052260745;1387.1676993803637;29639.25901852471;30099.849500752356;30291.69708302977;30352.696473034986;30852.22869428282;49087.75456122697;49643.75178124086;49671.25493117534;49691.75154124206;49703.75148124236;545230;548270;548299;549177;549263;22190.66713999259;26219.73780262173;27176.048838290564;27766;27776.44447111006;43727.34408983932;45120.871978200805;46129.077418452114;46648.53351466442;46829;37007.259854802214;37162.44256336103;37408.438873417464;38077.809610951765;39347;36752.448713269856;37207.627923720414;37465;38413.039674008374;38561.42157867578;37024.07439814026;37650.81174594219;37741.24517509579;37813.24373512459;37863.43204851874;29518.505928695653;31532.684673152973;33992.490112647836;34320.141996450286;34458.82770586131;24832.50334993254;24957.87521062383;25120;25602.84787184544;25816.483670326863 00:04:00 INFO - PROCESS | 1637 | |2;jquery.html;297.5610973813624;313.346672188487;376.26064782701985;377.7072768604331;404.7692815095395;14857.925710371446;14869.776953345696;19819.504512387197;20174.991250437455;21036.789632103675;19716;20104.396868093943;20117.295894643692;20385.980700964967;20577.897110514463;5637.577181711367;5881.382454842244;5932.021216499273;5935.228420305354;5935.673537955411;5263.973680131594;5575.163725441175;5869.735861886225;5901.911471327933;5944.821655350324;4653.557911998371;5182.948170518284;5947.137665038566;5948.1375200595885;5959.731812068446;5674.801381951612;5852.4440178183;5911.556633252501;5932.940670593303;5939.881202375971;5716.771329146829;5747.59766816323;5760.53915686744;5765.308163020422;5766.0486019806685;12649.367531623382;13380.531681391201;13515.053946223768;13519.986001049961;13541.661458463519;2182.181681869299;2185.7704940981125;2408.3497455687043;2530.683664541932;2621.0040184729874;9812.264080193943;10015.298929074968;10041.096301332842;10373.636922707668;10380.480975951248;1828.5794267318458;1915.9521011974673;1948.9317873874347;1952.5021119614548;1966.6361723081275;110579.44710276397;114634.85365146326;118554.40722796417;122100.38949805194;124548.37725811313;10140.594376224868;11107.944460277648;11338.716532086599;11798.410079496163;11914.880851191378;13571.457141714418;14485.130892146504;14798.630034249014;14801;14804.963652544435;8528.360372972053;8602;8655.6970506032;8682.348823838238;8805.69180078694;8191;8323;8400.831983360298;8401.991760988598;8543.700970466003;4571.1543364477675;4572.771361431982;4576.656750743641;4583.129205450954;4656.837010704609;1867.9252829886652;1915.1669023974337;1916.549610841479;1917.2618541861484;1919.29945569866;825.7646570727314;828.1676914700807;876.2770714160843;915.8900931888217;931.9347645664874;20257.696134557995;20856.957152142637;21015.003574660383;21202;21309.573808523743;902.8871391076116;908.7500937242311;923.1230331185267;925.5788616179622;943.235978857128;10244.897551024544;11066.889331106748;11093.445327733582;11097.83353249702;11168.385738784282;611248.8875111281;612529;625312;626327;637318;111554;115935.42032289953;119598.80401195877;122361.16458253133;130850;932.398602901125;950.8288508068475;963.4700914496999;965.2229954886384;969.0454901921602;17380.304787808345;18249.452516424528;18626.81373186251;18650.160742766337;18653.720194196827;47427.288590670476;51119;52166.47833521616;52527.737361312946;53057;56850;68158.65920670563;68827.96758048734;70930.64534677293;73840.89238661317;7091.326323999212;7401.5189012714;7920.920790792249;8037.8392432152195;8153.429259951984;11468.713282168013;12267.754644906874;12316.81524777147;12619.684507887376;12727.363631818558;10502;10555.894441055798;10565.735856603338;10640.095591874777;10922.672319830417;10503.317284376491;10506.264561480622;10573.2598718089;10746.140308775122;10853;10774.838377424188;10858.077063449695;10872.565097396016;11144;11206.495707693337;7129.7861064168155;7148.714051437884;7209.567425954459;7419.666115024729;7445.994790703186;5270.156774915995;8198.38512111606;9036.502992335485;10575.153987680813;10802.405867677353 00:04:00 INFO - PROCESS | 1637 | |3;mootools.html;1305;1454.2946670864628;1486.4945918387746;1645.2020769926582;1830.670479313723;7483.588402637853;7868.921310786897;8160.755177344674;8434.873476897852;8444.324454043677;8218.096009438957;8266.62800173992;8378.162183781618;8379.162083791618;8386.62260198291;2321.8606883586995;2355.9646605300936;2356.434455730621;2356.9292921212304;2359.764023597635;2108.641530939736;2276.4195130241765;2335.065973610552;2374.560706269337;2381.0356805493716;2306.8039216666602;2319.524497478013;2338.6141286687675;2340.20433052762;2343.496148328109;2280.703508543879;2304.135949019118;2325.476767727256;2325.7790509901533;2334.7198336199594;2326.220716060122;2349.6475528670667;2349.835511514195;2354.093673935547;2358.2217868103658;8307.875381869277;8391.412601117925;8401.15988401155;8405.327573794142;8414.495130292202;5025.673331233458;5056;5231.9476805232225;5252.501012403815;5271;3294.2917272786226;3474.6525347465304;3570.8750193743354;3572.6605972432576;3576.2489877125836;1040.6825918094978;1067.0449947297204;1075.833245846895;1081.0756802933458;1096.506572042576;10524.263301568893;10844.295120817122;10896.18278629106;10915.617953371593;11061.834072489;4264.701470897007;4265.317549192115;4311.805968731414;4401.625861801719;4497.955020449754;8543.231109200202;8628.827423451496;8647.048824629286;8654.307655387554;8684.261837743863;5121.743912804345;5121.769520371592;5125.820596279113;5131.358580177478;5132.8964272680805;4064.4716186895516;4319.762413067248;4450.532694067141;4472.642188624902;4825.227963525889;3032.9696703032687;3625.2386998730035;3717.498137751369;3720.4605332227216;3731.9067023324633;1002.6691191906654;1113.287496002559;1132.767782604564;1135.7671677306132;1147.2428197389684;799.9800004999805;885.5793498088357;905.2260317428504;911.8541033434751;914.593006112287;5705.657660540381;5765.769369225184;5872.383399743051;5926.23600293019;6166.383361663798;874.8031692869054;892.638481415028;908.3777612335572;909.4406939732017;988.5172241637543;3169.3819705157734;3730.813459327077;3793.8482460701816;3810.7329362579967;3830.8276127574327;9950.701478955498;10188.032136946978;10321.535530901128;10333.48332583383;10348.016938390841;10280.537375818107;10285.331453455501;10298.279120461495;10303.227257955683;10320.845187322197;7844;8019.839603207904;8026.5986700664735;8052.235037671412;8065.435419520694;5135.614828887774;5459.235707000944;5561.416051314553;5705.885882282414;5728.0262355398845;9449.385789923634;9502.714918552454;9681.66114186;9684.128428441474;9707;7165.745994451055;8546.829063418822;8897.510636915033;9621.615135394506;9644.083812037845;6776.728930842711;7045.647717614202;7289.890651640124;7310.8903366448485;7367.300106489874;7584;7938.64276107565;8036.557989310644;8097.2712455879255;8099.757007289791;7091;7222.313880181375;7234.927650723636;7283.271672832674;7370.078740157481;7057.576545407292;7085.681144348414;7094.396976257076;7108.3602475777425;7127.928720712934;6707.765228216989;6723.697433615401;6764.120664313805;6801.591904485746;6811.080504132077;4791.448983366958;4817.9036419272115;4852.369192005017;4870.09903167915;4895.33912921751;4927.088488629615;4932.45742968266;4948.307236986752;5000.549950504472;5025.597952163745 00:04:00 INFO - PROCESS | 1637 | |4;prototype.html;246.6817805031509;303.41895270556887;303.6523180957803;308.4725120044723;312.56865525574705;5307.205348176053;5322.33470816148;5332.973335133329;5354.41101478837;5366.3828659704095;5268.314139475369;5277.445868183843;5292.470752924705;5294.761735721892;5351.625386222966;3136.40408322418;3530.3645343838098;3597.9640203598024;3807.200487897538;3926.391409331544;3495.0563347896054;4014.1770936958;4027.758334499925;4078.714489985702;4107.404426358176;3654.7441679082476;3870.7290489665734;3983.470496234883;3985.5688030301494;4014.0767623446623;3918.7648741075627;4025.1748391579804;4041.838326466938;4047.377597050623;4048.878533643966;3486.163320802989;3529.5940966788635;3538.841860813534;3551.164742078549;3559.60844307126;4203.600657937522;5112.182050871843;5115.923261151086;5126.359205099363;5130.280951095888;4440.911181776379;4597.218472859622;4604.286335617984;4631.768411579407;4636.843867601581;4895.922896962663;4969.006198760262;5479.972600137014;5481.616286859921;5556.527695145917;3873.992761881903;3897.7466464679705;3900.4344370066465;3902.804859757029;3913.158670885773;4522.648222924061;5306.787728490855;5564.693941833198;5784.623999440023;5826.388229235954;3924.9982508920084;3957.4261732049267;3959.8416063357145;3974.900627484336;4003.619656132663;3596.3526565218513;3830.4637350770913;3994.0813612868624;4013.8595149170224;4014.3541148129125;4059.837606495707;4609.459682213018;4640.23436133041;4649.5815376616265;4651.465081515601;4646.372739680168;5374.543163831118;5495.945040549624;5533.063427800238;5587.245721827582;1101.2786624760633;1124.80315944711;1302.4074046309097;1310.702404619439;1312.1864444044754;849.902261239953;880.8810810540623;893.629143905285;894.789724414762;900.3427497926419;4409.735415875058;4505.617022553054;4681.625469962395;5098.770555324945;5130.666506677054;5596.972015139898;5768.480836724715;5780.95333266715;5835.620684655484;5851.941480585225;4539.228331183706;5026.698398096126;5245.921311180336;5261.342332208474;5261.631685782035;2637.4988752137033;2642.141304076183;2833.6115303501138;2890.248535380816;2891.9710802892123;4858.5384388483035;5290.867728306823;5358.5981051421295;5455.826997195622;5643.5767317451355;5124.410692770304;5348.251244825728;5567.359753628384;5595.524380427628;5602.38373778884;5172.611939179843;5233.450487698889;5499.120140777456;5657.547396208376;5667.574931880125;4952.529509696573;5288.762005709676;5465.3109151321905;5482.725863706719;5510.531604813636;5101.923471147861;5317.574594032545;5704.486596206361;5777.884442311046;5787.594868359174;5984.431479009487;5985.371535988658;5987.970060149672;5998.93036902268;6001.429864163072;3842.308384490707;3843.442700808311;3869.9419508707956;3880.340342141786;3893.7469064510715;5042.747862606929;5177.058603023866;5244.318238628954;5480.478979669397;5784.82645520635;4687.9296810547185;4801.535531662877;5556.777728890961;5648.604597678123;5664.971675141598;5090.414602320632;5173.508516690908;5312.619204494159;5655.915161272502;5671.517920976763;4718.598919091778;4928.732782919754;5494.587905906993;5539.307586551681;5554.805581804779;4524.529527903379;4537.591616754508;5402.135658294654;5522.641028333145;5669.902136209629;4957.355543779286;5259.684418934876;5411.511834245614;5531.1426728857095;5531.529819965348 00:04:00 INFO - PROCESS | 1637 | |5;yui.html;600.4505877122433;605.6517502436101;687.824604725795;726.2156870579774;772.7179579453501;14010.579682609512;14375.64060898478;14420.711585768291;14446.855531444682;14532.709345813077;10672.252942294042;12687.682807929783;12828.615141545744;12855.935720321386;13155.539556115538;640.634838142258;659.858130501942;799.5722288575613;825.0717942314895;833.9374546908975;832.662771577509;835.1606635331483;835.8787975743513;837.798928257217;838.287455662688;885.8715486254522;890.1098901098901;890.2521881619439;890.4835195586577;890.6259371064168;686.0806519264185;710.5701050864204;843.1357858195338;858.7638399440142;876.4784952952983;647.9125318082046;648.0506058624092;648.1962366665348;649.6069877723957;650.7364517370472;13473;13798.65503362414;13836.515721949683;13840.93079534606;13846.861531384659;12537.122401431903;12570.182938108994;12582.182158159781;12583.307918064504;12650;11289.0968722502;11419.99171958321;11572.016378607828;12015.819762703566;12102.455389507493;4072.36220863773;4217.8312867485265;4280.400743895857;4292.034292284242;4319.114581510814;78587.21412785913;80896.19103808946;80955.59522202352;81013;83262.75105873417;9669.70990870275;10037.397756134655;10088.899111008795;10169.237307201842;10297.897021029694;18442.723359149622;19013.334533291545;19078.236870525023;19112.904435477732;19122;7406.703731850774;7447.515911465737;7453.291937265951;7561.659725312375;7780.533168009937;3862.420636904487;4001.679865610744;4004.01901534126;4014.6788256939376;4073.450084238649;3344.7658663893294;3447.1382154461385;3459.4983727359895;3477.2697733475748;3485.9477107843404;643.7425029988042;648.3678413546754;648.9123968264224;661.0909998751716;666.6466772610524;577.6476349426846;635.2662674610832;648.1962366665396;656.5535435903536;671.4494114825796;8167.877481837659;8830.86753698695;8932.69259588576;8988.910110898807;9018.458892466473;518.9610779191576;547.6686604604157;560.1429812387055;566.3515275010091;615.5691016288616;4087.713860029829;4180.163967206571;4232.809523571446;4287.444127188179;4359.258925982591;88163.55918220368;111437;111839.3224101639;113018;113899;98442;100814.48778268333;101478;102075.48962255141;105747.4712626432;69333.3066669347;79399;80244.59877700574;80920.59539702382;88528;7411.295926886937;7539.359154471931;9194.12655797725;9446.10262025134;9627;47204.05591888211;50862;51275;52801.47198528119;53009;50120.498795013045;51245.231321530955;55533.72233138808;67138.31446742076;69397.3060269411;11496;12017.63947081589;12059.51761929513;12231.388430578614;12372.319522426353;8765.649374024968;11677.240979336317;12431;13589.932050340081;14036.649083772987;11254.83117753218;11707.414629268673;11708.70728231801;12169.02647788157;12276.693082673004;12681.746365072831;12973.675658108623;13232.603021909357;13232.801507977581;13347.532836350681;12453;12503.812442813547;12581.685457863627;12651.240925544496;12921.612351629467;8416;10107.848382274125;10634.361938283728;10717.249792514449;11128.18326906515;10723.624673136403;10918.836217456586;11059.170562207706;11183.944080279547;11243 00:04:00 INFO - PROCESS | 1637 | __end_tp_report 00:04:00 INFO - PROCESS | 1637 | __start_cc_report 00:04:00 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1316 00:04:00 INFO - PROCESS | 1637 | __end_cc_report 00:04:00 INFO - PROCESS | 1637 | __startTimestamp1484899440475__endTimestamp 00:04:00 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | ------- Summary: start ------- 00:04:00 INFO - PROCESS | 1637 | Number of tests: 6 00:04:00 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5790.49 Median:5973.01 stddev:655.07 (11.0%) stddev-sans-first:628.74 00:04:00 INFO - PROCESS | 1637 | Values: 3361.1 3371.1 3374.4 3387.9 3421.6 5748.8 5818.3 5819.8 5844.9 5845.9 5703.2 5725.8 5825.4 5835.4 5839.2 5236.7 5286.6 5296.4 5298.8 5336.8 5152.8 5154.6 5159.5 5237.4 5352.9 5290.0 5356.4 5358.7 5362.0 5406.7 4726.3 5340.5 5351.7 5358.2 5372.4 5093.3 5191.5 5279.2 5294.1 5305.3 5666.8 5706.8 5744.5 5794.6 5796.5 5451.2 6028.9 6240.5 6333.5 6333.9 6319.6 6322.3 6351.1 6353.3 6354.2 5712.4 5734.8 5848.2 5873.2 5887.3 6331.1 6346.6 6349.6 6355.8 6358.7 5481.9 5985.8 6294.7 6294.9 6306.3 6146.1 6232.2 6270.6 6322.3 6392.3 6324.5 6337.1 6342.5 6345.8 6356.9 6243.3 6274.9 6326.1 6333.4 6339.8 4869.9 4919.4 4926.7 4936.8 5010.1 4437.9 4475.8 4478.6 4490.4 4537.4 5942.4 5945.0 5951.8 5952.2 5954.4 6185.2 6210.1 6301.3 6318.4 6328.4 5843.8 5884.1 5912.6 5963.6 6027.0 5425.8 5486.1 5487.9 5497.1 5500.0 6218.9 6416.6 6428.9 6432.5 6437.9 6217.2 6267.3 6338.8 6339.7 6343.0 4811.6 6269.2 6301.3 6323.9 6340.3 6167.8 6183.8 6186.9 6188.3 6198.3 5823.7 6224.3 6235.6 6238.4 6256.9 6137.8 6199.1 6201.9 6207.9 6238.3 6193.5 6203.7 6204.3 6210.8 6216.6 4946.8 5512.7 5960.3 5973.0 5978.2 6229.9 6241.6 6258.2 6259.7 6260.7 5852.2 6107.8 6112.7 6130.9 6133.4 00:04:00 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:69586.53 Median:20201.61 stddev:154794.39 (766.2%) stddev-sans-first:155141.60 00:04:00 INFO - PROCESS | 1637 | Values: 453.9 467.7 509.5 519.6 548.8 30344.4 31905.8 39451.8 40166.4 40993.2 40836.4 41082.8 41195.6 41326.4 41384.0 9222.7 9412.5 9418.2 9419.4 9423.0 8426.7 8632.8 11164.8 11428.6 11438.7 11357.2 11434.7 11436.7 11459.4 11530.5 8440.3 11326.9 11382.5 11382.8 11407.0 7928.5 8451.8 11282.9 11329.8 11457.2 31649.8 39856.4 39922.8 39967.0 40054.4 3273.9 3532.6 3650.8 3794.3 3861.2 28460.4 28461.1 29824.6 30027.7 31016.5 2670.6 3150.3 3172.8 3212.4 3241.6 494977.0 498598.0 500180.5 501072.0 508149.0 59633.4 59914.0 59926.7 60036.0 60182.7 34689.7 35522.3 35582.9 36334.5 36398.5 15736.0 15880.3 15897.7 15907.5 15914.9 11260.6 11337.5 12096.9 12146.3 12147.6 18895.7 19091.4 19377.7 19381.8 19651.9 5461.7 7232.6 7242.0 7307.7 7320.6 805.7 911.5 930.0 953.7 966.6 29123.6 29508.7 29633.6 30045.8 30297.7 1020.6 1049.2 1109.3 1133.8 1173.3 15261.4 15293.0 15460.5 15486.5 15995.7 668380.0 672349.0 673425.0 674963.4 675749.0 57376.1 57890.4 57938.0 58085.4 58092.0 1225.8 1266.3 1348.8 1365.4 1387.2 29639.3 30099.8 30291.7 30352.7 30852.2 49087.8 49643.8 49671.3 49691.8 49703.8 545230.0 548270.0 548299.0 549177.0 549263.0 22190.7 26219.7 27176.0 27766.0 27776.4 43727.3 45120.9 46129.1 46648.5 46829.0 37007.3 37162.4 37408.4 38077.8 39347.0 36752.4 37207.6 37465.0 38413.0 38561.4 37024.1 37650.8 37741.2 37813.2 37863.4 29518.5 31532.7 33992.5 34320.1 34458.8 24832.5 24957.9 25120.0 25602.8 25816.5 00:04:00 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:34592.72 Median:1459.28 stddev:103506.88 (7093.0%) stddev-sans-first:103765.21 00:04:00 INFO - PROCESS | 1637 | Values: 297.6 313.3 376.3 377.7 404.8 14857.9 14869.8 19819.5 20175.0 21036.8 19716.0 20104.4 20117.3 20386.0 20577.9 5637.6 5881.4 5932.0 5935.2 5935.7 5264.0 5575.2 5869.7 5901.9 5944.8 4653.6 5182.9 5947.1 5948.1 5959.7 5674.8 5852.4 5911.6 5932.9 5939.9 5716.8 5747.6 5760.5 5765.3 5766.0 12649.4 13380.5 13515.1 13520.0 13541.7 2182.2 2185.8 2408.3 2530.7 2621.0 9812.3 10015.3 10041.1 10373.6 10380.5 1828.6 1916.0 1948.9 1952.5 1966.6 110579.4 114634.9 118554.4 122100.4 124548.4 10140.6 11107.9 11338.7 11798.4 11914.9 13571.5 14485.1 14798.6 14801.0 14805.0 8528.4 8602.0 8655.7 8682.3 8805.7 8191.0 8323.0 8400.8 8402.0 8543.7 4571.2 4572.8 4576.7 4583.1 4656.8 1867.9 1915.2 1916.5 1917.3 1919.3 825.8 828.2 876.3 915.9 931.9 20257.7 20857.0 21015.0 21202.0 21309.6 902.9 908.8 923.1 925.6 943.2 10244.9 11066.9 11093.4 11097.8 11168.4 611248.9 612529.0 625312.0 626327.0 637318.0 111554.0 115935.4 119598.8 122361.2 130850.0 932.4 950.8 963.5 965.2 969.0 17380.3 18249.5 18626.8 18650.2 18653.7 47427.3 51119.0 52166.5 52527.7 53057.0 56850.0 68158.7 68828.0 70930.6 73840.9 7091.3 7401.5 7920.9 8037.8 8153.4 11468.7 12267.8 12316.8 12619.7 12727.4 10502.0 10555.9 10565.7 10640.1 10922.7 10503.3 10506.3 10573.3 10746.1 10853.0 10774.8 10858.1 10872.6 11144.0 11206.5 7129.8 7148.7 7209.6 7419.7 7446.0 5270.2 8198.4 9036.5 10575.2 10802.4 00:04:00 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5432.14 Median:5088.87 stddev:2988.54 (58.7%) stddev-sans-first:2980.82 00:04:00 INFO - PROCESS | 1637 | Values: 1305.0 1454.3 1486.5 1645.2 1830.7 7483.6 7868.9 8160.8 8434.9 8444.3 8218.1 8266.6 8378.2 8379.2 8386.6 2321.9 2356.0 2356.4 2356.9 2359.8 2108.6 2276.4 2335.1 2374.6 2381.0 2306.8 2319.5 2338.6 2340.2 2343.5 2280.7 2304.1 2325.5 2325.8 2334.7 2326.2 2349.6 2349.8 2354.1 2358.2 8307.9 8391.4 8401.2 8405.3 8414.5 5025.7 5056.0 5231.9 5252.5 5271.0 3294.3 3474.7 3570.9 3572.7 3576.2 1040.7 1067.0 1075.8 1081.1 1096.5 10524.3 10844.3 10896.2 10915.6 11061.8 4264.7 4265.3 4311.8 4401.6 4498.0 8543.2 8628.8 8647.0 8654.3 8684.3 5121.7 5121.8 5125.8 5131.4 5132.9 4064.5 4319.8 4450.5 4472.6 4825.2 3033.0 3625.2 3717.5 3720.5 3731.9 1002.7 1113.3 1132.8 1135.8 1147.2 800.0 885.6 905.2 911.9 914.6 5705.7 5765.8 5872.4 5926.2 6166.4 874.8 892.6 908.4 909.4 988.5 3169.4 3730.8 3793.8 3810.7 3830.8 9950.7 10188.0 10321.5 10333.5 10348.0 10280.5 10285.3 10298.3 10303.2 10320.8 7844.0 8019.8 8026.6 8052.2 8065.4 5135.6 5459.2 5561.4 5705.9 5728.0 9449.4 9502.7 9681.7 9684.1 9707.0 7165.7 8546.8 8897.5 9621.6 9644.1 6776.7 7045.6 7289.9 7310.9 7367.3 7584.0 7938.6 8036.6 8097.3 8099.8 7091.0 7222.3 7234.9 7283.3 7370.1 7057.6 7085.7 7094.4 7108.4 7127.9 6707.8 6723.7 6764.1 6801.6 6811.1 4791.4 4817.9 4852.4 4870.1 4895.3 4927.1 4932.5 4948.3 5000.5 5025.6 00:04:00 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4468.92 Median:5094.59 stddev:1360.80 (26.7%) stddev-sans-first:1327.20 00:04:00 INFO - PROCESS | 1637 | Values: 246.7 303.4 303.7 308.5 312.6 5307.2 5322.3 5333.0 5354.4 5366.4 5268.3 5277.4 5292.5 5294.8 5351.6 3136.4 3530.4 3598.0 3807.2 3926.4 3495.1 4014.2 4027.8 4078.7 4107.4 3654.7 3870.7 3983.5 3985.6 4014.1 3918.8 4025.2 4041.8 4047.4 4048.9 3486.2 3529.6 3538.8 3551.2 3559.6 4203.6 5112.2 5115.9 5126.4 5130.3 4440.9 4597.2 4604.3 4631.8 4636.8 4895.9 4969.0 5480.0 5481.6 5556.5 3874.0 3897.7 3900.4 3902.8 3913.2 4522.6 5306.8 5564.7 5784.6 5826.4 3925.0 3957.4 3959.8 3974.9 4003.6 3596.4 3830.5 3994.1 4013.9 4014.4 4059.8 4609.5 4640.2 4649.6 4651.5 4646.4 5374.5 5495.9 5533.1 5587.2 1101.3 1124.8 1302.4 1310.7 1312.2 849.9 880.9 893.6 894.8 900.3 4409.7 4505.6 4681.6 5098.8 5130.7 5597.0 5768.5 5781.0 5835.6 5851.9 4539.2 5026.7 5245.9 5261.3 5261.6 2637.5 2642.1 2833.6 2890.2 2892.0 4858.5 5290.9 5358.6 5455.8 5643.6 5124.4 5348.3 5567.4 5595.5 5602.4 5172.6 5233.5 5499.1 5657.5 5667.6 4952.5 5288.8 5465.3 5482.7 5510.5 5101.9 5317.6 5704.5 5777.9 5787.6 5984.4 5985.4 5988.0 5998.9 6001.4 3842.3 3843.4 3869.9 3880.3 3893.7 5042.7 5177.1 5244.3 5480.5 5784.8 4687.9 4801.5 5556.8 5648.6 5665.0 5090.4 5173.5 5312.6 5655.9 5671.5 4718.6 4928.7 5494.6 5539.3 5554.8 4524.5 4537.6 5402.1 5522.6 5669.9 4957.4 5259.7 5411.5 5531.1 5531.5 00:04:00 INFO - PROCESS | 1637 | 00:04:00 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19549.20 Median:4283.92 stddev:28907.95 (674.8%) stddev-sans-first:28954.04 00:04:00 INFO - PROCESS | 1637 | Values: 600.5 605.7 687.8 726.2 772.7 14010.6 14375.6 14420.7 14446.9 14532.7 10672.3 12687.7 12828.6 12855.9 13155.5 640.6 659.9 799.6 825.1 833.9 832.7 835.2 835.9 837.8 838.3 885.9 890.1 890.3 890.5 890.6 686.1 710.6 843.1 858.8 876.5 647.9 648.1 648.2 649.6 650.7 13473.0 13798.7 13836.5 13840.9 13846.9 12537.1 12570.2 12582.2 12583.3 12650.0 11289.1 11420.0 11572.0 12015.8 12102.5 4072.4 4217.8 4280.4 4292.0 4319.1 78587.2 80896.2 80955.6 81013.0 83262.8 9669.7 10037.4 10088.9 10169.2 10297.9 18442.7 19013.3 19078.2 19112.9 19122.0 7406.7 7447.5 7453.3 7561.7 7780.5 3862.4 4001.7 4004.0 4014.7 4073.5 3344.8 3447.1 3459.5 3477.3 3485.9 643.7 648.4 648.9 661.1 666.6 577.6 635.3 648.2 656.6 671.4 8167.9 8830.9 8932.7 8988.9 9018.5 519.0 547.7 560.1 566.4 615.6 4087.7 4180.2 4232.8 4287.4 4359.3 88163.6 111437.0 111839.3 113018.0 113899.0 98442.0 100814.5 101478.0 102075.5 105747.5 69333.3 79399.0 80244.6 80920.6 88528.0 7411.3 7539.4 9194.1 9446.1 9627.0 47204.1 50862.0 51275.0 52801.5 53009.0 50120.5 51245.2 55533.7 67138.3 69397.3 11496.0 12017.6 12059.5 12231.4 12372.3 8765.6 11677.2 12431.0 13589.9 14036.6 11254.8 11707.4 11708.7 12169.0 12276.7 12681.7 12973.7 13232.6 13232.8 13347.5 12453.0 12503.8 12581.7 12651.2 12921.6 8416.0 10107.8 10634.4 10717.2 11128.2 10723.6 10918.8 11059.2 11183.9 11243.0 00:04:00 INFO - PROCESS | 1637 | -------- Summary: end -------- 00:04:00 INFO - PROCESS | 1637 | 00:04:01 INFO - TEST-INFO | 1637: exit 0 00:04:01 INFO - TEST-OK | dromaeo_css | took 1119118ms 00:04:01 INFO - TEST-START | kraken 00:04:01 INFO - operating with platform_type : mac_ 00:04:01 INFO - Initialising browser for kraken test... 00:04:01 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpZXyp6d/profile http://localhost:49241/getInfo.html) 00:04:03 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 00:04:03 INFO - PROCESS | 1648 | colorDepth:24 00:04:03 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 00:04:03 INFO - PROCESS | 1648 | __metrics 00:04:03 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:04:03 INFO - PROCESS | 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:04:04 INFO - TEST-INFO | 1648: exit 0 00:04:04 INFO - Browser initialized. 00:04:04 INFO - Running cycle 1/1 for kraken test... 00:04:04 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpZXyp6d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:04:17 INFO - PROCESS | 1649 | RSS: Main: 222146560 00:04:17 INFO - PROCESS | 1649 | 00:04:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 00:04:20 INFO - PROCESS | 1649 | RSS: Main: 272429056 00:04:20 INFO - PROCESS | 1649 | 00:04:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 00:04:24 INFO - PROCESS | 1649 | RSS: Main: 283807744 00:04:24 INFO - PROCESS | 1649 | 00:04:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 00:04:27 INFO - PROCESS | 1649 | RSS: Main: 380092416 00:04:27 INFO - PROCESS | 1649 | 00:04:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 00:04:30 INFO - PROCESS | 1649 | RSS: Main: 283635712 00:04:30 INFO - PROCESS | 1649 | 00:04:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:04:33 INFO - PROCESS | 1649 | RSS: Main: 285048832 00:04:33 INFO - PROCESS | 1649 | 00:04:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 00:04:36 INFO - PROCESS | 1649 | RSS: Main: 324632576 00:04:36 INFO - PROCESS | 1649 | 00:04:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 00:04:40 INFO - PROCESS | 1649 | RSS: Main: 327004160 00:04:40 INFO - PROCESS | 1649 | 00:04:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 00:04:43 INFO - PROCESS | 1649 | RSS: Main: 324808704 00:04:43 INFO - PROCESS | 1649 | 00:04:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:04:45 INFO - PROCESS | 1649 | RSS: Main: 299270144 00:04:45 INFO - PROCESS | 1649 | 00:04:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:04:48 INFO - PROCESS | 1649 | RSS: Main: 300077056 00:04:48 INFO - PROCESS | 1649 | 00:04:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:04:50 INFO - PROCESS | 1649 | RSS: Main: 302399488 00:04:50 INFO - PROCESS | 1649 | 00:04:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:04:54 INFO - PROCESS | 1649 | RSS: Main: 322174976 00:04:54 INFO - PROCESS | 1649 | 00:04:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:04:58 INFO - PROCESS | 1649 | RSS: Main: 315277312 00:04:58 INFO - PROCESS | 1649 | 00:04:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 00:05:00 INFO - PROCESS | 1649 | RSS: Main: 359362560 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | __start_tp_report 00:05:00 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 00:05:00 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 00:05:00 INFO - PROCESS | 1649 | |i|pagename|runs| 00:05:00 INFO - PROCESS | 1649 | |0;ai-astar;116;115;116;115;115;117;114;115;115;121 00:05:00 INFO - PROCESS | 1649 | |1;audio-beat-detection;144;109;108;143;110;112;132;140;149;112 00:05:00 INFO - PROCESS | 1649 | |2;audio-dft;183;181;181;184;183;184;182;186;185;184 00:05:00 INFO - PROCESS | 1649 | |3;audio-fft;80;61;49;95;78;86;83;82;91;57 00:05:00 INFO - PROCESS | 1649 | |4;audio-oscillator;89;72;95;69;69;69;89;67;69;94 00:05:00 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;88;85;89;86;87;87;90;86;90;86 00:05:00 INFO - PROCESS | 1649 | |6;imaging-darkroom;97;96;98;97;97;109;97;98;96;97 00:05:00 INFO - PROCESS | 1649 | |7;imaging-desaturate;79;84;82;80;82;84;84;105;86;81 00:05:00 INFO - PROCESS | 1649 | |8;json-parse-financial;58;54;54;54;54;59;59;57;58;58 00:05:00 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;65;68;74;70;75;71;71;69;69;70 00:05:00 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;86;93;101;100;101;83;89;80;83;84 00:05:00 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;140;140;142;142;151;149;141;156;188;140 00:05:00 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;164;166;167;165;167;164;167;165;174;164 00:05:00 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;68;67;68;66;65;67;66;66;66;66 00:05:00 INFO - PROCESS | 1649 | __end_tp_report 00:05:00 INFO - PROCESS | 1649 | __start_cc_report 00:05:00 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1992 00:05:00 INFO - PROCESS | 1649 | __end_cc_report 00:05:00 INFO - PROCESS | 1649 | __startTimestamp1484899500017__endTimestamp 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | ------- Summary: start ------- 00:05:00 INFO - PROCESS | 1649 | Number of tests: 14 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:115.90 Median:115.50 stddev:1.97 (1.7%) stddev-sans-first:2.09 00:05:00 INFO - PROCESS | 1649 | Values: 116.0 115.0 116.0 115.0 115.0 117.0 114.0 115.0 115.0 121.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:125.90 Median:136.00 stddev:17.11 (12.6%) stddev-sans-first:16.85 00:05:00 INFO - PROCESS | 1649 | Values: 144.0 109.0 108.0 143.0 110.0 112.0 132.0 140.0 149.0 112.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.64 (0.9%) stddev-sans-first:1.73 00:05:00 INFO - PROCESS | 1649 | Values: 183.0 181.0 181.0 184.0 183.0 184.0 182.0 186.0 185.0 184.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:76.20 Median:82.50 stddev:15.30 (18.5%) stddev-sans-first:16.16 00:05:00 INFO - PROCESS | 1649 | Values: 80.0 61.0 49.0 95.0 78.0 86.0 83.0 82.0 91.0 57.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:80.50 stddev:11.87 (14.7%) stddev-sans-first:11.93 00:05:00 INFO - PROCESS | 1649 | Values: 89.0 72.0 95.0 69.0 69.0 69.0 89.0 67.0 69.0 94.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:87.50 stddev:1.78 (2.0%) stddev-sans-first:1.87 00:05:00 INFO - PROCESS | 1649 | Values: 88.0 85.0 89.0 86.0 87.0 87.0 90.0 86.0 90.0 86.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:98.20 Median:97.00 stddev:3.85 (4.0%) stddev-sans-first:4.06 00:05:00 INFO - PROCESS | 1649 | Values: 97.0 96.0 98.0 97.0 97.0 109.0 97.0 98.0 96.0 97.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:83.00 stddev:7.44 (9.0%) stddev-sans-first:7.60 00:05:00 INFO - PROCESS | 1649 | Values: 79.0 84.0 82.0 80.0 82.0 84.0 84.0 105.0 86.0 81.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:58.00 stddev:2.22 (3.8%) stddev-sans-first:2.29 00:05:00 INFO - PROCESS | 1649 | Values: 58.0 54.0 54.0 54.0 54.0 59.0 59.0 57.0 58.0 58.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:2.86 (4.1%) stddev-sans-first:2.33 00:05:00 INFO - PROCESS | 1649 | Values: 65.0 68.0 74.0 70.0 75.0 71.0 71.0 69.0 69.0 70.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:83.50 stddev:8.18 (9.8%) stddev-sans-first:8.55 00:05:00 INFO - PROCESS | 1649 | Values: 86.0 93.0 101.0 100.0 101.0 83.0 89.0 80.0 83.0 84.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:145.50 stddev:14.83 (10.2%) stddev-sans-first:15.37 00:05:00 INFO - PROCESS | 1649 | Values: 140.0 140.0 142.0 142.0 151.0 149.0 141.0 156.0 188.0 140.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:166.50 stddev:2.98 (1.8%) stddev-sans-first:3.05 00:05:00 INFO - PROCESS | 1649 | Values: 164.0 166.0 167.0 165.0 167.0 164.0 167.0 165.0 174.0 164.0 00:05:00 INFO - PROCESS | 1649 | 00:05:00 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:0.97 (1.5%) stddev-sans-first:0.87 00:05:00 INFO - PROCESS | 1649 | Values: 68.0 67.0 68.0 66.0 65.0 67.0 66.0 66.0 66.0 66.0 00:05:00 INFO - PROCESS | 1649 | -------- Summary: end -------- 00:05:00 INFO - PROCESS | 1649 | 00:05:01 INFO - TEST-INFO | 1649: exit 0 00:05:01 INFO - TEST-OK | kraken | took 60030ms 00:05:01 INFO - SUITE-END | took 1179s 00:05:01 INFO - Completed test suite (00:19:39) 00:05:01 INFO - javascript benchmark 00:05:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3361.075704181348, 3371.1403592084016, 3374.3588718143556, 3387.898363049108, 3421.5551978242825, 5748.827535173947, 5818.301803783541, 5819.82540523785, 5844.941550584493, 5845.912311315323, 5703.201551782743, 5725.770969161239, 5825.417458254173, 5835.35811060784, 5839.153322768196, 5236.73816309184, 5286.603504737141, 5296.417394086648, 5298.761555730001, 5336.786528538853, 5152.793888244466, 5154.561862241714, 5159.484051594829, 5237.423883372826, 5352.946470535284, 5290.0, 5356.3572371315495, 5358.678479291236, 5362.034833729927, 5406.729663516828, 4726.291056341576, 5340.492653197959, 5351.7056561889085, 5358.223057656657, 5372.355317361941, 5093.286939828427, 5191.454897235812, 5279.208118782175, 5294.100002999499, 5305.31030965972, 5666.801661941853, 5706.80026199081, 5744.482996530291, 5794.565407594448, 5796.478316951452, 5451.155070964007, 6028.884656338591, 6240.500759939239, 6333.524985626051, 6333.904991425133, 6319.5576309658345, 6322.272938612073, 6351.142595749561, 6353.269374022, 6354.173957385557, 5712.400197979152, 5734.827955161393, 5848.152017957373, 5873.177755114288, 5887.264091988502, 6331.145295385064, 6346.619202847844, 6349.619022858551, 6355.8093257201435, 6358.682065896686, 5481.890362192814, 5985.820425387245, 6294.65379404128, 6294.905576416358, 6306.306306306303, 6146.108814221912, 6232.158658581124, 6270.592411493239, 6322.304546499881, 6392.296847346788, 6324.525660575475, 6337.144485494491, 6342.460890824239, 6345.777897773556, 6356.904646430307, 6243.344448832898, 6274.874502510015, 6326.114343991845, 6333.429991300805, 6339.778107766206, 4869.9269510957365, 4919.3604831371695, 4926.679765815211, 4936.827211047596, 5010.073136469765, 4437.889052773642, 4475.843345482893, 4478.619317357996, 4490.393796837385, 4537.4328208973875, 5942.37605051472, 5945.048792193228, 5951.791687291011, 5952.226210592596, 5954.404559544011, 6185.165002724574, 6210.125463599654, 6301.275353334334, 6318.368163183645, 6328.367163283635, 5843.795467158629, 5884.08796636522, 5912.615679980788, 5963.612365196248, 6026.969865150646, 5425.810096646677, 5486.067368547357, 5487.917681234785, 5497.092979658311, 5500.037493438632, 6218.9378106218355, 6416.550841441054, 6428.935710643021, 6432.517561182836, 6437.871242575216, 6217.191765070512, 6267.310595834363, 6338.841528961812, 6339.651319177489, 6343.0485427186295, 4811.591014763644, 6269.216347956505, 6301.275353334242, 6323.905141422791, 6340.302566717565, 6167.84580385494, 6183.783567575113, 6186.876262474815, 6188.288346840171, 6198.2871969722255, 5823.679697616672, 6224.346443623354, 6235.563510554218, 6238.407351301619, 6256.937430625636, 6137.7851775187655, 6199.070139479114, 6201.906971395523, 6207.875842483216, 6238.344973777688, 6193.473554747717, 6203.720832562455, 6204.317525072329, 6210.782622608187, 6216.595921265103, 4946.826861059845, 5512.6692398456225, 5960.254968128984, 5973.014452615444, 5978.192943952511, 6229.937700622936, 6241.594296370721, 6258.155149054819, 6259.6870156492905, 6260.68696565179, 5852.18069470266, 6107.755689772359, 6112.724927378367, 6130.877382452415, 6133.417325354084], "value": 5749.1698660698075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.9319102134679, 467.69833457419963, 509.46506168523047, 519.6440438299765, 548.7640314664693, 30344.393112137743, 31905.840470797644, 39451.80274098633, 40166.397504037464, 40993.180136397255, 40836.38745418821, 41082.79458602703, 41195.58804411955, 41326.38010429838, 41384.0, 9222.723318300461, 9412.482313472756, 9418.1994530465, 9419.434833909952, 9423.010583888661, 8426.705065322714, 8632.827343453127, 11164.832527512093, 11428.599999000036, 11438.71403214922, 11357.204995650267, 11434.71413214672, 11436.656900292963, 11459.427028648575, 11530.538778448852, 8440.324774018063, 11326.886731132707, 11382.487788049475, 11382.829257561143, 11407.030402415805, 7928.524288542706, 8451.83096338076, 11282.94358528202, 11329.773404531863, 11457.197996140274, 31649.8417507911, 39856.40215396771, 39922.800385998176, 39967.0, 40054.39918401226, 3273.885414010522, 3532.5584301962253, 3650.7809531428197, 3794.2793789379316, 3861.2084522672785, 28460.430791384057, 28461.146165615064, 29824.552631710543, 30027.69972300271, 31016.53475197874, 2670.5994100885023, 3150.2596889730894, 3172.7779055466126, 3212.3735871505064, 3241.6272128705264, 494977.0, 498598.0140198588, 500180.4990975022, 501072.0, 508149.0, 59633.403665963655, 59914.0, 59926.70036649876, 60036.0, 60182.69908650516, 34689.65310346864, 35522.28955420929, 35582.93251202468, 36334.454983175274, 36398.454023189144, 15736.0, 15880.285387157606, 15897.682046359008, 15907.522774316789, 15914.92042539803, 11260.605878794202, 11337.546498140147, 12096.939515302543, 12146.271223726604, 12147.574834880737, 18895.716564251547, 19091.427257182306, 19377.709334359715, 19381.806181938282, 19651.901740491205, 5461.672299662033, 7232.566046037255, 7241.963790181016, 7307.744228951961, 7320.597367144858, 805.7341077444429, 911.4941207629696, 929.9953500232456, 953.7329547726648, 966.6133546581283, 29123.56314655282, 29508.704912951023, 29633.555496667566, 30045.849770751007, 30297.697023029486, 1020.5764607687875, 1049.2445439283704, 1109.3177695717238, 1133.8015847226702, 1173.3370645585217, 15261.389544418322, 15293.0, 15460.5361839145, 15486.457973970857, 15995.680086398206, 668380.0, 672349.0, 673425.0, 674963.3762818278, 675749.0, 57376.13935790966, 57890.4210957885, 57938.0, 58085.419145808846, 58092.0, 1225.8345123408403, 1266.2592383455597, 1348.82465279515, 1365.3651052260745, 1387.1676993803637, 29639.25901852471, 30099.849500752356, 30291.69708302977, 30352.696473034986, 30852.22869428282, 49087.75456122697, 49643.75178124086, 49671.25493117534, 49691.75154124206, 49703.75148124236, 545230.0, 548270.0, 548299.0, 549177.0, 549263.0, 22190.66713999259, 26219.73780262173, 27176.048838290564, 27766.0, 27776.44447111006, 43727.34408983932, 45120.871978200805, 46129.077418452114, 46648.53351466442, 46829.0, 37007.259854802214, 37162.44256336103, 37408.438873417464, 38077.809610951765, 39347.0, 36752.448713269856, 37207.627923720414, 37465.0, 38413.039674008374, 38561.42157867578, 37024.07439814026, 37650.81174594219, 37741.24517509579, 37813.24373512459, 37863.43204851874, 29518.505928695653, 31532.684673152973, 33992.490112647836, 34320.141996450286, 34458.82770586131, 24832.50334993254, 24957.87521062383, 25120.0, 25602.84787184544, 25816.483670326863], "value": 19574.029231733533, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.5610973813624, 313.346672188487, 376.26064782701985, 377.7072768604331, 404.7692815095395, 14857.925710371446, 14869.776953345696, 19819.504512387197, 20174.991250437455, 21036.789632103675, 19716.0, 20104.396868093943, 20117.295894643692, 20385.980700964967, 20577.897110514463, 5637.577181711367, 5881.382454842244, 5932.021216499273, 5935.228420305354, 5935.673537955411, 5263.973680131594, 5575.163725441175, 5869.735861886225, 5901.911471327933, 5944.821655350324, 4653.557911998371, 5182.948170518284, 5947.137665038566, 5948.1375200595885, 5959.731812068446, 5674.801381951612, 5852.4440178183, 5911.556633252501, 5932.940670593303, 5939.881202375971, 5716.771329146829, 5747.59766816323, 5760.53915686744, 5765.308163020422, 5766.0486019806685, 12649.367531623382, 13380.531681391201, 13515.053946223768, 13519.986001049961, 13541.661458463519, 2182.181681869299, 2185.7704940981125, 2408.3497455687043, 2530.683664541932, 2621.0040184729874, 9812.264080193943, 10015.298929074968, 10041.096301332842, 10373.636922707668, 10380.480975951248, 1828.5794267318458, 1915.9521011974673, 1948.9317873874347, 1952.5021119614548, 1966.6361723081275, 110579.44710276397, 114634.85365146326, 118554.40722796417, 122100.38949805194, 124548.37725811313, 10140.594376224868, 11107.944460277648, 11338.716532086599, 11798.410079496163, 11914.880851191378, 13571.457141714418, 14485.130892146504, 14798.630034249014, 14801.0, 14804.963652544435, 8528.360372972053, 8602.0, 8655.6970506032, 8682.348823838238, 8805.69180078694, 8191.0, 8323.0, 8400.831983360298, 8401.991760988598, 8543.700970466003, 4571.1543364477675, 4572.771361431982, 4576.656750743641, 4583.129205450954, 4656.837010704609, 1867.9252829886652, 1915.1669023974337, 1916.549610841479, 1917.2618541861484, 1919.29945569866, 825.7646570727314, 828.1676914700807, 876.2770714160843, 915.8900931888217, 931.9347645664874, 20257.696134557995, 20856.957152142637, 21015.003574660383, 21202.0, 21309.573808523743, 902.8871391076116, 908.7500937242311, 923.1230331185267, 925.5788616179622, 943.235978857128, 10244.897551024544, 11066.889331106748, 11093.445327733582, 11097.83353249702, 11168.385738784282, 611248.8875111281, 612529.0, 625312.0, 626327.0, 637318.0, 111554.0, 115935.42032289953, 119598.80401195877, 122361.16458253133, 130850.0, 932.398602901125, 950.8288508068475, 963.4700914496999, 965.2229954886384, 969.0454901921602, 17380.304787808345, 18249.452516424528, 18626.81373186251, 18650.160742766337, 18653.720194196827, 47427.288590670476, 51119.0, 52166.47833521616, 52527.737361312946, 53057.0, 56850.0, 68158.65920670563, 68827.96758048734, 70930.64534677293, 73840.89238661317, 7091.326323999212, 7401.5189012714, 7920.920790792249, 8037.8392432152195, 8153.429259951984, 11468.713282168013, 12267.754644906874, 12316.81524777147, 12619.684507887376, 12727.363631818558, 10502.0, 10555.894441055798, 10565.735856603338, 10640.095591874777, 10922.672319830417, 10503.317284376491, 10506.264561480622, 10573.2598718089, 10746.140308775122, 10853.0, 10774.838377424188, 10858.077063449695, 10872.565097396016, 11144.0, 11206.495707693337, 7129.7861064168155, 7148.714051437884, 7209.567425954459, 7419.666115024729, 7445.994790703186, 5270.156774915995, 8198.38512111606, 9036.502992335485, 10575.153987680813, 10802.405867677353], "value": 9137.2846138885, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.0, 1454.2946670864628, 1486.4945918387746, 1645.2020769926582, 1830.670479313723, 7483.588402637853, 7868.921310786897, 8160.755177344674, 8434.873476897852, 8444.324454043677, 8218.096009438957, 8266.62800173992, 8378.162183781618, 8379.162083791618, 8386.62260198291, 2321.8606883586995, 2355.9646605300936, 2356.434455730621, 2356.9292921212304, 2359.764023597635, 2108.641530939736, 2276.4195130241765, 2335.065973610552, 2374.560706269337, 2381.0356805493716, 2306.8039216666602, 2319.524497478013, 2338.6141286687675, 2340.20433052762, 2343.496148328109, 2280.703508543879, 2304.135949019118, 2325.476767727256, 2325.7790509901533, 2334.7198336199594, 2326.220716060122, 2349.6475528670667, 2349.835511514195, 2354.093673935547, 2358.2217868103658, 8307.875381869277, 8391.412601117925, 8401.15988401155, 8405.327573794142, 8414.495130292202, 5025.673331233458, 5056.0, 5231.9476805232225, 5252.501012403815, 5271.0, 3294.2917272786226, 3474.6525347465304, 3570.8750193743354, 3572.6605972432576, 3576.2489877125836, 1040.6825918094978, 1067.0449947297204, 1075.833245846895, 1081.0756802933458, 1096.506572042576, 10524.263301568893, 10844.295120817122, 10896.18278629106, 10915.617953371593, 11061.834072489, 4264.701470897007, 4265.317549192115, 4311.805968731414, 4401.625861801719, 4497.955020449754, 8543.231109200202, 8628.827423451496, 8647.048824629286, 8654.307655387554, 8684.261837743863, 5121.743912804345, 5121.769520371592, 5125.820596279113, 5131.358580177478, 5132.8964272680805, 4064.4716186895516, 4319.762413067248, 4450.532694067141, 4472.642188624902, 4825.227963525889, 3032.9696703032687, 3625.2386998730035, 3717.498137751369, 3720.4605332227216, 3731.9067023324633, 1002.6691191906654, 1113.287496002559, 1132.767782604564, 1135.7671677306132, 1147.2428197389684, 799.9800004999805, 885.5793498088357, 905.2260317428504, 911.8541033434751, 914.593006112287, 5705.657660540381, 5765.769369225184, 5872.383399743051, 5926.23600293019, 6166.383361663798, 874.8031692869054, 892.638481415028, 908.3777612335572, 909.4406939732017, 988.5172241637543, 3169.3819705157734, 3730.813459327077, 3793.8482460701816, 3810.7329362579967, 3830.8276127574327, 9950.701478955498, 10188.032136946978, 10321.535530901128, 10333.48332583383, 10348.016938390841, 10280.537375818107, 10285.331453455501, 10298.279120461495, 10303.227257955683, 10320.845187322197, 7844.0, 8019.839603207904, 8026.5986700664735, 8052.235037671412, 8065.435419520694, 5135.614828887774, 5459.235707000944, 5561.416051314553, 5705.885882282414, 5728.0262355398845, 9449.385789923634, 9502.714918552454, 9681.66114186, 9684.128428441474, 9707.0, 7165.745994451055, 8546.829063418822, 8897.510636915033, 9621.615135394506, 9644.083812037845, 6776.728930842711, 7045.647717614202, 7289.890651640124, 7310.8903366448485, 7367.300106489874, 7584.0, 7938.64276107565, 8036.557989310644, 8097.2712455879255, 8099.757007289791, 7091.0, 7222.313880181375, 7234.927650723636, 7283.271672832674, 7370.078740157481, 7057.576545407292, 7085.681144348414, 7094.396976257076, 7108.3602475777425, 7127.928720712934, 6707.765228216989, 6723.697433615401, 6764.120664313805, 6801.591904485746, 6811.080504132077, 4791.448983366958, 4817.9036419272115, 4852.369192005017, 4870.09903167915, 4895.33912921751, 4927.088488629615, 4932.45742968266, 4948.307236986752, 5000.549950504472, 5025.597952163745], "value": 4392.288618057032, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [246.6817805031509, 303.41895270556887, 303.6523180957803, 308.4725120044723, 312.56865525574705, 5307.205348176053, 5322.33470816148, 5332.973335133329, 5354.41101478837, 5366.3828659704095, 5268.314139475369, 5277.445868183843, 5292.470752924705, 5294.761735721892, 5351.625386222966, 3136.40408322418, 3530.3645343838098, 3597.9640203598024, 3807.200487897538, 3926.391409331544, 3495.0563347896054, 4014.1770936958, 4027.758334499925, 4078.714489985702, 4107.404426358176, 3654.7441679082476, 3870.7290489665734, 3983.470496234883, 3985.5688030301494, 4014.0767623446623, 3918.7648741075627, 4025.1748391579804, 4041.838326466938, 4047.377597050623, 4048.878533643966, 3486.163320802989, 3529.5940966788635, 3538.841860813534, 3551.164742078549, 3559.60844307126, 4203.600657937522, 5112.182050871843, 5115.923261151086, 5126.359205099363, 5130.280951095888, 4440.911181776379, 4597.218472859622, 4604.286335617984, 4631.768411579407, 4636.843867601581, 4895.922896962663, 4969.006198760262, 5479.972600137014, 5481.616286859921, 5556.527695145917, 3873.992761881903, 3897.7466464679705, 3900.4344370066465, 3902.804859757029, 3913.158670885773, 4522.648222924061, 5306.787728490855, 5564.693941833198, 5784.623999440023, 5826.388229235954, 3924.9982508920084, 3957.4261732049267, 3959.8416063357145, 3974.900627484336, 4003.619656132663, 3596.3526565218513, 3830.4637350770913, 3994.0813612868624, 4013.8595149170224, 4014.3541148129125, 4059.837606495707, 4609.459682213018, 4640.23436133041, 4649.5815376616265, 4651.465081515601, 4646.372739680168, 5374.543163831118, 5495.945040549624, 5533.063427800238, 5587.245721827582, 1101.2786624760633, 1124.80315944711, 1302.4074046309097, 1310.702404619439, 1312.1864444044754, 849.902261239953, 880.8810810540623, 893.629143905285, 894.789724414762, 900.3427497926419, 4409.735415875058, 4505.617022553054, 4681.625469962395, 5098.770555324945, 5130.666506677054, 5596.972015139898, 5768.480836724715, 5780.95333266715, 5835.620684655484, 5851.941480585225, 4539.228331183706, 5026.698398096126, 5245.921311180336, 5261.342332208474, 5261.631685782035, 2637.4988752137033, 2642.141304076183, 2833.6115303501138, 2890.248535380816, 2891.9710802892123, 4858.5384388483035, 5290.867728306823, 5358.5981051421295, 5455.826997195622, 5643.5767317451355, 5124.410692770304, 5348.251244825728, 5567.359753628384, 5595.524380427628, 5602.38373778884, 5172.611939179843, 5233.450487698889, 5499.120140777456, 5657.547396208376, 5667.574931880125, 4952.529509696573, 5288.762005709676, 5465.3109151321905, 5482.725863706719, 5510.531604813636, 5101.923471147861, 5317.574594032545, 5704.486596206361, 5777.884442311046, 5787.594868359174, 5984.431479009487, 5985.371535988658, 5987.970060149672, 5998.93036902268, 6001.429864163072, 3842.308384490707, 3843.442700808311, 3869.9419508707956, 3880.340342141786, 3893.7469064510715, 5042.747862606929, 5177.058603023866, 5244.318238628954, 5480.478979669397, 5784.82645520635, 4687.9296810547185, 4801.535531662877, 5556.777728890961, 5648.604597678123, 5664.971675141598, 5090.414602320632, 5173.508516690908, 5312.619204494159, 5655.915161272502, 5671.517920976763, 4718.598919091778, 4928.732782919754, 5494.587905906993, 5539.307586551681, 5554.805581804779, 4524.529527903379, 4537.591616754508, 5402.135658294654, 5522.641028333145, 5669.902136209629, 4957.355543779286, 5259.684418934876, 5411.511834245614, 5531.1426728857095, 5531.529819965348], "value": 4028.390344849178, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.4505877122433, 605.6517502436101, 687.824604725795, 726.2156870579774, 772.7179579453501, 14010.579682609512, 14375.64060898478, 14420.711585768291, 14446.855531444682, 14532.709345813077, 10672.252942294042, 12687.682807929783, 12828.615141545744, 12855.935720321386, 13155.539556115538, 640.634838142258, 659.858130501942, 799.5722288575613, 825.0717942314895, 833.9374546908975, 832.662771577509, 835.1606635331483, 835.8787975743513, 837.798928257217, 838.287455662688, 885.8715486254522, 890.1098901098901, 890.2521881619439, 890.4835195586577, 890.6259371064168, 686.0806519264185, 710.5701050864204, 843.1357858195338, 858.7638399440142, 876.4784952952983, 647.9125318082046, 648.0506058624092, 648.1962366665348, 649.6069877723957, 650.7364517370472, 13473.0, 13798.65503362414, 13836.515721949683, 13840.93079534606, 13846.861531384659, 12537.122401431903, 12570.182938108994, 12582.182158159781, 12583.307918064504, 12650.0, 11289.0968722502, 11419.99171958321, 11572.016378607828, 12015.819762703566, 12102.455389507493, 4072.36220863773, 4217.8312867485265, 4280.400743895857, 4292.034292284242, 4319.114581510814, 78587.21412785913, 80896.19103808946, 80955.59522202352, 81013.0, 83262.75105873417, 9669.70990870275, 10037.397756134655, 10088.899111008795, 10169.237307201842, 10297.897021029694, 18442.723359149622, 19013.334533291545, 19078.236870525023, 19112.904435477732, 19122.0, 7406.703731850774, 7447.515911465737, 7453.291937265951, 7561.659725312375, 7780.533168009937, 3862.420636904487, 4001.679865610744, 4004.01901534126, 4014.6788256939376, 4073.450084238649, 3344.7658663893294, 3447.1382154461385, 3459.4983727359895, 3477.2697733475748, 3485.9477107843404, 643.7425029988042, 648.3678413546754, 648.9123968264224, 661.0909998751716, 666.6466772610524, 577.6476349426846, 635.2662674610832, 648.1962366665396, 656.5535435903536, 671.4494114825796, 8167.877481837659, 8830.86753698695, 8932.69259588576, 8988.910110898807, 9018.458892466473, 518.9610779191576, 547.6686604604157, 560.1429812387055, 566.3515275010091, 615.5691016288616, 4087.713860029829, 4180.163967206571, 4232.809523571446, 4287.444127188179, 4359.258925982591, 88163.55918220368, 111437.0, 111839.3224101639, 113018.0, 113899.0, 98442.0, 100814.48778268333, 101478.0, 102075.48962255141, 105747.4712626432, 69333.3066669347, 79399.0, 80244.59877700574, 80920.59539702382, 88528.0, 7411.295926886937, 7539.359154471931, 9194.12655797725, 9446.10262025134, 9627.0, 47204.05591888211, 50862.0, 51275.0, 52801.47198528119, 53009.0, 50120.498795013045, 51245.231321530955, 55533.72233138808, 67138.31446742076, 69397.3060269411, 11496.0, 12017.63947081589, 12059.51761929513, 12231.388430578614, 12372.319522426353, 8765.649374024968, 11677.240979336317, 12431.0, 13589.932050340081, 14036.649083772987, 11254.83117753218, 11707.414629268673, 11708.70728231801, 12169.02647788157, 12276.693082673004, 12681.746365072831, 12973.675658108623, 13232.603021909357, 13232.801507977581, 13347.532836350681, 12453.0, 12503.812442813547, 12581.685457863627, 12651.240925544496, 12921.612351629467, 8416.0, 10107.848382274125, 10634.361938283728, 10717.249792514449, 11128.18326906515, 10723.624673136403, 10918.836217456586, 11059.170562207706, 11183.944080279547, 11243.0], "value": 7026.547946805892, "unit": "score"}], "name": "dromaeo_css", "value": 7097.7210623475485, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 115.0, 115.0, 117.0, 114.0, 115.0, 115.0, 121.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 108.0, 143.0, 110.0, 112.0, 132.0, 140.0, 149.0, 112.0], "value": 125.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 181.0, 184.0, 183.0, 184.0, 182.0, 186.0, 185.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 49.0, 95.0, 78.0, 86.0, 83.0, 82.0, 91.0, 57.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 95.0, 69.0, 69.0, 69.0, 89.0, 67.0, 69.0, 94.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 86.0, 87.0, 87.0, 90.0, 86.0, 90.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 98.0, 97.0, 97.0, 109.0, 97.0, 98.0, 96.0, 97.0], "value": 98.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 82.0, 80.0, 82.0, 84.0, 84.0, 105.0, 86.0, 81.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 54.0, 54.0, 54.0, 59.0, 59.0, 57.0, 58.0, 58.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 74.0, 70.0, 75.0, 71.0, 71.0, 69.0, 69.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 93.0, 101.0, 100.0, 101.0, 83.0, 89.0, 80.0, 83.0, 84.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 142.0, 142.0, 151.0, 149.0, 141.0, 156.0, 188.0, 140.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 167.0, 165.0, 167.0, 164.0, 167.0, 165.0, 174.0, 164.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 65.0, 67.0, 66.0, 66.0, 66.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1448.2, "alertThreshold": 2.0}]} 00:05:01 INFO - Return code: 0 00:05:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:05:01 INFO - # TBPL SUCCESS # 00:05:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:05:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:05:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:05:01 INFO - 2017-01-20 00:05:01.325 screenresolution[1653:9936] starting screenresolution argv=screenresolution get 00:05:01 INFO - 2017-01-20 00:05:01.327 screenresolution[1653:9936] Display 0: 1600x1200x32@60 00:05:01 INFO - 2017-01-20 00:05:01.333 screenresolution[1654:9940] starting screenresolution argv=screenresolution list 00:05:01 INFO - Available Modes on Display 0 00:05:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:05:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:05:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:05:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:05:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:05:01 INFO - Intel Iris: 00:05:01 INFO - Chipset Model: Intel Iris 00:05:01 INFO - Type: GPU 00:05:01 INFO - Bus: Built-In 00:05:01 INFO - VRAM (Dynamic, Max): 1536 MB 00:05:01 INFO - Vendor: Intel (0x8086) 00:05:01 INFO - Device ID: 0x0a2e 00:05:01 INFO - Revision ID: 0x0009 00:05:01 INFO - Displays: 00:05:01 INFO - Display: 00:05:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:05:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:05:01 INFO - Display Serial Number: 1600x1200 60 00:05:01 INFO - Main Display: Yes 00:05:01 INFO - Mirror: Off 00:05:01 INFO - Online: Yes 00:05:01 INFO - Rotation: Supported 00:05:01 INFO - Return code: 0 00:05:01 INFO - Running post-action listener: _resource_record_post_action 00:05:01 INFO - [mozharness: 2017-01-20 08:05:01.579266Z] Finished run-tests step (success) 00:05:01 INFO - Running post-run listener: _resource_record_post_run 00:05:01 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 43246592; Write bytes: 664248320; Read time: 466; Write time: 1911 00:05:01 INFO - TinderboxPrint: CPU usage
53.4% 00:05:01 INFO - TinderboxPrint: I/O read bytes / time
43,246,592 / 466 00:05:01 INFO - TinderboxPrint: I/O write bytes / time
664,248,320 / 1,911 00:05:01 INFO - TinderboxPrint: CPU idle
2,258.0 (46.6%) 00:05:01 INFO - TinderboxPrint: CPU system
970.7 (20.0%) 00:05:01 INFO - TinderboxPrint: CPU user
1,617.1 (33.4%) 00:05:01 INFO - TinderboxPrint: Swap in / out
390,238,208 / 0 00:05:01 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348643840; Write bytes: 346023936; Read time: 27233; Write time: 717 00:05:01 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 40920064; Write bytes: 310917120; Read time: 444; Write time: 1164 00:05:02 INFO - Running post-run listener: _upload_blobber_files 00:05:02 INFO - Blob upload gear active. 00:05:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:05:02 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 00:05:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:05:02 INFO - (blobuploader) - INFO - Open directory for files ... 00:05:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:05:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:05:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:05:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:05:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:05:03 INFO - (blobuploader) - INFO - Done attempting. 00:05:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:05:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:05:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:05:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:05:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:05:04 INFO - (blobuploader) - INFO - Done attempting. 00:05:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:05:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:05:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:05:06 INFO - (blobuploader) - INFO - Done attempting. 00:05:06 INFO - (blobuploader) - INFO - Iteration through files over. 00:05:06 INFO - Return code: 0 00:05:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:05:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:05:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86062abdeb08ecc0ea3876527a159ab225bc4da7c1e9dc45cc8e7403dfd4b06ffb8ea27c4b362ea858c10af0a2b197fe1da64d6c7b85125ab6708e59e248454a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebc040326b06de2c20aed5e436b3d38ca81c979320382475e8d367e2427a425b027bcaf6b3594c5a4ca50906b2e3b12cfbee2cef6722e0f7e95ac7e46047d9c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c88399b7bbb8b3ff5ac149bebb3c80498b416475fed7a7ae3c34e5948ec8fae117fc5368274888309b34c8252984a5355cf924f3078525bc6fbca0af23526cdc"} 00:05:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:05:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:05:06 INFO - Contents: 00:05:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86062abdeb08ecc0ea3876527a159ab225bc4da7c1e9dc45cc8e7403dfd4b06ffb8ea27c4b362ea858c10af0a2b197fe1da64d6c7b85125ab6708e59e248454a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebc040326b06de2c20aed5e436b3d38ca81c979320382475e8d367e2427a425b027bcaf6b3594c5a4ca50906b2e3b12cfbee2cef6722e0f7e95ac7e46047d9c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c88399b7bbb8b3ff5ac149bebb3c80498b416475fed7a7ae3c34e5948ec8fae117fc5368274888309b34c8252984a5355cf924f3078525bc6fbca0af23526cdc"} 00:05:06 INFO - Running post-run listener: copy_logs_to_upload_dir 00:05:06 INFO - Copying logs to upload dir... 00:05:06 INFO - mkdir: /builds/slave/test/build/upload/logs 00:05:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.095035 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-20 00:05:06.710175) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 00:05:06.713379) ========= 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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86062abdeb08ecc0ea3876527a159ab225bc4da7c1e9dc45cc8e7403dfd4b06ffb8ea27c4b362ea858c10af0a2b197fe1da64d6c7b85125ab6708e59e248454a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebc040326b06de2c20aed5e436b3d38ca81c979320382475e8d367e2427a425b027bcaf6b3594c5a4ca50906b2e3b12cfbee2cef6722e0f7e95ac7e46047d9c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c88399b7bbb8b3ff5ac149bebb3c80498b416475fed7a7ae3c34e5948ec8fae117fc5368274888309b34c8252984a5355cf924f3078525bc6fbca0af23526cdc"} build_url:https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011226 build_url: 'https://queue.taskcluster.net/v1/task/anZpsl3aS7yfJ8l_KAPgIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86062abdeb08ecc0ea3876527a159ab225bc4da7c1e9dc45cc8e7403dfd4b06ffb8ea27c4b362ea858c10af0a2b197fe1da64d6c7b85125ab6708e59e248454a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebc040326b06de2c20aed5e436b3d38ca81c979320382475e8d367e2427a425b027bcaf6b3594c5a4ca50906b2e3b12cfbee2cef6722e0f7e95ac7e46047d9c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c88399b7bbb8b3ff5ac149bebb3c80498b416475fed7a7ae3c34e5948ec8fae117fc5368274888309b34c8252984a5355cf924f3078525bc6fbca0af23526cdc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 00:05:06.747210) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 00:05:06.747516) ========= 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.tvaTzk1FX7/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.YD0el8PdnJ/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.004998 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 00:05:06.795506) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 00:05:06.795787) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 00:05:06.796105) ========= ========= Total master_lag: 0.51 =========