builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0237 starttime: 1481192941.25 results: success (0) buildid: 20161208004003 builduid: 8613420ec01a430faa9125050cbb113a revision: 9de9a34b6793b36218032a215178cc7ddbd66c98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:01.249654) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:01.250129) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:01.250414) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:01.278279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-08 02:29:01.278707) ========= 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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-08 02:29:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2016-12-08 02:29:02 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.278278 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-08 02:29:02.580892) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:02.581196) ========= 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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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.024395 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:02.638381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-08 02:29:02.638727) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9de9a34b6793b36218032a215178cc7ddbd66c98 --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 9de9a34b6793b36218032a215178cc7ddbd66c98 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EvcyDWSkiL/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.MLCNwIAf5j/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-08 02:29:02,737 truncating revision to first 12 chars 2016-12-08 02:29:02,737 Setting DEBUG logging. 2016-12-08 02:29:02,738 attempt 1/10 2016-12-08 02:29:02,738 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9de9a34b6793?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-08 02:29:03,854 unpacking tar archive at: mozilla-aurora-9de9a34b6793/testing/mozharness/ program finished with exit code 0 elapsedTime=1.390771 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-08 02:29:04.053116) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:04.053435) ========= script_repo_revision: 9de9a34b6793b36218032a215178cc7ddbd66c98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:04.053827) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:04.054117) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 02:29:04.073743) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-08 02:29:04.074070) ========= /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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:04 INFO - MultiFileLogger online at 20161208 02:29:04 in /builds/slave/test 02:29:04 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 02:29:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:29:04 INFO - {'append_to_log': False, 02:29:04 INFO - 'base_work_dir': '/builds/slave/test', 02:29:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:29:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:29:04 INFO - 'branch': 'Mozilla-Aurora', 02:29:04 INFO - 'buildbot_json_path': 'buildprops.json', 02:29:04 INFO - 'config_files': ('talos/mac_config.py',), 02:29:04 INFO - 'default_actions': ('clobber', 02:29:04 INFO - 'read-buildbot-config', 02:29:04 INFO - 'download-and-extract', 02:29:04 INFO - 'populate-webroot', 02:29:04 INFO - 'create-virtualenv', 02:29:04 INFO - 'install', 02:29:04 INFO - 'run-tests'), 02:29:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:29:04 INFO - 'download_minidump_stackwalk': True, 02:29:04 INFO - 'download_symbols': 'ondemand', 02:29:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:29:04 INFO - 'tooltool.py': '/tools/tooltool.py', 02:29:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:29:04 INFO - '/tools/misc-python/virtualenv.py')}, 02:29:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:29:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:29:04 INFO - 'installer_path': 'installer.exe', 02:29:04 INFO - 'log_level': 'info', 02:29:04 INFO - 'log_name': 'talos', 02:29:04 INFO - 'log_to_console': True, 02:29:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:29:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:29:04 INFO - 'opt_config_files': (), 02:29:04 INFO - 'pip_index': False, 02:29:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:29:04 INFO - 'cmd': ('bash', 02:29:04 INFO - '-c', 02:29:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:29:04 INFO - 'enabled': True, 02:29:04 INFO - 'halt_on_failure': False, 02:29:04 INFO - 'name': 'check_screen_resolution'},), 02:29:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:29:04 INFO - 'cmd': ('bash', 02:29:04 INFO - '-c', 02:29:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:29:04 INFO - 'enabled': True, 02:29:04 INFO - 'halt_on_failure': False, 02:29:04 INFO - 'name': 'check_screen_resolution'},), 02:29:04 INFO - 'run_cmd_checks_enabled': True, 02:29:04 INFO - 'sps_profile': False, 02:29:04 INFO - 'sps_profile_interval': 0, 02:29:04 INFO - 'suite': 'dromaeojs', 02:29:04 INFO - 'system_bits': '32', 02:29:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:29:04 INFO - 'title': 't-yosemite-r7-0237', 02:29:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:29:04 INFO - 'use_talos_json': True, 02:29:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:29:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:29:04 INFO - 'work_dir': 'build'} 02:29:04 INFO - [mozharness: 2016-12-08 10:29:04.281370Z] Running clobber step. 02:29:04 INFO - Running pre-action listener: _resource_record_pre_action 02:29:04 INFO - Running main action method: clobber 02:29:04 INFO - rmtree: /builds/slave/test/build 02:29:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:29:06 INFO - Running post-action listener: _resource_record_post_action 02:29:06 INFO - [mozharness: 2016-12-08 10:29:06.499396Z] Finished clobber step (success) 02:29:06 INFO - [mozharness: 2016-12-08 10:29:06.499520Z] Running read-buildbot-config step. 02:29:06 INFO - Running pre-action listener: _resource_record_pre_action 02:29:06 INFO - Running main action method: read_buildbot_config 02:29:06 INFO - Using buildbot properties: 02:29:06 INFO - { 02:29:06 INFO - "project": "", 02:29:06 INFO - "product": "firefox", 02:29:06 INFO - "script_repo_revision": "production", 02:29:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:29:06 INFO - "repository": "", 02:29:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:29:06 INFO - "buildid": "20161208004003", 02:29:06 INFO - "stage_platform": "macosx64", 02:29:06 INFO - "basedir": "/builds/slave/test", 02:29:06 INFO - "nightly_build": "True", 02:29:06 INFO - "buildnumber": 60, 02:29:06 INFO - "slavename": "t-yosemite-r7-0237", 02:29:06 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:29:06 INFO - "platform": "yosemite_r7", 02:29:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:29:06 INFO - "branch": "mozilla-aurora", 02:29:06 INFO - "repo_path": "releases/mozilla-aurora", 02:29:06 INFO - "revision": "9de9a34b6793b36218032a215178cc7ddbd66c98", 02:29:06 INFO - "pgo_build": "False", 02:29:06 INFO - "builduid": "8613420ec01a430faa9125050cbb113a", 02:29:06 INFO - "slavebuilddir": "test" 02:29:06 INFO - } 02:29:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:29:06 INFO - Running post-action listener: _resource_record_post_action 02:29:06 INFO - [mozharness: 2016-12-08 10:29:06.502887Z] Finished read-buildbot-config step (success) 02:29:06 INFO - [mozharness: 2016-12-08 10:29:06.503021Z] Running download-and-extract step. 02:29:06 INFO - Running pre-action listener: _resource_record_pre_action 02:29:06 INFO - Running main action method: download_and_extract 02:29:06 INFO - mkdir: /builds/slave/test/build/tests 02:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:06 INFO - trying https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:29:06 INFO - Downloading https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/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 02:29:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/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 02:29:07 INFO - Downloaded 1199 bytes. 02:29:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:29:07 INFO - Using the following test package requirements: 02:29:07 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:29:07 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:29:07 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:29:07 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:29:07 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:29:07 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:29:07 INFO - u'jsshell-mac.zip'], 02:29:07 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:29:07 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:29:07 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:29:07 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:29:07 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:29:07 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:29:07 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:29:07 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:29:07 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:29:07 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:29:07 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:29:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:29:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:29:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:29:07 INFO - Fetch https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:29:08 INFO - Http code: 200 02:29:08 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 02:29:08 INFO - x-amz-cf-id: h9b4T8x4gq9wUWQmuPUqKSnf84g0t9pkF_9WgOSA5g8Z_Q-5RgNdRA== 02:29:08 INFO - x-amz-version-id: OGaBGWO9gU_DUnIiIdZrKchsjhaSYVuV 02:29:08 INFO - Expected file size: 16282003 02:29:08 INFO - Obtained file size: 16282003 02:29:15 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 02:29:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:29:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:29:15 INFO - Fetch https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:29:15 INFO - Http code: 200 02:29:15 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 02:29:15 INFO - x-amz-cf-id: 1Oj6A8hs1x1_WlRB1jwa7_-S2dRPiv7_4THSe6eCrSLuS6uAJTvKPQ== 02:29:15 INFO - x-amz-version-id: OGaBGWO9gU_DUnIiIdZrKchsjhaSYVuV 02:29:16 INFO - Expected file size: 16282003 02:29:16 INFO - Obtained file size: 16282003 02:29:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:29:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:29:22 INFO - Fetch https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:29:23 INFO - Http code: 200 02:29:23 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 02:29:23 INFO - x-amz-cf-id: jGq-LEtEl5vDXGZOL7MhChiAVpdbF6Qj6KH6QoCSN8nAkRyfy9hXOw== 02:29:23 INFO - x-amz-version-id: cJRFBg8sINuJl2dZ8QZNXV4VOaoIqsKV 02:29:23 INFO - Expected file size: 14409038 02:29:23 INFO - Obtained file size: 14409038 02:29: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')]} 02:29:24 INFO - trying https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:29:24 INFO - Downloading https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:29:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/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 02:29:27 INFO - Downloaded 109858954 bytes. 02:29:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:29:27 INFO - mkdir: /builds/slave/test/properties 02:29:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:29:27 INFO - Writing to file /builds/slave/test/properties/build_url 02:29:27 INFO - Contents: 02:29:27 INFO - build_url:https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:29:27 INFO - Running post-action listener: _resource_record_post_action 02:29:27 INFO - Running post-action listener: set_extra_try_arguments 02:29:27 INFO - [mozharness: 2016-12-08 10:29:27.787390Z] Finished download-and-extract step (success) 02:29:27 INFO - [mozharness: 2016-12-08 10:29:27.787523Z] Running populate-webroot step. 02:29:27 INFO - Running pre-action listener: _resource_record_pre_action 02:29:27 INFO - Running main action method: populate_webroot 02:29:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:29:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:29:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:29:27 INFO - '--tppagecycles', 02:29:27 INFO - '7'], 02:29:27 INFO - 'tests': ['tsvgm']}}, 02:29:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:29:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:29:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:29:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:29:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:27 INFO - 'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:27 INFO - 'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['damp', 'tps']}, 02:29:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:27 INFO - 'tests': ['damp', 'tps']}, 02:29:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['dromaeo_dom']}, 02:29:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:29:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['basic_compositor_video']}, 02:29:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:29:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['a11yr', 02:29:27 INFO - 'ts_paint', 02:29:27 INFO - 'tpaint', 02:29:27 INFO - 'sessionrestore', 02:29:27 INFO - 'sessionrestore_no_auto_restore', 02:29:27 INFO - 'tabpaint']}, 02:29:27 INFO - 'other-e10s': {'tests': ['a11yr', 02:29:27 INFO - 'ts_paint', 02:29:27 INFO - 'tpaint', 02:29:27 INFO - 'sessionrestore', 02:29:27 INFO - 'sessionrestore_no_auto_restore', 02:29:27 INFO - 'tabpaint']}, 02:29:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:29:27 INFO - 'ts_paint', 02:29:27 INFO - 'tpaint', 02:29:27 INFO - 'sessionrestore', 02:29:27 INFO - 'sessionrestore_no_auto_restore', 02:29:27 INFO - 'tabpaint']}, 02:29:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:29:27 INFO - 'ts_paint', 02:29:27 INFO - 'tpaint', 02:29:27 INFO - 'sessionrestore', 02:29:27 INFO - 'sessionrestore_no_auto_restore', 02:29:27 INFO - 'tabpaint']}, 02:29:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['a11yr', 02:29:27 INFO - 'ts_paint', 02:29:27 INFO - 'tpaint', 02:29:27 INFO - 'sessionrestore', 02:29:27 INFO - 'sessionrestore_no_auto_restore', 02:29:27 INFO - 'tabpaint']}, 02:29:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['a11yr', 02:29:27 INFO - 'ts_paint', 02:29:27 INFO - 'tpaint', 02:29:27 INFO - 'sessionrestore', 02:29:27 INFO - 'sessionrestore_no_auto_restore', 02:29:27 INFO - 'tabpaint']}, 02:29:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['tsvgx', 02:29:27 INFO - 'tsvgr_opacity', 02:29:27 INFO - 'tart', 02:29:27 INFO - 'tscrollx', 02:29:27 INFO - 'cart']}, 02:29:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:29:27 INFO - 'tsvgr_opacity', 02:29:27 INFO - 'tart', 02:29:27 INFO - 'tscrollx', 02:29:27 INFO - 'cart']}, 02:29:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:27 INFO - 'talos_options': ['--disable-e10s'], 02:29:27 INFO - 'tests': ['tp5o']}, 02:29:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:27 INFO - 'tests': ['tp5o']}, 02:29:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:27 INFO - 'talos_options': ['--disable-e10s', 02:29:27 INFO - '--xperf_path', 02:29:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:27 INFO - 'tests': ['tp5n']}, 02:29:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:27 INFO - 'talos_options': ['--xperf_path', 02:29:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:27 INFO - 'tests': ['tp5n']}}, 02:29:27 INFO - 'talos.zip': {'path': '', 02:29:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:29:27 INFO - Running post-action listener: _resource_record_post_action 02:29:27 INFO - [mozharness: 2016-12-08 10:29:27.806493Z] Finished populate-webroot step (success) 02:29:27 INFO - [mozharness: 2016-12-08 10:29:27.806625Z] Running create-virtualenv step. 02:29:27 INFO - Running pre-action listener: _resource_record_pre_action 02:29:27 INFO - Running main action method: create_virtualenv 02:29:27 INFO - Creating virtualenv /builds/slave/test/build/venv 02:29:27 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 02:29:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:29:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:29:27 INFO - Using real prefix '/tools/python27' 02:29:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:29:28 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:31 INFO - Installing pip.................done. 02:29:31 INFO - Return code: 0 02:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:29:31 INFO - Reading from file tmpfile_stdout 02:29:31 INFO - Output received: 02:29:31 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) 02:29:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29: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', 'pip>=1.5'] in /builds/slave/test/build 02:29: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 pip>=1.5 02:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:31 INFO - 'HOME': '/Users/cltbld', 02:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:31 INFO - 'LOGNAME': 'cltbld', 02:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:31 INFO - 'MOZ_NO_REMOTE': '1', 02:29:31 INFO - 'NO_EM_RESTART': '1', 02:29:31 INFO - 'PAGER': '/bin/cat', 02:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:31 INFO - 'PWD': '/builds/slave/test', 02:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:31 INFO - 'SHELL': '/bin/bash', 02:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:31 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:31 INFO - 'USER': 'cltbld', 02:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:31 INFO - 'XPC_FLAGS': '0x0', 02:29:31 INFO - 'XPC_SERVICE_NAME': '0', 02:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:32 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 02:29:32 INFO - Cleaning up... 02:29:32 INFO - Return code: 0 02:29:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:32 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:32 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 02:29:32 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 02:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:32 INFO - 'HOME': '/Users/cltbld', 02:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:32 INFO - 'LOGNAME': 'cltbld', 02:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:32 INFO - 'MOZ_NO_REMOTE': '1', 02:29:32 INFO - 'NO_EM_RESTART': '1', 02:29:32 INFO - 'PAGER': '/bin/cat', 02:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:32 INFO - 'PWD': '/builds/slave/test', 02:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:32 INFO - 'SHELL': '/bin/bash', 02:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:32 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:32 INFO - 'USER': 'cltbld', 02:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:32 INFO - 'XPC_FLAGS': '0x0', 02:29:32 INFO - 'XPC_SERVICE_NAME': '0', 02:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:32 INFO - Downloading/unpacking psutil>=3.1.1 02:29: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 02:29: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 02:29: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 02:29: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 02:29: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 02:29: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 02:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:36 INFO - Installing collected packages: psutil 02:29:36 INFO - Running setup.py install for psutil 02:29:36 INFO - building 'psutil._psutil_osx' extension 02:29:36 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 02:29:36 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 02:29:36 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 02:29:37 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 02:29:37 INFO - building 'psutil._psutil_posix' extension 02:29:37 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 02:29:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:29:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:29:37 INFO - ^ 02:29:37 INFO - 1 warning generated. 02:29:37 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 02:29:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:37 INFO - Successfully installed psutil 02:29:37 INFO - Cleaning up... 02:29:37 INFO - Return code: 0 02:29:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:29:37 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')]} 02:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:37 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')]} 02:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:37 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:37 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 02:29:37 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 02:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:37 INFO - 'HOME': '/Users/cltbld', 02:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:37 INFO - 'LOGNAME': 'cltbld', 02:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:37 INFO - 'MOZ_NO_REMOTE': '1', 02:29:37 INFO - 'NO_EM_RESTART': '1', 02:29:37 INFO - 'PAGER': '/bin/cat', 02:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:37 INFO - 'PWD': '/builds/slave/test', 02:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:37 INFO - 'SHELL': '/bin/bash', 02:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:37 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:37 INFO - 'USER': 'cltbld', 02:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:37 INFO - 'XPC_FLAGS': '0x0', 02:29:37 INFO - 'XPC_SERVICE_NAME': '0', 02:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:29:37 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 02:29:37 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 02:29:37 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 02:29:37 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 02:29:37 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 02:29:37 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 02:29:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:29:40 INFO - Installing collected packages: mozsystemmonitor 02:29:40 INFO - Running setup.py install for mozsystemmonitor 02:29:40 INFO - Successfully installed mozsystemmonitor 02:29:40 INFO - Cleaning up... 02:29:40 INFO - Return code: 0 02:29:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:29:40 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')]} 02:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:40 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')]} 02:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:40 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:40 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 02:29:40 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 02:29:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:40 INFO - 'HOME': '/Users/cltbld', 02:29:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:40 INFO - 'LOGNAME': 'cltbld', 02:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:40 INFO - 'MOZ_NO_REMOTE': '1', 02:29:40 INFO - 'NO_EM_RESTART': '1', 02:29:40 INFO - 'PAGER': '/bin/cat', 02:29:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:40 INFO - 'PWD': '/builds/slave/test', 02:29:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:40 INFO - 'SHELL': '/bin/bash', 02:29:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:40 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:40 INFO - 'USER': 'cltbld', 02:29:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:40 INFO - 'XPC_FLAGS': '0x0', 02:29:40 INFO - 'XPC_SERVICE_NAME': '0', 02:29:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:40 INFO - Downloading/unpacking jsonschema==2.5.1 02:29:40 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 02:29:40 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 02:29:40 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 02:29:40 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 02:29:40 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 02:29:40 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 02:29:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:43 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:43 INFO - warning: no files found matching '*.txt' 02:29:43 INFO - no previously-included directories found matching 'build' 02:29:43 INFO - no previously-included directories found matching 'dist' 02:29:43 INFO - no previously-included directories found matching '.git*' 02:29:43 INFO - Installing collected packages: jsonschema, functools32 02:29:43 INFO - Running setup.py install for functools32 02:29:43 INFO - warning: no files found matching '*.txt' 02:29:43 INFO - no previously-included directories found matching 'build' 02:29:43 INFO - no previously-included directories found matching 'dist' 02:29:43 INFO - no previously-included directories found matching '.git*' 02:29:44 INFO - Successfully installed jsonschema functools32 02:29:44 INFO - Cleaning up... 02:29:44 INFO - Return code: 0 02:29:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:29:44 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')]} 02:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:44 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')]} 02:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:44 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:44 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 02:29:44 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 02:29:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:44 INFO - 'HOME': '/Users/cltbld', 02:29:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:44 INFO - 'LOGNAME': 'cltbld', 02:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:44 INFO - 'MOZ_NO_REMOTE': '1', 02:29:44 INFO - 'NO_EM_RESTART': '1', 02:29:44 INFO - 'PAGER': '/bin/cat', 02:29:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:44 INFO - 'PWD': '/builds/slave/test', 02:29:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:44 INFO - 'SHELL': '/bin/bash', 02:29:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:44 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:44 INFO - 'USER': 'cltbld', 02:29:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:44 INFO - 'XPC_FLAGS': '0x0', 02:29:44 INFO - 'XPC_SERVICE_NAME': '0', 02:29:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:29:44 INFO - Cleaning up... 02:29:44 INFO - Return code: 0 02:29:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:44 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')]} 02:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:44 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')]} 02:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:44 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:44 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 02:29:44 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 02:29:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:44 INFO - 'HOME': '/Users/cltbld', 02:29:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:44 INFO - 'LOGNAME': 'cltbld', 02:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:44 INFO - 'MOZ_NO_REMOTE': '1', 02:29:44 INFO - 'NO_EM_RESTART': '1', 02:29:44 INFO - 'PAGER': '/bin/cat', 02:29:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:44 INFO - 'PWD': '/builds/slave/test', 02:29:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:44 INFO - 'SHELL': '/bin/bash', 02:29:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:44 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:44 INFO - 'USER': 'cltbld', 02:29:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:44 INFO - 'XPC_FLAGS': '0x0', 02:29:44 INFO - 'XPC_SERVICE_NAME': '0', 02:29:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:44 INFO - Downloading/unpacking blobuploader==1.2.4 02:29: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 02:29: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 02:29: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 02:29: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 02:29: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 02:29: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 02:29:47 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:47 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 02:29:47 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 02:29:47 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 02:29:47 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 02:29:47 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 02:29:47 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 02:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:47 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 02:29:47 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 02:29:47 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 02:29:47 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 02:29:47 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 02:29:47 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 02:29:48 INFO - Downloading docopt-0.6.1.tar.gz 02:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:48 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:48 INFO - Running setup.py install for blobuploader 02:29:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:48 INFO - Running setup.py install for requests 02:29:49 INFO - Running setup.py install for docopt 02:29:49 INFO - Successfully installed blobuploader requests docopt 02:29:49 INFO - Cleaning up... 02:29:49 INFO - Return code: 0 02:29:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:49 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')]} 02:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:49 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')]} 02:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:49 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:49 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 02:29:49 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 02:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:49 INFO - 'HOME': '/Users/cltbld', 02:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:49 INFO - 'LOGNAME': 'cltbld', 02:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:49 INFO - 'MOZ_NO_REMOTE': '1', 02:29:49 INFO - 'NO_EM_RESTART': '1', 02:29:49 INFO - 'PAGER': '/bin/cat', 02:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:49 INFO - 'PWD': '/builds/slave/test', 02:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:49 INFO - 'SHELL': '/bin/bash', 02:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:49 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:49 INFO - 'USER': 'cltbld', 02:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:49 INFO - 'XPC_FLAGS': '0x0', 02:29:49 INFO - 'XPC_SERVICE_NAME': '0', 02:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:49 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-d4nU4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:49 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-BI9XaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:49 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-FCaT9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:49 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-An7YJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-XnGbVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-rJw6ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-weYbcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-fuQSlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Shpx3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-DtO7oR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-NbxATP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-4fjxrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-QjJJSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:50 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-xbGhsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:51 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-tPdeXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:51 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-TsA6Ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:51 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-bmGPWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:51 INFO - Running setup.py install for manifestparser 02:29:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:51 INFO - Running setup.py install for mozcrash 02:29:51 INFO - Running setup.py install for mozdebug 02:29:51 INFO - Running setup.py install for mozdevice 02:29:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:29:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:51 INFO - Running setup.py install for mozfile 02:29:52 INFO - Running setup.py install for mozhttpd 02:29:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:52 INFO - Running setup.py install for mozinfo 02:29:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:52 INFO - Running setup.py install for mozInstall 02:29:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:52 INFO - Running setup.py install for mozleak 02:29:52 INFO - Running setup.py install for mozlog 02:29:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:52 INFO - Running setup.py install for moznetwork 02:29:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:52 INFO - Running setup.py install for mozprocess 02:29:53 INFO - Running setup.py install for mozprofile 02:29:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:53 INFO - Running setup.py install for mozrunner 02:29:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:53 INFO - Running setup.py install for mozscreenshot 02:29:53 INFO - Running setup.py install for moztest 02:29:53 INFO - Running setup.py install for mozversion 02:29:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:53 INFO - Cleaning up... 02:29:53 INFO - Return code: 0 02:29:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:53 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:53 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 02:29:53 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 02:29:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:53 INFO - 'HOME': '/Users/cltbld', 02:29:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:53 INFO - 'LOGNAME': 'cltbld', 02:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:53 INFO - 'MOZ_NO_REMOTE': '1', 02:29:53 INFO - 'NO_EM_RESTART': '1', 02:29:53 INFO - 'PAGER': '/bin/cat', 02:29:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:53 INFO - 'PWD': '/builds/slave/test', 02:29:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:53 INFO - 'SHELL': '/bin/bash', 02:29:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:53 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:53 INFO - 'USER': 'cltbld', 02:29:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:53 INFO - 'XPC_FLAGS': '0x0', 02:29:53 INFO - 'XPC_SERVICE_NAME': '0', 02:29:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:54 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-RkPRO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:54 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)) 02:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:54 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Sg6ak8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:54 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)) 02:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:54 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-5jXHyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:54 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)) 02:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:54 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-GmsWGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:54 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)) 02:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:54 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-TxH5C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:54 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)) 02:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:54 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-wMokmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:54 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)) 02:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:54 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-xp95XZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:54 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)) 02:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:54 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-jLtbm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:55 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)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-N_3zZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:55 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)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-r5tAS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:55 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)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-1a5eHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:55 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)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-wCnRj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:55 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)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-5D5QUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:55 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)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-LJUsi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:55 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)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-cuHEWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:55 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)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-2Yhl1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:55 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-MkpuL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:55 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)) 02:29:55 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)) 02:29:55 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)) 02:29:55 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)) 02:29:55 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)) 02:29:55 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)) 02:29:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:55 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 02:29:55 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 02:29:55 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 02:29:55 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 02:29:55 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 02:29:55 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 02:29:58 INFO - Downloading blessings-1.6.tar.gz 02:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:58 INFO - Installing collected packages: blessings 02:29:58 INFO - Running setup.py install for blessings 02:29:58 INFO - Successfully installed blessings 02:29:58 INFO - Cleaning up... 02:29:58 INFO - Return code: 0 02:29:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:59 INFO - Reading from file tmpfile_stdout 02:29:59 INFO - Current package versions: 02:29:59 INFO - blessings == 1.6 02:29:59 INFO - blobuploader == 1.2.4 02:29:59 INFO - docopt == 0.6.1 02:29:59 INFO - functools32 == 3.2.3-2 02:29:59 INFO - jsonschema == 2.5.1 02:29:59 INFO - manifestparser == 1.1 02:29:59 INFO - mozInstall == 1.12 02:29:59 INFO - mozcrash == 1.0 02:29:59 INFO - mozdebug == 0.1 02:29:59 INFO - mozdevice == 0.48 02:29:59 INFO - mozfile == 1.2 02:29:59 INFO - mozhttpd == 0.7 02:29:59 INFO - mozinfo == 0.9 02:29:59 INFO - mozleak == 0.1 02:29:59 INFO - mozlog == 3.4 02:29:59 INFO - moznetwork == 0.27 02:29:59 INFO - mozprocess == 0.23 02:29:59 INFO - mozprofile == 0.28 02:29:59 INFO - mozrunner == 6.13 02:29:59 INFO - mozscreenshot == 0.1 02:29:59 INFO - mozsystemmonitor == 0.3 02:29:59 INFO - moztest == 0.7 02:29:59 INFO - mozversion == 1.4 02:29:59 INFO - psutil == 3.1.1 02:29:59 INFO - requests == 1.2.3 02:29:59 INFO - wsgiref == 0.1.2 02:29:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:59 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')]} 02:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:59 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')]} 02:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:59 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:59 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 02:29:59 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 02:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:59 INFO - 'HOME': '/Users/cltbld', 02:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:59 INFO - 'LOGNAME': 'cltbld', 02:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:59 INFO - 'MOZ_NO_REMOTE': '1', 02:29:59 INFO - 'NO_EM_RESTART': '1', 02:29:59 INFO - 'PAGER': '/bin/cat', 02:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:59 INFO - 'PWD': '/builds/slave/test', 02:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:59 INFO - 'SHELL': '/bin/bash', 02:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:59 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:59 INFO - 'USER': 'cltbld', 02:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:59 INFO - 'XPC_FLAGS': '0x0', 02:29:59 INFO - 'XPC_SERVICE_NAME': '0', 02:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 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)) 02:29:59 INFO - Cleaning up... 02:29:59 INFO - Return code: 0 02:29:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:59 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')]} 02:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:59 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')]} 02:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:59 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 0x10d892490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d808570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b13d5c8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, '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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 02:29:59 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 02:29:59 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 02:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:59 INFO - 'HOME': '/Users/cltbld', 02:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:59 INFO - 'LOGNAME': 'cltbld', 02:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:59 INFO - 'MOZ_NO_REMOTE': '1', 02:29:59 INFO - 'NO_EM_RESTART': '1', 02:29:59 INFO - 'PAGER': '/bin/cat', 02:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:59 INFO - 'PWD': '/builds/slave/test', 02:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:59 INFO - 'SHELL': '/bin/bash', 02:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:29:59 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:59 INFO - 'USER': 'cltbld', 02:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:59 INFO - 'XPC_FLAGS': '0x0', 02:29:59 INFO - 'XPC_SERVICE_NAME': '0', 02:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:59 INFO - Cleaning up... 02:29:59 INFO - Return code: 0 02:29:59 INFO - Running post-action listener: _resource_record_post_action 02:29:59 INFO - Running post-action listener: _start_resource_monitoring 02:29:59 INFO - Starting resource monitoring. 02:29:59 INFO - [mozharness: 2016-12-08 10:29:59.777573Z] Finished create-virtualenv step (success) 02:29:59 INFO - [mozharness: 2016-12-08 10:29:59.778602Z] Running install step. 02:29:59 INFO - Running pre-action listener: _resource_record_pre_action 02:29:59 INFO - Running main action method: install 02:29:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:30:00 INFO - Reading from file tmpfile_stdout 02:30:00 INFO - Detecting whether we're running mozinstall >=1.0... 02:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:30:00 INFO - Reading from file tmpfile_stdout 02:30:00 INFO - Output received: 02:30:00 INFO - Usage: mozinstall [options] installer 02:30:00 INFO - Options: 02:30:00 INFO - -h, --help show this help message and exit 02:30:00 INFO - -d DEST, --destination=DEST 02:30:00 INFO - Directory to install application into. [default: 02:30:00 INFO - "/builds/slave/test"] 02:30:00 INFO - --app=APP Application being installed. [default: firefox] 02:30:00 INFO - mkdir: /builds/slave/test/build/application 02:30:00 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'] 02:30:00 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 02:30:31 INFO - Reading from file tmpfile_stdout 02:30:31 INFO - Output received: 02:30:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:30:31 INFO - Running post-action listener: _resource_record_post_action 02:30:31 INFO - [mozharness: 2016-12-08 10:30:31.062069Z] Finished install step (success) 02:30:31 INFO - [mozharness: 2016-12-08 10:30:31.062210Z] Running run-tests step. 02:30:31 INFO - Running pre-action listener: _resource_record_pre_action 02:30:31 INFO - Running main action method: run_tests 02:30:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:30:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:30:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:30:31 INFO - 2016-12-08 02:30:31.076 screenresolution[1632:8197] starting screenresolution argv=screenresolution get 02:30:31 INFO - 2016-12-08 02:30:31.079 screenresolution[1632:8197] Display 0: 1600x1200x32@60 02:30:31 INFO - 2016-12-08 02:30:31.084 screenresolution[1633:8199] starting screenresolution argv=screenresolution list 02:30:31 INFO - Available Modes on Display 0 02:30:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:30:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:30:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:30:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:30:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:30:31 INFO - Intel Iris: 02:30:31 INFO - Chipset Model: Intel Iris 02:30:31 INFO - Type: GPU 02:30:31 INFO - Bus: Built-In 02:30:31 INFO - VRAM (Dynamic, Max): 1536 MB 02:30:31 INFO - Vendor: Intel (0x8086) 02:30:31 INFO - Device ID: 0x0a2e 02:30:31 INFO - Revision ID: 0x0009 02:30:31 INFO - Displays: 02:30:31 INFO - Display: 02:30:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:30:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:30:31 INFO - Display Serial Number: 1600x1200 60 02:30:31 INFO - Main Display: Yes 02:30:31 INFO - Mirror: Off 02:30:31 INFO - Online: Yes 02:30:31 INFO - Rotation: Supported 02:30:31 INFO - Return code: 0 02:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:30:31 INFO - Python 2.7.3 02:30:31 INFO - Return code: 0 02:30:31 INFO - grabbing minidump binary from tooltool 02:30: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')]} 02:30:31 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 0x10d6a6d30>, '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 0x10d50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d69eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:30:31 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 02:30:31 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 02:30:31 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 02:30:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:30:31 INFO - Return code: 0 02:30:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:30:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:30:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:30:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:30:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:30:31 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/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--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 02:30:31 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/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0237 --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 02:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EvcyDWSkiL/Render', 02:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:31 INFO - 'HOME': '/Users/cltbld', 02:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:31 INFO - 'LOGNAME': 'cltbld', 02:30:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:30:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:31 INFO - 'MOZ_NO_REMOTE': '1', 02:30:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:30:31 INFO - 'NO_EM_RESTART': '1', 02:30:31 INFO - 'PAGER': '/bin/cat', 02:30:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:31 INFO - 'PWD': '/builds/slave/test', 02:30:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:31 INFO - 'SHELL': '/bin/bash', 02:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLCNwIAf5j/Listeners', 02:30:31 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 02:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:31 INFO - 'USER': 'cltbld', 02:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:31 INFO - 'XPC_FLAGS': '0x0', 02:30:31 INFO - 'XPC_SERVICE_NAME': '0', 02:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:31 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/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--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 02:30:31 INFO - mozversion application_buildid: 20161208004003 02:30:31 INFO - mozversion application_changeset: 9de9a34b6793b36218032a215178cc7ddbd66c98 02:30:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:30:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:30:31 INFO - mozversion application_name: Firefox 02:30:31 INFO - mozversion application_remotingname: firefox-dev 02:30:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:30:31 INFO - mozversion application_vendor: Mozilla 02:30:31 INFO - mozversion application_version: 52.0a2 02:30:31 INFO - mozversion platform_buildid: 20161208004003 02:30:31 INFO - mozversion platform_changeset: 9de9a34b6793b36218032a215178cc7ddbd66c98 02:30:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:30:31 INFO - mozversion platform_version: 52.0a2 02:30:31 INFO - using testdate: 1481193031 02:30:31 INFO - actual date: 1481193031 02:30:31 INFO - starting webserver on 'localhost:49245' 02:30:31 INFO - SUITE-START | Running 2 tests 02:30:31 INFO - TEST-START | dromaeo_css 02:30:31 INFO - operating with platform_type : mac_ 02:30:31 INFO - Initialising browser for dromaeo_css test... 02:30:31 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpboTSV8/profile http://localhost:49245/getInfo.html) 02:30:34 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 02:30:34 INFO - PROCESS | 1643 | colorDepth:24 02:30:34 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 02:30:34 INFO - PROCESS | 1643 | __metrics 02:30:34 INFO - PROCESS | 1643 | 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" 02:30:34 INFO - PROCESS | 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:30:35 INFO - TEST-INFO | 1643: exit 0 02:30:35 INFO - Browser initialized. 02:30:35 INFO - Running cycle 1/1 for dromaeo_css test... 02:30:35 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpboTSV8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:47 INFO - PROCESS | 1647 | RSS: Main: 222838784 02:30:47 INFO - PROCESS | 1647 | 02:31:39 INFO - PROCESS | 1647 | 1481193099364 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:33:53 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 02:33:55 INFO - PROCESS | 1647 | RSS: Main: 360943616 02:33:55 INFO - PROCESS | 1647 | 02:36:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 02:36:58 INFO - PROCESS | 1647 | RSS: Main: 347586560 02:36:58 INFO - PROCESS | 1647 | 02:40:00 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 02:40:01 INFO - PROCESS | 1647 | RSS: Main: 319406080 02:40:01 INFO - PROCESS | 1647 | 02:43:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 02:43:04 INFO - PROCESS | 1647 | RSS: Main: 318898176 02:43:04 INFO - PROCESS | 1647 | 02:46:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 02:46:08 INFO - PROCESS | 1647 | RSS: Main: 314249216 02:46:08 INFO - PROCESS | 1647 | 02:49:11 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 02:49:12 INFO - PROCESS | 1647 | RSS: Main: 415387648 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - PROCESS | 1647 | __start_tp_report 02:49:12 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 02:49:12 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 02:49:12 INFO - PROCESS | 1647 | |i|pagename|runs| 02:49:12 INFO - PROCESS | 1647 | |0;dojo.html;3325.251818340874;3332.2335862751565;3345.1469875181824;3345.230596962697;3399;5674.432556744328;5745.770169193227;5746.770129194832;5748.5401167906575;5762.855928601782;5701.57238207134;5749.59752817303;5757.712114394274;5763.740631671574;5788.5658575606785;5222.686638801665;5241.2400201970695;5244.5279924806855;5274.868128296785;5306.893862122755;4283.67872409569;5197.636165468419;5205.687658740469;5335.466453354672;5348.3849357323825;5233.2150177473495;5261.079311120558;5262.05283049051;5268.710220937847;5276;5297.258383826286;5309.33633295838;5326.813561525328;5340.62615616907;5346.518813306782;5224.007438586695;5265.420803711627;5297.443768404339;5305.071612467803;5332.226827110047;5623.409541998072;5625.043742563774;5661.433856614347;5662.745176467069;5695.45893140151;6149.569530132892;6253.906191407133;6255.530835187379;6305.779297724604;6321.273053598849;6338.556301058928;6357.586756860789;6368.5860419072615;6378.043293505965;6380.266269379034;5687.289088863892;5755.971220143956;5792.362840087587;5834.766609335621;5849.5027922626205;6297.149884765498;6312.368763123651;6321.589096708699;6329.810105696836;6332;6197.783077592263;6208.317085120633;6210.813675589739;6212.81361559154;6222.688865556704;6111.470193407285;6131.755408318012;6195.81412557615;6207.317195108534;6320.62076275425;6175.3515880831865;6246.312905580383;6257.655828929453;6259.123722678829;6266.310705822356;6210.161628180228;6244.219472565929;6280.434760871543;6280.748770049238;6299.055141728777;4300.65594752419;4789.425268967746;4885.658003939761;4941.530554597308;4952.702837829742;4378.408914796525;4453.198424283595;4468.798904049326;4474.843380481668;4489.573490518395;5792;5794.188813566104;5794.333651630032;5802.709864506758;5802.883942321215;6198.690065496708;6259.467945224706;6268.028455589391;6276.995680691068;6278.497720182466;5773.913391299134;5780.653160810365;5785.074388097968;5792.391798861143;5801.419858014165;4579.74811385377;5371.543418809445;5397.919031214535;5405;5419.132938729863;5190.532852043334;6245.68771561411;6313.842153946188;6323.52573556985;6345.777897773556;6249.500039996698;6327.367263273636;6331.176946996861;6337.017762246859;6339.746410143543;6213.316535181036;6340.175777149126;6363.5227357947415;6365.427111559982;6370.936290637034;6038.486728628116;6109.908351374644;6139.693015349126;6160.969195153995;6248.687565621792;6243.843903902439;6293.3391993841815;6310.463610592968;6310.905336420049;6334.3665633436285;6126.6017708848785;6131.662758548323;6134.04922237054;6138.447539721446;6138.785142519991;5875.882482350244;5944.821655350346;6038.094285857156;6065.969670151621;6076.969615151896;5840;5865;5871.2073870027;5893.381194974466;5911.970440147772;6150.169727086786;6150.569460137747;6175.320714721467;6176.567640265138;6183.690815459299;5912.846994836138;5959.731812068554;5972.163897054503;5976.641401515923;5987.251593550806 02:49:12 INFO - PROCESS | 1647 | |1;ext.html;447.8566858605246;467.61188213782543;487.8292597590846;490.07376059248;490.8318202677627;24207.152749653767;31051.68948310516;31061;31071.533926991084;31150.688493115063;39490.80254598723;40610.7969460153;40768.796156019176;42568.574314256846;42576.14847703052;9226.584803683834;9389.906100939006;9395.154436100749;9426.811463770755;9429.151376376125;9392.295577831655;9479;9481.09929556691;9495.762605934873;9509.952450237739;8015.95992020042;9495.43027418357;9534.904650953506;9539.80920381592;9546.856797148048;9416.387934784252;9496.525173741285;9497.620095196253;9510.38182518141;9537.237021038369;9258.213051890598;9319.627214911396;9330.160285574262;9339.486328251944;9354.204892584139;38473.84578462651;38749.806250968846;38818.611813881784;38829.8351049469;38863.222735545416;3341.298327351259;3400.387930172568;3494.8252587370785;3868.052327179859;4097.836086556535;25764.711764411706;25846.87076564624;27763.444731105265;29092.854535727398;29697.406051879057;2978.4787662159038;3030.818150910952;3084.2135255509925;3134.435801555719;3236.158598764315;482664;486603;488381;488423;489227;57749.422505774404;58279.4172058274;58303.708481457325;58335.70832145812;58477.415225848046;34129.658703413144;34967.475487867705;34986.300273994886;62657.68671156706;62883.6855815718;12770.29763363024;15147.394104235927;15514.913956023196;15531.378744850306;15541.06753594788;11846.289222646668;11847.111466640037;11853.22954008004;11856.288622682494;11960.40197990097;18594.535136621693;18685.813141868406;18691.15889784963;18691.813081869008;18807.435776926715;7524.774256772305;7640.235976402315;7660.1463341794615;7728.26581474759;7734.806629834186;832.2883934236141;969.8593703912893;970.7330484116783;974.6004138303261;977.6431602465049;24400;28420.289492762848;29146.125616231548;29704.257393565334;29939.401211975637;1002.7793885345212;1005.1606908231709;1043.8903915088958;1088.6353071721062;1101.0200921180156;14632.707345853236;15093.54719358421;15164.772528412082;15290.159041252839;15510.30203640839;638603;644026;644186.7790661016;644539;644627.7768611127;54396;54964.72517637386;55082.72458637761;55099;55102;1332.9200247985152;1361.4282001559368;1371;1371.6570857285678;1375.3192169875977;23773;26530.60204096979;28971;28998.56502152427;29272.853635731684;46664.30003549882;47026.52973470221;47082;47153.764231178626;47338;472166;475674;475987;476615;476643;25499;25689.87155064213;25949.870250648626;25974.48051038931;26030.73969260283;44691.32963005623;44873.55126448694;45152.871178220805;45249;45302.773486132355;35761.44442841305;36055.81972090123;36682.26635467329;37455;37668;36971.76784682487;37226.25547488981;37431.877043688735;37573.62426375701;37839.62160378361;31525;32255.838720806245;36155.09612259609;36801.81599091987;37066.8146659265;30951;32505.674943250266;32544.411205058197;32782.18044548905;33066.00801975944;24422.145224917043;24492.510149796548;24601.261962141165;24860.37849053788;24891.25326240216 02:49:12 INFO - PROCESS | 1647 | |2;jquery.html;345.7441493294962;361.67810648522817;370.69602925600987;377.4074702716735;397.6560275361811;13862.70256220571;14744.926275368622;18854.62290754184;19556.706649400254;20238.69641955372;19180;19385.903070484626;19613.21547138113;19670.606587868235;19719.1126399312;5223.843284701456;5862.208601838761;5922.170896074553;5925.051991681332;5934.851628709273;5424.891502169974;5721.713914304289;5766.9711651441685;5828.825135245949;5975.342712301644;5794.188813566104;5794.884102317951;5797.217375654296;5805.912911306334;5816.185733997244;5649.689267090308;5789.102689083199;5891.8527036824;5956.374580669054;5970.283565972111;4823.734694591796;5645.350784659735;5757.136429535562;5770.04794208955;5777.422257774231;22536.43658908524;23496.177633782736;23671;23843.284701458982;23959.88020059906;2157.503774131943;2350.3536527454917;2352.647102934556;2498.7876030537245;2721.0748345562406;10231.334963227366;10965.56137754489;12905.483780648763;14353.210573418457;15428.228588570639;1794.1298470241923;1815.5279627296998;1862.534366408398;1869.6354210928873;1888.1031510032624;103035;111311;114047;114096.42951785271;116457;11320.886791131983;11392;11489.655310340702;11556.88443115575;11708.473118709679;12547.24716517012;13286.601401957956;13298.800517992238;13430.26133562644;14311;8028.638711258008;8106.4325497215805;8255.894746839518;8334.12491688376;8358.665653373919;6656.267810540837;7163.212046674862;7397.926020739724;7542.924570754222;7543.698252069856;4448.55514448559;4504.842330518416;4562.1560011398;4587.311903214478;4633.62930965522;1882.9905850470934;1917.6835822089479;1919.1172060852164;1920.3470819921295;1951.638946794819;821.3839620284787;887.2414085956415;905.5200743605899;933.9579718912665;963.6579014449909;19605.90197049034;20347.287844925653;20430.795692043186;20633.58732825335;20841.478963025744;878.512425603796;888.8666699994923;921.9677311294207;922.2898368256405;933.8459154239474;8842.381033327605;10505.789884202273;10711.410872401937;10746.892531074589;10789.028987391173;587322;592760;596151;602996;609451;120493.19260211104;127438;129982;133513;137759;911.9361644684808;965.5172413793103;973.6056896956744;976.2043934193609;977.0180968127129;18158.364457243933;18622;18802.435926922215;18836.34072807445;18913.810861891205;37098.62901370952;40752.1849563013;49538.75230623968;53505;54051.72974135104;74936;76318;76892;77799.2220077792;77825.832612512;6066.939330606638;8018.438709290293;8109.189081091844;8127.390445716476;8134.87797683047;12148.392580371123;12392.132550721362;12495.562655307021;12647.620571382873;12889.8066529004;10670.679879603624;10835.945820270848;10917.12662986975;11127.944360278147;11165.776684466427;9179.495127768037;10127.430248311524;10260.948695256477;10948;10949.452527373758;10917.617883374043;11051;11586.246893951866;11587.652370428901;11649.41752912334;7298.744543940937;7378.926210738038;7409.851802964018;7680.692772289046;7710.11333696632;8117.594120293844;10802.837957430487;11036.337819730841;11118.833217501582;11162.162837787037 02:49:12 INFO - PROCESS | 1647 | |3;mootools.html;1308.1497026932498;1441.4954765831958;1478.356914742087;1617.2560622113826;1809.6109336492652;7361.631918404085;7716.305532502074;7902.367810575148;8260.173982601736;8264.83470330593;8097.109317975018;8130.390220733454;8189;8201.589920503966;8220.219079187467;2289.6679981402685;2319.536092781442;2323.7908588227056;2325.2559181062065;2326.9069237230574;2310.214527060799;2336.3224664847176;2358.8230882683783;2361.1145820317297;2362.976370236293;2287.531056133497;2295.047555264563;2325.1513197682893;2325.2559181062065;2325.825563082767;2271.829612779048;2278.3506700590333;2293.0827668932393;2295.494991101955;2298.1496846167024;2277.0664027748708;2297.483066310067;2302.4243939015246;2303.9078436862505;2306.169778879602;7999.720009799629;8118.228768267005;8155.632996515171;8214.83570328596;8258.793530161734;4966.726830024347;5166.250893620404;5169;5178.378594568639;5188.195829646411;2923.4153169366;3384.4246478098535;3496.230829217568;3567.02276961189;3587.641235876418;888.3958907942597;1056.6988408303596;1091.110744743032;1093.6875749101032;1100.6422912553453;10446.216533759998;10497.475126243657;10589.73525661857;10607.84088238677;10642.467876606217;4131.690123240769;4330.718503297276;4416.183006143874;4425.159219748238;4461.286194208911;8523.744287671256;8551.871721924052;8558.78603034929;8570.957145214234;8575.056743758181;5052.141136006888;5059.316992206079;5063.012712521023;5069.39167299919;5070.264811602371;4062.5734297898885;4234.809433575497;4354.150940566622;4397.164538737629;4761.642876784255;3576.3562558739154;3590.7666001710327;3657.9634203658156;3660.9816950915074;3672.0452682302794;1124.5389390349917;1133.4502766158407;1135.3131355529952;1144.1361771862191;1146.7294756382244;843.5360551696542;870.7561882672861;870.9738707838775;898.3172788680649;900.6352427266968;5439.211314359475;5591.832245032656;5598;5683.147527870769;5722.055860782924;949.7957939043001;983.8081574092984;995.0994350113159;997.5211898288717;1032.829583118792;3511.3328467591587;3534.858605655745;3711.740178187555;3724.497192878927;3775.1317197044286;9535.47554884474;10027.849582256273;10033.899661003297;10045.648402305884;10049.748756281151;7804.141544430108;9753.463559504153;9939.55272012761;9954.30319877616;10007.349522281029;7384.963075184697;7566.205548417447;7596.24037596247;7686.654100565488;7697.615119243904;5301.9469805303;5356.384015838228;5392.379876314274;5499.285092937893;5559.499645031967;8496.745097647081;9113.544322783702;9164.129407706258;9195.172434480928;9198.632054717737;6805.557638753465;7647.502912310682;7661.961690191513;9420.62317507292;9430.434173949585;7542.094948606203;7711.0746710395115;7713.80715482095;7728.033995750531;7733.8839917400155;7575.848483030199;7627.427942904415;7716.575588342471;7741.535507869546;7761.525310191046;7015.473839461959;7034.2965703429245;7044.36600705939;7187.748428804966;7196.568205907663;6914.0666010088;6928.341807528277;6948.513604047668;6953.756618518328;6994.790156295319;6503.447206987459;6503.804885853432;6531.804045878631;6584.967075164594;6587.341265873374;4474;4546.068056048568;4650.581447669726;4659.44086709597;4750.881227969328;4530.841420550265;4555.476120246214;4589.449266087957;4782.210935195653;4786.090642777861 02:49:12 INFO - PROCESS | 1647 | |4;prototype.html;305.3145687930595;305.65461029037186;306.50652449556213;312.6545167589812;314.70732219036313;4251.787410629468;5072.28987941688;5111.56551693106;5159.845204643857;5213.426523082458;3934.567197608261;4247.808848601812;5057.080762409135;5087.694738315704;5137.717425541603;3254.902352929416;3682.5396825396824;3839.36650452675;3886.358750806128;3916.8445308634023;3711.257748450307;3923.8234279457492;3948.8617898373564;3964.5638979712207;3998.1603863188766;3893.9415908761393;3903.906906066306;3907.199024200032;3911.628395302456;3929.9214015719667;3301.504774283853;3487.424574945131;3521.683048525645;3689.6494832990825;3775.6601905828607;3229.9677003229904;3407.926503151499;3411.3518431497937;3429.839201303163;3444.362792883325;4284.935725964113;4496.797644106023;4936.7531623419045;4941.950580494185;4969.428515720666;4090.2433049885867;4136.400221967798;4275.935860962057;4403.669724770655;4462.8884277892985;4656.95343046572;5311.707856067915;5332.280142180794;5335.1997200419855;5355.919661205085;3283.7865538740143;3731.533558305212;3765.548134223883;3787.299349620329;3796.4495148203355;4573;5577.60559860035;5598.692071936042;5609.709766753629;5624.662520248839;3692.833822477995;3724.8696295629525;3728.440733889884;3758.496601359478;3770.698344132463;3585.3367127081574;3607.981960090235;3790;3803.67668748154;3817.9427308590393;4413.977930110329;4432.778361081933;4433.1577000369825;4446.333050042519;4452.109578084396;4495.483019452739;4646.976765116153;5004.024215277986;5241.161414173714;5412.161115027177;1148.62095508482;1160.8275641601874;1194.1521519721096;1227.9570215042431;1242.658268976039;739.8076500109903;796.5579103597444;830.9958450207831;844.7170197983621;845.3406343052429;4835.758212089452;5018.648694591344;5029.5221953914315;5032.899342013139;5055.039542486916;4666.463356714022;5418.160185171305;5423.2136340230445;5441.401445840955;5464.551828842004;4025.959740402617;5090.287359769635;5105.974470127626;5123.154679477919;5162.6902385856965;2519.634652975308;2554.6678931738757;2753.284146122023;2760.6963234044642;2762.668479782439;4391.5827996340295;4691.5308469152815;4746.501617330199;5036.949630503648;5146.922796158061;4435.695406921864;4798.776312040407;4810.735409552508;5322.562908014892;5328.49379308965;5783.971080144573;5789.565782566325;5833.183354330397;5843.152742852262;5856.9121463178935;4913.533214344631;5189.81835635766;5197.896042079062;5241.240020197126;5590.832275031756;5188.455212202664;5194.3247377840635;5490.286262785972;5561.998840208801;5571.387147413862;5410.404855465974;5806.622569532967;5843.29880414353;5846.707664616837;5868.073246021464;3439.3491124260995;3531.8344946168218;3821.522309711286;3825.049224895268;3854.5952674969844;5374.838754837361;5435.402105768283;5606.691631960118;5668.518175954925;5742.59801813869;4505.5719706629125;5098.388193416666;5471.781128754965;5476.561875050066;5482.985647655197;4634.791434385528;5349.705766182898;5371.382291036581;5389.865253368697;5406.864828379322;4661.160991021513;4945.626099472345;5040.596752259884;5195.3246078009615;5366.221897824716;4762.214169686367;5141.8687888664435;5195.9735703075075;5335.786568537215;5517.393086760533;4366.956330436655;5069.391672999263;5283.10187268173;5284.260203571426;5293 02:49:12 INFO - PROCESS | 1647 | |5;yui.html;680.9148856392951;687.8830598798204;699.5208282326606;720.8161918710731;758.2569082299344;14036.228007459586;14421.927890360534;14428.855711442882;14461.20463374514;14475.203863787487;11546.191766576343;12744.299063551502;12875.742485150291;12877.162984406006;12882;635.2757856044113;651.0527182948798;773.404478551515;784.6273020315361;803.9598020099;804.3725893802844;806.8709006558952;807.935365170785;808.0787901791962;808.7816289601803;669.9631520266383;855.8716192571133;856.4518708026868;857.2242120880582;857.5112186053951;593.4095574902977;806.6329819931947;827.896512935883;831.8294749576322;837.8994520657499;612.9724162412703;614.4899733221415;614.529884638252;615.6952308607268;616.3836163836164;13569.52506662262;13884.09753366028;13939.233342166177;13942.302884855819;13958.302084895715;12648.873511264861;12696.17474864131;12705.745885082339;12712.555060572835;12802.295873726942;11114.22200445969;11389.715257118638;11623.418829058513;12078.758424831542;12434;4276.807543660543;4309;4416.933745993812;4417.889552761207;4418.9337159942625;74854;76002.6199868997;76099.23900760977;78294.21705782926;83589;9818.214542836557;10073.194144468425;10127.7468063299;10401.895981040245;10411.427371494641;18890.716639250422;19057;19082.236710531422;19191.136398862083;19391.903040484707;7169.713211471587;7198.7840364789145;7265.491415600857;7320.5607663539295;7409.222031686595;3979.303621866162;4018.8794336169963;4047.534533528682;4059.837606495766;4091.7135800494275;3180.061881744891;3272.3291725196277;3275.7706960512537;3275.852586633607;3277.5411442398085;554.9667019978733;596.1014169868981;626.0359047067465;641.8267067891736;642.210081599635;512.1600575056909;582.0047718401581;595.4105435618793;610.43229796289;611.2632939859451;7144.571325720473;7794.3764498839955;8519.616617252239;8603.655853765778;8607.78480537979;566.41376175658;577.9479846813723;603.9305479869784;628.5129025005566;656.4584218020152;3862.439946207784;4184.958150418457;4186.312259346665;4209.768462734579;4220.915581688349;76841.61579192069;89630.55184724164;106236;107211;107895.46052269689;63655.68172159109;67575;74272;89445;91401;76038.6198069006;79293.60353198196;79626.6018669903;82522.76215856767;83595.5820220895;8027.1972802719265;8095.554744488874;8101.675932962616;8170.795730106557;8285.668573257244;52255.73872130615;52407.47592524026;53415.73292133515;53696.46303536915;54793.72603136959;64785.67607161934;74254.62872685603;74941.62529187319;76231.61884190544;78392;10710.678679639623;10902.073323767569;11004.39475828837;11311;11442.885571144516;10343.94828025855;11245.887541124484;11930.701732456759;12118.575849845212;12212.87787122153;12091.334976576021;12267.386630668252;12292.201006934522;12394.81407778902;13460.25968571738;12624.936875315932;13371.799423008468;13460.394282257126;13522.985776066638;13526.391312391163;12292.631221063382;12692.873071269169;12914.935425322814;12959.352032398154;13160.670983225496;10576.524056417325;10616.416097114732;10831.891681083089;10860.891391085988;11098.61154859576;10417.739556511147;11000.22998390137;11127.609048868891;11138.88861111411;11150.498227579616 02:49:12 INFO - PROCESS | 1647 | __end_tp_report 02:49:12 INFO - PROCESS | 1647 | __start_cc_report 02:49:12 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1507 02:49:12 INFO - PROCESS | 1647 | __end_cc_report 02:49:12 INFO - PROCESS | 1647 | __startTimestamp1481194152128__endTimestamp 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - PROCESS | 1647 | ------- Summary: start ------- 02:49:12 INFO - PROCESS | 1647 | Number of tests: 6 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5755.62 Median:5912.85 stddev:662.07 (11.2%) stddev-sans-first:636.05 02:49:12 INFO - PROCESS | 1647 | Values: 3325.3 3332.2 3345.1 3345.2 3399.0 5674.4 5745.8 5746.8 5748.5 5762.9 5701.6 5749.6 5757.7 5763.7 5788.6 5222.7 5241.2 5244.5 5274.9 5306.9 4283.7 5197.6 5205.7 5335.5 5348.4 5233.2 5261.1 5262.1 5268.7 5276.0 5297.3 5309.3 5326.8 5340.6 5346.5 5224.0 5265.4 5297.4 5305.1 5332.2 5623.4 5625.0 5661.4 5662.7 5695.5 6149.6 6253.9 6255.5 6305.8 6321.3 6338.6 6357.6 6368.6 6378.0 6380.3 5687.3 5756.0 5792.4 5834.8 5849.5 6297.1 6312.4 6321.6 6329.8 6332.0 6197.8 6208.3 6210.8 6212.8 6222.7 6111.5 6131.8 6195.8 6207.3 6320.6 6175.4 6246.3 6257.7 6259.1 6266.3 6210.2 6244.2 6280.4 6280.7 6299.1 4300.7 4789.4 4885.7 4941.5 4952.7 4378.4 4453.2 4468.8 4474.8 4489.6 5792.0 5794.2 5794.3 5802.7 5802.9 6198.7 6259.5 6268.0 6277.0 6278.5 5773.9 5780.7 5785.1 5792.4 5801.4 4579.7 5371.5 5397.9 5405.0 5419.1 5190.5 6245.7 6313.8 6323.5 6345.8 6249.5 6327.4 6331.2 6337.0 6339.7 6213.3 6340.2 6363.5 6365.4 6370.9 6038.5 6109.9 6139.7 6161.0 6248.7 6243.8 6293.3 6310.5 6310.9 6334.4 6126.6 6131.7 6134.0 6138.4 6138.8 5875.9 5944.8 6038.1 6066.0 6077.0 5840.0 5865.0 5871.2 5893.4 5912.0 6150.2 6150.6 6175.3 6176.6 6183.7 5912.8 5959.7 5972.2 5976.6 5987.3 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:65762.37 Median:38156.73 stddev:144586.12 (378.9%) stddev-sans-first:144908.56 02:49:12 INFO - PROCESS | 1647 | Values: 447.9 467.6 487.8 490.1 490.8 24207.2 31051.7 31061.0 31071.5 31150.7 39490.8 40610.8 40768.8 42568.6 42576.1 9226.6 9389.9 9395.2 9426.8 9429.2 9392.3 9479.0 9481.1 9495.8 9510.0 8016.0 9495.4 9534.9 9539.8 9546.9 9416.4 9496.5 9497.6 9510.4 9537.2 9258.2 9319.6 9330.2 9339.5 9354.2 38473.8 38749.8 38818.6 38829.8 38863.2 3341.3 3400.4 3494.8 3868.1 4097.8 25764.7 25846.9 27763.4 29092.9 29697.4 2978.5 3030.8 3084.2 3134.4 3236.2 482664.0 486603.0 488381.0 488423.0 489227.0 57749.4 58279.4 58303.7 58335.7 58477.4 34129.7 34967.5 34986.3 62657.7 62883.7 12770.3 15147.4 15514.9 15531.4 15541.1 11846.3 11847.1 11853.2 11856.3 11960.4 18594.5 18685.8 18691.2 18691.8 18807.4 7524.8 7640.2 7660.1 7728.3 7734.8 832.3 969.9 970.7 974.6 977.6 24400.0 28420.3 29146.1 29704.3 29939.4 1002.8 1005.2 1043.9 1088.6 1101.0 14632.7 15093.5 15164.8 15290.2 15510.3 638603.0 644026.0 644186.8 644539.0 644627.8 54396.0 54964.7 55082.7 55099.0 55102.0 1332.9 1361.4 1371.0 1371.7 1375.3 23773.0 26530.6 28971.0 28998.6 29272.9 46664.3 47026.5 47082.0 47153.8 47338.0 472166.0 475674.0 475987.0 476615.0 476643.0 25499.0 25689.9 25949.9 25974.5 26030.7 44691.3 44873.6 45152.9 45249.0 45302.8 35761.4 36055.8 36682.3 37455.0 37668.0 36971.8 37226.3 37431.9 37573.6 37839.6 31525.0 32255.8 36155.1 36801.8 37066.8 30951.0 32505.7 32544.4 32782.2 33066.0 24422.1 24492.5 24601.3 24860.4 24891.3 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:34427.57 Median:2609.93 stddev:99705.06 (3820.2%) stddev-sans-first:99951.92 02:49:12 INFO - PROCESS | 1647 | Values: 345.7 361.7 370.7 377.4 397.7 13862.7 14744.9 18854.6 19556.7 20238.7 19180.0 19385.9 19613.2 19670.6 19719.1 5223.8 5862.2 5922.2 5925.1 5934.9 5424.9 5721.7 5767.0 5828.8 5975.3 5794.2 5794.9 5797.2 5805.9 5816.2 5649.7 5789.1 5891.9 5956.4 5970.3 4823.7 5645.4 5757.1 5770.0 5777.4 22536.4 23496.2 23671.0 23843.3 23959.9 2157.5 2350.4 2352.6 2498.8 2721.1 10231.3 10965.6 12905.5 14353.2 15428.2 1794.1 1815.5 1862.5 1869.6 1888.1 103035.0 111311.0 114047.0 114096.4 116457.0 11320.9 11392.0 11489.7 11556.9 11708.5 12547.2 13286.6 13298.8 13430.3 14311.0 8028.6 8106.4 8255.9 8334.1 8358.7 6656.3 7163.2 7397.9 7542.9 7543.7 4448.6 4504.8 4562.2 4587.3 4633.6 1883.0 1917.7 1919.1 1920.3 1951.6 821.4 887.2 905.5 934.0 963.7 19605.9 20347.3 20430.8 20633.6 20841.5 878.5 888.9 922.0 922.3 933.8 8842.4 10505.8 10711.4 10746.9 10789.0 587322.0 592760.0 596151.0 602996.0 609451.0 120493.2 127438.0 129982.0 133513.0 137759.0 911.9 965.5 973.6 976.2 977.0 18158.4 18622.0 18802.4 18836.3 18913.8 37098.6 40752.2 49538.8 53505.0 54051.7 74936.0 76318.0 76892.0 77799.2 77825.8 6066.9 8018.4 8109.2 8127.4 8134.9 12148.4 12392.1 12495.6 12647.6 12889.8 10670.7 10835.9 10917.1 11127.9 11165.8 9179.5 10127.4 10260.9 10948.0 10949.5 10917.6 11051.0 11586.2 11587.7 11649.4 7298.7 7378.9 7409.9 7680.7 7710.1 8117.6 10802.8 11036.3 11118.8 11162.2 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5293.14 Median:5118.26 stddev:2863.60 (55.9%) stddev-sans-first:2855.97 02:49:12 INFO - PROCESS | 1647 | Values: 1308.1 1441.5 1478.4 1617.3 1809.6 7361.6 7716.3 7902.4 8260.2 8264.8 8097.1 8130.4 8189.0 8201.6 8220.2 2289.7 2319.5 2323.8 2325.3 2326.9 2310.2 2336.3 2358.8 2361.1 2363.0 2287.5 2295.0 2325.2 2325.3 2325.8 2271.8 2278.4 2293.1 2295.5 2298.1 2277.1 2297.5 2302.4 2303.9 2306.2 7999.7 8118.2 8155.6 8214.8 8258.8 4966.7 5166.3 5169.0 5178.4 5188.2 2923.4 3384.4 3496.2 3567.0 3587.6 888.4 1056.7 1091.1 1093.7 1100.6 10446.2 10497.5 10589.7 10607.8 10642.5 4131.7 4330.7 4416.2 4425.2 4461.3 8523.7 8551.9 8558.8 8571.0 8575.1 5052.1 5059.3 5063.0 5069.4 5070.3 4062.6 4234.8 4354.2 4397.2 4761.6 3576.4 3590.8 3658.0 3661.0 3672.0 1124.5 1133.5 1135.3 1144.1 1146.7 843.5 870.8 871.0 898.3 900.6 5439.2 5591.8 5598.0 5683.1 5722.1 949.8 983.8 995.1 997.5 1032.8 3511.3 3534.9 3711.7 3724.5 3775.1 9535.5 10027.8 10033.9 10045.6 10049.7 7804.1 9753.5 9939.6 9954.3 10007.3 7385.0 7566.2 7596.2 7686.7 7697.6 5301.9 5356.4 5392.4 5499.3 5559.5 8496.7 9113.5 9164.1 9195.2 9198.6 6805.6 7647.5 7662.0 9420.6 9430.4 7542.1 7711.1 7713.8 7728.0 7733.9 7575.8 7627.4 7716.6 7741.5 7761.5 7015.5 7034.3 7044.4 7187.7 7196.6 6914.1 6928.3 6948.5 6953.8 6994.8 6503.4 6503.8 6531.8 6585.0 6587.3 4474.0 4546.1 4650.6 4659.4 4750.9 4530.8 4555.5 4589.4 4782.2 4786.1 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4315.24 Median:4804.76 stddev:1330.74 (27.7%) stddev-sans-first:1299.99 02:49:12 INFO - PROCESS | 1647 | Values: 305.3 305.7 306.5 312.7 314.7 4251.8 5072.3 5111.6 5159.8 5213.4 3934.6 4247.8 5057.1 5087.7 5137.7 3254.9 3682.5 3839.4 3886.4 3916.8 3711.3 3923.8 3948.9 3964.6 3998.2 3893.9 3903.9 3907.2 3911.6 3929.9 3301.5 3487.4 3521.7 3689.6 3775.7 3230.0 3407.9 3411.4 3429.8 3444.4 4284.9 4496.8 4936.8 4942.0 4969.4 4090.2 4136.4 4275.9 4403.7 4462.9 4657.0 5311.7 5332.3 5335.2 5355.9 3283.8 3731.5 3765.5 3787.3 3796.4 4573.0 5577.6 5598.7 5609.7 5624.7 3692.8 3724.9 3728.4 3758.5 3770.7 3585.3 3608.0 3790.0 3803.7 3817.9 4414.0 4432.8 4433.2 4446.3 4452.1 4495.5 4647.0 5004.0 5241.2 5412.2 1148.6 1160.8 1194.2 1228.0 1242.7 739.8 796.6 831.0 844.7 845.3 4835.8 5018.6 5029.5 5032.9 5055.0 4666.5 5418.2 5423.2 5441.4 5464.6 4026.0 5090.3 5106.0 5123.2 5162.7 2519.6 2554.7 2753.3 2760.7 2762.7 4391.6 4691.5 4746.5 5036.9 5146.9 4435.7 4798.8 4810.7 5322.6 5328.5 5784.0 5789.6 5833.2 5843.2 5856.9 4913.5 5189.8 5197.9 5241.2 5590.8 5188.5 5194.3 5490.3 5562.0 5571.4 5410.4 5806.6 5843.3 5846.7 5868.1 3439.3 3531.8 3821.5 3825.0 3854.6 5374.8 5435.4 5606.7 5668.5 5742.6 4505.6 5098.4 5471.8 5476.6 5483.0 4634.8 5349.7 5371.4 5389.9 5406.9 4661.2 4945.6 5040.6 5195.3 5366.2 4762.2 5141.9 5196.0 5335.8 5517.4 4367.0 5069.4 5283.1 5284.3 5293.0 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:18972.37 Median:4418.41 stddev:27042.87 (612.0%) stddev-sans-first:27083.86 02:49:12 INFO - PROCESS | 1647 | Values: 680.9 687.9 699.5 720.8 758.3 14036.2 14421.9 14428.9 14461.2 14475.2 11546.2 12744.3 12875.7 12877.2 12882.0 635.3 651.1 773.4 784.6 804.0 804.4 806.9 807.9 808.1 808.8 670.0 855.9 856.5 857.2 857.5 593.4 806.6 827.9 831.8 837.9 613.0 614.5 614.5 615.7 616.4 13569.5 13884.1 13939.2 13942.3 13958.3 12648.9 12696.2 12705.7 12712.6 12802.3 11114.2 11389.7 11623.4 12078.8 12434.0 4276.8 4309.0 4416.9 4417.9 4418.9 74854.0 76002.6 76099.2 78294.2 83589.0 9818.2 10073.2 10127.7 10401.9 10411.4 18890.7 19057.0 19082.2 19191.1 19391.9 7169.7 7198.8 7265.5 7320.6 7409.2 3979.3 4018.9 4047.5 4059.8 4091.7 3180.1 3272.3 3275.8 3275.9 3277.5 555.0 596.1 626.0 641.8 642.2 512.2 582.0 595.4 610.4 611.3 7144.6 7794.4 8519.6 8603.7 8607.8 566.4 577.9 603.9 628.5 656.5 3862.4 4185.0 4186.3 4209.8 4220.9 76841.6 89630.6 106236.0 107211.0 107895.5 63655.7 67575.0 74272.0 89445.0 91401.0 76038.6 79293.6 79626.6 82522.8 83595.6 8027.2 8095.6 8101.7 8170.8 8285.7 52255.7 52407.5 53415.7 53696.5 54793.7 64785.7 74254.6 74941.6 76231.6 78392.0 10710.7 10902.1 11004.4 11311.0 11442.9 10343.9 11245.9 11930.7 12118.6 12212.9 12091.3 12267.4 12292.2 12394.8 13460.3 12624.9 13371.8 13460.4 13523.0 13526.4 12292.6 12692.9 12914.9 12959.4 13160.7 10576.5 10616.4 10831.9 10860.9 11098.6 10417.7 11000.2 11127.6 11138.9 11150.5 02:49:12 INFO - PROCESS | 1647 | -------- Summary: end -------- 02:49:12 INFO - PROCESS | 1647 | 02:49:12 INFO - TEST-INFO | 1647: exit 0 02:49:12 INFO - TEST-OK | dromaeo_css | took 1121095ms 02:49:12 INFO - TEST-START | kraken 02:49:12 INFO - operating with platform_type : mac_ 02:49:12 INFO - Initialising browser for kraken test... 02:49:12 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpB_90bQ/profile http://localhost:49245/getInfo.html) 02:49:15 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 02:49:15 INFO - PROCESS | 1653 | colorDepth:24 02:49:15 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 02:49:15 INFO - PROCESS | 1653 | __metrics 02:49:15 INFO - PROCESS | 1653 | 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" 02:49:15 INFO - PROCESS | 1653 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:49:16 INFO - TEST-INFO | 1653: exit 0 02:49:16 INFO - Browser initialized. 02:49:16 INFO - Running cycle 1/1 for kraken test... 02:49:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpB_90bQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:28 INFO - PROCESS | 1655 | RSS: Main: 222593024 02:49:28 INFO - PROCESS | 1655 | 02:49:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 02:49:32 INFO - PROCESS | 1655 | RSS: Main: 273735680 02:49:32 INFO - PROCESS | 1655 | 02:49:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 02:49:35 INFO - PROCESS | 1655 | RSS: Main: 284471296 02:49:35 INFO - PROCESS | 1655 | 02:49:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 02:49:39 INFO - PROCESS | 1655 | RSS: Main: 380354560 02:49:39 INFO - PROCESS | 1655 | 02:49:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 02:49:42 INFO - PROCESS | 1655 | RSS: Main: 283471872 02:49:42 INFO - PROCESS | 1655 | 02:49:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:49:44 INFO - PROCESS | 1655 | RSS: Main: 284381184 02:49:44 INFO - PROCESS | 1655 | 02:49:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 02:49:48 INFO - PROCESS | 1655 | RSS: Main: 325185536 02:49:48 INFO - PROCESS | 1655 | 02:49:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 02:49:51 INFO - PROCESS | 1655 | RSS: Main: 326758400 02:49:51 INFO - PROCESS | 1655 | 02:49:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 02:49:54 INFO - PROCESS | 1655 | RSS: Main: 322789376 02:49:54 INFO - PROCESS | 1655 | 02:49:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:57 INFO - PROCESS | 1655 | RSS: Main: 297885696 02:49:57 INFO - PROCESS | 1655 | 02:49:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:59 INFO - PROCESS | 1655 | RSS: Main: 303411200 02:49:59 INFO - PROCESS | 1655 | 02:50:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:50:02 INFO - PROCESS | 1655 | RSS: Main: 305369088 02:50:02 INFO - PROCESS | 1655 | 02:50:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:50:06 INFO - PROCESS | 1655 | RSS: Main: 315539456 02:50:06 INFO - PROCESS | 1655 | 02:50:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:50:09 INFO - PROCESS | 1655 | RSS: Main: 315998208 02:50:09 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 02:50:11 INFO - PROCESS | 1655 | RSS: Main: 359374848 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | __start_tp_report 02:50:11 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 02:50:11 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 02:50:11 INFO - PROCESS | 1655 | |i|pagename|runs| 02:50:11 INFO - PROCESS | 1655 | |0;ai-astar;115;124;172;115;113;115;114;113;113;113 02:50:11 INFO - PROCESS | 1655 | |1;audio-beat-detection;152;116;118;154;133;143;114;113;145;119 02:50:11 INFO - PROCESS | 1655 | |2;audio-dft;183;182;184;181;181;181;182;183;183;185 02:50:11 INFO - PROCESS | 1655 | |3;audio-fft;81;58;58;89;60;58;60;59;82;58 02:50:11 INFO - PROCESS | 1655 | |4;audio-oscillator;83;71;94;70;69;69;89;69;69;91 02:50:11 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;86;87;86;88;85;89;85;88;85 02:50:11 INFO - PROCESS | 1655 | |6;imaging-darkroom;94;95;94;95;93;95;95;95;94;94 02:50:11 INFO - PROCESS | 1655 | |7;imaging-desaturate;84;81;82;80;83;82;84;83;85;83 02:50:11 INFO - PROCESS | 1655 | |8;json-parse-financial;55;55;57;56;58;59;59;58;58;58 02:50:11 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;65;75;73;80;73;71;69;71;69;70 02:50:11 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;84;86;85;83;84;84;85;86;86 02:50:11 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;156;150;154;153;144;149;152;149;192;167 02:50:11 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;168;167;172;178;171;170;173;170;172;169 02:50:11 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;71;69;69;70;73;82;86;87;78;68 02:50:11 INFO - PROCESS | 1655 | __end_tp_report 02:50:11 INFO - PROCESS | 1655 | __start_cc_report 02:50:11 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2035 02:50:11 INFO - PROCESS | 1655 | __end_cc_report 02:50:11 INFO - PROCESS | 1655 | __startTimestamp1481194211659__endTimestamp 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | ------- Summary: start ------- 02:50:11 INFO - PROCESS | 1655 | Number of tests: 14 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:120.70 Median:115.00 stddev:18.32 (15.9%) stddev-sans-first:19.32 02:50:11 INFO - PROCESS | 1655 | Values: 115.0 124.0 172.0 115.0 113.0 115.0 114.0 113.0 113.0 113.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:138.00 stddev:16.55 (12.0%) stddev-sans-first:15.65 02:50:11 INFO - PROCESS | 1655 | Values: 152.0 116.0 118.0 154.0 133.0 143.0 114.0 113.0 145.0 119.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:1.35 (0.7%) stddev-sans-first:1.42 02:50:11 INFO - PROCESS | 1655 | Values: 183.0 182.0 184.0 181.0 181.0 181.0 182.0 183.0 183.0 185.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:66.30 Median:60.00 stddev:12.41 (20.7%) stddev-sans-first:11.97 02:50:11 INFO - PROCESS | 1655 | Values: 81.0 58.0 58.0 89.0 60.0 58.0 60.0 59.0 82.0 58.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.00 stddev:10.56 (13.7%) stddev-sans-first:11.01 02:50:11 INFO - PROCESS | 1655 | Values: 83.0 71.0 94.0 70.0 69.0 69.0 89.0 69.0 69.0 91.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:1.49 (1.7%) stddev-sans-first:1.51 02:50:11 INFO - PROCESS | 1655 | Values: 88.0 86.0 87.0 86.0 88.0 85.0 89.0 85.0 88.0 85.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 02:50:11 INFO - PROCESS | 1655 | Values: 94.0 95.0 94.0 95.0 93.0 95.0 95.0 95.0 94.0 94.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:82.70 Median:83.00 stddev:1.49 (1.8%) stddev-sans-first:1.51 02:50:11 INFO - PROCESS | 1655 | Values: 84.0 81.0 82.0 80.0 83.0 82.0 84.0 83.0 85.0 83.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:1.49 (2.6%) stddev-sans-first:1.33 02:50:11 INFO - PROCESS | 1655 | Values: 55.0 55.0 57.0 56.0 58.0 59.0 59.0 58.0 58.0 58.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:4.03 (5.6%) stddev-sans-first:3.50 02:50:11 INFO - PROCESS | 1655 | Values: 65.0 75.0 73.0 80.0 73.0 71.0 69.0 71.0 69.0 70.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.50 stddev:1.10 (1.3%) stddev-sans-first:1.09 02:50:11 INFO - PROCESS | 1655 | Values: 86.0 84.0 86.0 85.0 83.0 84.0 84.0 85.0 86.0 86.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:156.60 Median:153.50 stddev:13.83 (9.0%) stddev-sans-first:14.66 02:50:11 INFO - PROCESS | 1655 | Values: 156.0 150.0 154.0 153.0 144.0 149.0 152.0 149.0 192.0 167.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:171.50 stddev:3.09 (1.8%) stddev-sans-first:3.08 02:50:11 INFO - PROCESS | 1655 | Values: 168.0 167.0 172.0 178.0 171.0 170.0 173.0 170.0 172.0 169.0 02:50:11 INFO - PROCESS | 1655 | 02:50:11 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:75.50 stddev:7.36 (9.8%) stddev-sans-first:7.64 02:50:11 INFO - PROCESS | 1655 | Values: 71.0 69.0 69.0 70.0 73.0 82.0 86.0 87.0 78.0 68.0 02:50:11 INFO - PROCESS | 1655 | -------- Summary: end -------- 02:50:11 INFO - PROCESS | 1655 | 02:50:12 INFO - TEST-INFO | 1655: exit 0 02:50:12 INFO - TEST-OK | kraken | took 59942ms 02:50:12 INFO - SUITE-END | took 1181s 02:50:12 INFO - Completed test suite (00:19:41) 02:50:12 INFO - javascript benchmark 02:50:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.251818340874, 3332.2335862751565, 3345.1469875181824, 3345.230596962697, 3399.0, 5674.432556744328, 5745.770169193227, 5746.770129194832, 5748.5401167906575, 5762.855928601782, 5701.57238207134, 5749.59752817303, 5757.712114394274, 5763.740631671574, 5788.5658575606785, 5222.686638801665, 5241.2400201970695, 5244.5279924806855, 5274.868128296785, 5306.893862122755, 4283.67872409569, 5197.636165468419, 5205.687658740469, 5335.466453354672, 5348.3849357323825, 5233.2150177473495, 5261.079311120558, 5262.05283049051, 5268.710220937847, 5276.0, 5297.258383826286, 5309.33633295838, 5326.813561525328, 5340.62615616907, 5346.518813306782, 5224.007438586695, 5265.420803711627, 5297.443768404339, 5305.071612467803, 5332.226827110047, 5623.409541998072, 5625.043742563774, 5661.433856614347, 5662.745176467069, 5695.45893140151, 6149.569530132892, 6253.906191407133, 6255.530835187379, 6305.779297724604, 6321.273053598849, 6338.556301058928, 6357.586756860789, 6368.5860419072615, 6378.043293505965, 6380.266269379034, 5687.289088863892, 5755.971220143956, 5792.362840087587, 5834.766609335621, 5849.5027922626205, 6297.149884765498, 6312.368763123651, 6321.589096708699, 6329.810105696836, 6332.0, 6197.783077592263, 6208.317085120633, 6210.813675589739, 6212.81361559154, 6222.688865556704, 6111.470193407285, 6131.755408318012, 6195.81412557615, 6207.317195108534, 6320.62076275425, 6175.3515880831865, 6246.312905580383, 6257.655828929453, 6259.123722678829, 6266.310705822356, 6210.161628180228, 6244.219472565929, 6280.434760871543, 6280.748770049238, 6299.055141728777, 4300.65594752419, 4789.425268967746, 4885.658003939761, 4941.530554597308, 4952.702837829742, 4378.408914796525, 4453.198424283595, 4468.798904049326, 4474.843380481668, 4489.573490518395, 5792.0, 5794.188813566104, 5794.333651630032, 5802.709864506758, 5802.883942321215, 6198.690065496708, 6259.467945224706, 6268.028455589391, 6276.995680691068, 6278.497720182466, 5773.913391299134, 5780.653160810365, 5785.074388097968, 5792.391798861143, 5801.419858014165, 4579.74811385377, 5371.543418809445, 5397.919031214535, 5405.0, 5419.132938729863, 5190.532852043334, 6245.68771561411, 6313.842153946188, 6323.52573556985, 6345.777897773556, 6249.500039996698, 6327.367263273636, 6331.176946996861, 6337.017762246859, 6339.746410143543, 6213.316535181036, 6340.175777149126, 6363.5227357947415, 6365.427111559982, 6370.936290637034, 6038.486728628116, 6109.908351374644, 6139.693015349126, 6160.969195153995, 6248.687565621792, 6243.843903902439, 6293.3391993841815, 6310.463610592968, 6310.905336420049, 6334.3665633436285, 6126.6017708848785, 6131.662758548323, 6134.04922237054, 6138.447539721446, 6138.785142519991, 5875.882482350244, 5944.821655350346, 6038.094285857156, 6065.969670151621, 6076.969615151896, 5840.0, 5865.0, 5871.2073870027, 5893.381194974466, 5911.970440147772, 6150.169727086786, 6150.569460137747, 6175.320714721467, 6176.567640265138, 6183.690815459299, 5912.846994836138, 5959.731812068554, 5972.163897054503, 5976.641401515923, 5987.251593550806], "value": 5712.266087915785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.8566858605246, 467.61188213782543, 487.8292597590846, 490.07376059248, 490.8318202677627, 24207.152749653767, 31051.68948310516, 31061.0, 31071.533926991084, 31150.688493115063, 39490.80254598723, 40610.7969460153, 40768.796156019176, 42568.574314256846, 42576.14847703052, 9226.584803683834, 9389.906100939006, 9395.154436100749, 9426.811463770755, 9429.151376376125, 9392.295577831655, 9479.0, 9481.09929556691, 9495.762605934873, 9509.952450237739, 8015.95992020042, 9495.43027418357, 9534.904650953506, 9539.80920381592, 9546.856797148048, 9416.387934784252, 9496.525173741285, 9497.620095196253, 9510.38182518141, 9537.237021038369, 9258.213051890598, 9319.627214911396, 9330.160285574262, 9339.486328251944, 9354.204892584139, 38473.84578462651, 38749.806250968846, 38818.611813881784, 38829.8351049469, 38863.222735545416, 3341.298327351259, 3400.387930172568, 3494.8252587370785, 3868.052327179859, 4097.836086556535, 25764.711764411706, 25846.87076564624, 27763.444731105265, 29092.854535727398, 29697.406051879057, 2978.4787662159038, 3030.818150910952, 3084.2135255509925, 3134.435801555719, 3236.158598764315, 482664.0, 486603.0, 488381.0, 488423.0, 489227.0, 57749.422505774404, 58279.4172058274, 58303.708481457325, 58335.70832145812, 58477.415225848046, 34129.658703413144, 34967.475487867705, 34986.300273994886, 62657.68671156706, 62883.6855815718, 12770.29763363024, 15147.394104235927, 15514.913956023196, 15531.378744850306, 15541.06753594788, 11846.289222646668, 11847.111466640037, 11853.22954008004, 11856.288622682494, 11960.40197990097, 18594.535136621693, 18685.813141868406, 18691.15889784963, 18691.813081869008, 18807.435776926715, 7524.774256772305, 7640.235976402315, 7660.1463341794615, 7728.26581474759, 7734.806629834186, 832.2883934236141, 969.8593703912893, 970.7330484116783, 974.6004138303261, 977.6431602465049, 24400.0, 28420.289492762848, 29146.125616231548, 29704.257393565334, 29939.401211975637, 1002.7793885345212, 1005.1606908231709, 1043.8903915088958, 1088.6353071721062, 1101.0200921180156, 14632.707345853236, 15093.54719358421, 15164.772528412082, 15290.159041252839, 15510.30203640839, 638603.0, 644026.0, 644186.7790661016, 644539.0, 644627.7768611127, 54396.0, 54964.72517637386, 55082.72458637761, 55099.0, 55102.0, 1332.9200247985152, 1361.4282001559368, 1371.0, 1371.6570857285678, 1375.3192169875977, 23773.0, 26530.60204096979, 28971.0, 28998.56502152427, 29272.853635731684, 46664.30003549882, 47026.52973470221, 47082.0, 47153.764231178626, 47338.0, 472166.0, 475674.0, 475987.0, 476615.0, 476643.0, 25499.0, 25689.87155064213, 25949.870250648626, 25974.48051038931, 26030.73969260283, 44691.32963005623, 44873.55126448694, 45152.871178220805, 45249.0, 45302.773486132355, 35761.44442841305, 36055.81972090123, 36682.26635467329, 37455.0, 37668.0, 36971.76784682487, 37226.25547488981, 37431.877043688735, 37573.62426375701, 37839.62160378361, 31525.0, 32255.838720806245, 36155.09612259609, 36801.81599091987, 37066.8146659265, 30951.0, 32505.674943250266, 32544.411205058197, 32782.18044548905, 33066.00801975944, 24422.145224917043, 24492.510149796548, 24601.261962141165, 24860.37849053788, 24891.25326240216], "value": 18926.59268958106, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.7441493294962, 361.67810648522817, 370.69602925600987, 377.4074702716735, 397.6560275361811, 13862.70256220571, 14744.926275368622, 18854.62290754184, 19556.706649400254, 20238.69641955372, 19180.0, 19385.903070484626, 19613.21547138113, 19670.606587868235, 19719.1126399312, 5223.843284701456, 5862.208601838761, 5922.170896074553, 5925.051991681332, 5934.851628709273, 5424.891502169974, 5721.713914304289, 5766.9711651441685, 5828.825135245949, 5975.342712301644, 5794.188813566104, 5794.884102317951, 5797.217375654296, 5805.912911306334, 5816.185733997244, 5649.689267090308, 5789.102689083199, 5891.8527036824, 5956.374580669054, 5970.283565972111, 4823.734694591796, 5645.350784659735, 5757.136429535562, 5770.04794208955, 5777.422257774231, 22536.43658908524, 23496.177633782736, 23671.0, 23843.284701458982, 23959.88020059906, 2157.503774131943, 2350.3536527454917, 2352.647102934556, 2498.7876030537245, 2721.0748345562406, 10231.334963227366, 10965.56137754489, 12905.483780648763, 14353.210573418457, 15428.228588570639, 1794.1298470241923, 1815.5279627296998, 1862.534366408398, 1869.6354210928873, 1888.1031510032624, 103035.0, 111311.0, 114047.0, 114096.42951785271, 116457.0, 11320.886791131983, 11392.0, 11489.655310340702, 11556.88443115575, 11708.473118709679, 12547.24716517012, 13286.601401957956, 13298.800517992238, 13430.26133562644, 14311.0, 8028.638711258008, 8106.4325497215805, 8255.894746839518, 8334.12491688376, 8358.665653373919, 6656.267810540837, 7163.212046674862, 7397.926020739724, 7542.924570754222, 7543.698252069856, 4448.55514448559, 4504.842330518416, 4562.1560011398, 4587.311903214478, 4633.62930965522, 1882.9905850470934, 1917.6835822089479, 1919.1172060852164, 1920.3470819921295, 1951.638946794819, 821.3839620284787, 887.2414085956415, 905.5200743605899, 933.9579718912665, 963.6579014449909, 19605.90197049034, 20347.287844925653, 20430.795692043186, 20633.58732825335, 20841.478963025744, 878.512425603796, 888.8666699994923, 921.9677311294207, 922.2898368256405, 933.8459154239474, 8842.381033327605, 10505.789884202273, 10711.410872401937, 10746.892531074589, 10789.028987391173, 587322.0, 592760.0, 596151.0, 602996.0, 609451.0, 120493.19260211104, 127438.0, 129982.0, 133513.0, 137759.0, 911.9361644684808, 965.5172413793103, 973.6056896956744, 976.2043934193609, 977.0180968127129, 18158.364457243933, 18622.0, 18802.435926922215, 18836.34072807445, 18913.810861891205, 37098.62901370952, 40752.1849563013, 49538.75230623968, 53505.0, 54051.72974135104, 74936.0, 76318.0, 76892.0, 77799.2220077792, 77825.832612512, 6066.939330606638, 8018.438709290293, 8109.189081091844, 8127.390445716476, 8134.87797683047, 12148.392580371123, 12392.132550721362, 12495.562655307021, 12647.620571382873, 12889.8066529004, 10670.679879603624, 10835.945820270848, 10917.12662986975, 11127.944360278147, 11165.776684466427, 9179.495127768037, 10127.430248311524, 10260.948695256477, 10948.0, 10949.452527373758, 10917.617883374043, 11051.0, 11586.246893951866, 11587.652370428901, 11649.41752912334, 7298.744543940937, 7378.926210738038, 7409.851802964018, 7680.692772289046, 7710.11333696632, 8117.594120293844, 10802.837957430487, 11036.337819730841, 11118.833217501582, 11162.162837787037], "value": 9320.595672188043, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.1497026932498, 1441.4954765831958, 1478.356914742087, 1617.2560622113826, 1809.6109336492652, 7361.631918404085, 7716.305532502074, 7902.367810575148, 8260.173982601736, 8264.83470330593, 8097.109317975018, 8130.390220733454, 8189.0, 8201.589920503966, 8220.219079187467, 2289.6679981402685, 2319.536092781442, 2323.7908588227056, 2325.2559181062065, 2326.9069237230574, 2310.214527060799, 2336.3224664847176, 2358.8230882683783, 2361.1145820317297, 2362.976370236293, 2287.531056133497, 2295.047555264563, 2325.1513197682893, 2325.2559181062065, 2325.825563082767, 2271.829612779048, 2278.3506700590333, 2293.0827668932393, 2295.494991101955, 2298.1496846167024, 2277.0664027748708, 2297.483066310067, 2302.4243939015246, 2303.9078436862505, 2306.169778879602, 7999.720009799629, 8118.228768267005, 8155.632996515171, 8214.83570328596, 8258.793530161734, 4966.726830024347, 5166.250893620404, 5169.0, 5178.378594568639, 5188.195829646411, 2923.4153169366, 3384.4246478098535, 3496.230829217568, 3567.02276961189, 3587.641235876418, 888.3958907942597, 1056.6988408303596, 1091.110744743032, 1093.6875749101032, 1100.6422912553453, 10446.216533759998, 10497.475126243657, 10589.73525661857, 10607.84088238677, 10642.467876606217, 4131.690123240769, 4330.718503297276, 4416.183006143874, 4425.159219748238, 4461.286194208911, 8523.744287671256, 8551.871721924052, 8558.78603034929, 8570.957145214234, 8575.056743758181, 5052.141136006888, 5059.316992206079, 5063.012712521023, 5069.39167299919, 5070.264811602371, 4062.5734297898885, 4234.809433575497, 4354.150940566622, 4397.164538737629, 4761.642876784255, 3576.3562558739154, 3590.7666001710327, 3657.9634203658156, 3660.9816950915074, 3672.0452682302794, 1124.5389390349917, 1133.4502766158407, 1135.3131355529952, 1144.1361771862191, 1146.7294756382244, 843.5360551696542, 870.7561882672861, 870.9738707838775, 898.3172788680649, 900.6352427266968, 5439.211314359475, 5591.832245032656, 5598.0, 5683.147527870769, 5722.055860782924, 949.7957939043001, 983.8081574092984, 995.0994350113159, 997.5211898288717, 1032.829583118792, 3511.3328467591587, 3534.858605655745, 3711.740178187555, 3724.497192878927, 3775.1317197044286, 9535.47554884474, 10027.849582256273, 10033.899661003297, 10045.648402305884, 10049.748756281151, 7804.141544430108, 9753.463559504153, 9939.55272012761, 9954.30319877616, 10007.349522281029, 7384.963075184697, 7566.205548417447, 7596.24037596247, 7686.654100565488, 7697.615119243904, 5301.9469805303, 5356.384015838228, 5392.379876314274, 5499.285092937893, 5559.499645031967, 8496.745097647081, 9113.544322783702, 9164.129407706258, 9195.172434480928, 9198.632054717737, 6805.557638753465, 7647.502912310682, 7661.961690191513, 9420.62317507292, 9430.434173949585, 7542.094948606203, 7711.0746710395115, 7713.80715482095, 7728.033995750531, 7733.8839917400155, 7575.848483030199, 7627.427942904415, 7716.575588342471, 7741.535507869546, 7761.525310191046, 7015.473839461959, 7034.2965703429245, 7044.36600705939, 7187.748428804966, 7196.568205907663, 6914.0666010088, 6928.341807528277, 6948.513604047668, 6953.756618518328, 6994.790156295319, 6503.447206987459, 6503.804885853432, 6531.804045878631, 6584.967075164594, 6587.341265873374, 4474.0, 4546.068056048568, 4650.581447669726, 4659.44086709597, 4750.881227969328, 4530.841420550265, 4555.476120246214, 4589.449266087957, 4782.210935195653, 4786.090642777861], "value": 4315.7389151233565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.3145687930595, 305.65461029037186, 306.50652449556213, 312.6545167589812, 314.70732219036313, 4251.787410629468, 5072.28987941688, 5111.56551693106, 5159.845204643857, 5213.426523082458, 3934.567197608261, 4247.808848601812, 5057.080762409135, 5087.694738315704, 5137.717425541603, 3254.902352929416, 3682.5396825396824, 3839.36650452675, 3886.358750806128, 3916.8445308634023, 3711.257748450307, 3923.8234279457492, 3948.8617898373564, 3964.5638979712207, 3998.1603863188766, 3893.9415908761393, 3903.906906066306, 3907.199024200032, 3911.628395302456, 3929.9214015719667, 3301.504774283853, 3487.424574945131, 3521.683048525645, 3689.6494832990825, 3775.6601905828607, 3229.9677003229904, 3407.926503151499, 3411.3518431497937, 3429.839201303163, 3444.362792883325, 4284.935725964113, 4496.797644106023, 4936.7531623419045, 4941.950580494185, 4969.428515720666, 4090.2433049885867, 4136.400221967798, 4275.935860962057, 4403.669724770655, 4462.8884277892985, 4656.95343046572, 5311.707856067915, 5332.280142180794, 5335.1997200419855, 5355.919661205085, 3283.7865538740143, 3731.533558305212, 3765.548134223883, 3787.299349620329, 3796.4495148203355, 4573.0, 5577.60559860035, 5598.692071936042, 5609.709766753629, 5624.662520248839, 3692.833822477995, 3724.8696295629525, 3728.440733889884, 3758.496601359478, 3770.698344132463, 3585.3367127081574, 3607.981960090235, 3790.0, 3803.67668748154, 3817.9427308590393, 4413.977930110329, 4432.778361081933, 4433.1577000369825, 4446.333050042519, 4452.109578084396, 4495.483019452739, 4646.976765116153, 5004.024215277986, 5241.161414173714, 5412.161115027177, 1148.62095508482, 1160.8275641601874, 1194.1521519721096, 1227.9570215042431, 1242.658268976039, 739.8076500109903, 796.5579103597444, 830.9958450207831, 844.7170197983621, 845.3406343052429, 4835.758212089452, 5018.648694591344, 5029.5221953914315, 5032.899342013139, 5055.039542486916, 4666.463356714022, 5418.160185171305, 5423.2136340230445, 5441.401445840955, 5464.551828842004, 4025.959740402617, 5090.287359769635, 5105.974470127626, 5123.154679477919, 5162.6902385856965, 2519.634652975308, 2554.6678931738757, 2753.284146122023, 2760.6963234044642, 2762.668479782439, 4391.5827996340295, 4691.5308469152815, 4746.501617330199, 5036.949630503648, 5146.922796158061, 4435.695406921864, 4798.776312040407, 4810.735409552508, 5322.562908014892, 5328.49379308965, 5783.971080144573, 5789.565782566325, 5833.183354330397, 5843.152742852262, 5856.9121463178935, 4913.533214344631, 5189.81835635766, 5197.896042079062, 5241.240020197126, 5590.832275031756, 5188.455212202664, 5194.3247377840635, 5490.286262785972, 5561.998840208801, 5571.387147413862, 5410.404855465974, 5806.622569532967, 5843.29880414353, 5846.707664616837, 5868.073246021464, 3439.3491124260995, 3531.8344946168218, 3821.522309711286, 3825.049224895268, 3854.5952674969844, 5374.838754837361, 5435.402105768283, 5606.691631960118, 5668.518175954925, 5742.59801813869, 4505.5719706629125, 5098.388193416666, 5471.781128754965, 5476.561875050066, 5482.985647655197, 4634.791434385528, 5349.705766182898, 5371.382291036581, 5389.865253368697, 5406.864828379322, 4661.160991021513, 4945.626099472345, 5040.596752259884, 5195.3246078009615, 5366.221897824716, 4762.214169686367, 5141.8687888664435, 5195.9735703075075, 5335.786568537215, 5517.393086760533, 4366.956330436655, 5069.391672999263, 5283.10187268173, 5284.260203571426, 5293.0], "value": 3891.125627771828, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.9148856392951, 687.8830598798204, 699.5208282326606, 720.8161918710731, 758.2569082299344, 14036.228007459586, 14421.927890360534, 14428.855711442882, 14461.20463374514, 14475.203863787487, 11546.191766576343, 12744.299063551502, 12875.742485150291, 12877.162984406006, 12882.0, 635.2757856044113, 651.0527182948798, 773.404478551515, 784.6273020315361, 803.9598020099, 804.3725893802844, 806.8709006558952, 807.935365170785, 808.0787901791962, 808.7816289601803, 669.9631520266383, 855.8716192571133, 856.4518708026868, 857.2242120880582, 857.5112186053951, 593.4095574902977, 806.6329819931947, 827.896512935883, 831.8294749576322, 837.8994520657499, 612.9724162412703, 614.4899733221415, 614.529884638252, 615.6952308607268, 616.3836163836164, 13569.52506662262, 13884.09753366028, 13939.233342166177, 13942.302884855819, 13958.302084895715, 12648.873511264861, 12696.17474864131, 12705.745885082339, 12712.555060572835, 12802.295873726942, 11114.22200445969, 11389.715257118638, 11623.418829058513, 12078.758424831542, 12434.0, 4276.807543660543, 4309.0, 4416.933745993812, 4417.889552761207, 4418.9337159942625, 74854.0, 76002.6199868997, 76099.23900760977, 78294.21705782926, 83589.0, 9818.214542836557, 10073.194144468425, 10127.7468063299, 10401.895981040245, 10411.427371494641, 18890.716639250422, 19057.0, 19082.236710531422, 19191.136398862083, 19391.903040484707, 7169.713211471587, 7198.7840364789145, 7265.491415600857, 7320.5607663539295, 7409.222031686595, 3979.303621866162, 4018.8794336169963, 4047.534533528682, 4059.837606495766, 4091.7135800494275, 3180.061881744891, 3272.3291725196277, 3275.7706960512537, 3275.852586633607, 3277.5411442398085, 554.9667019978733, 596.1014169868981, 626.0359047067465, 641.8267067891736, 642.210081599635, 512.1600575056909, 582.0047718401581, 595.4105435618793, 610.43229796289, 611.2632939859451, 7144.571325720473, 7794.3764498839955, 8519.616617252239, 8603.655853765778, 8607.78480537979, 566.41376175658, 577.9479846813723, 603.9305479869784, 628.5129025005566, 656.4584218020152, 3862.439946207784, 4184.958150418457, 4186.312259346665, 4209.768462734579, 4220.915581688349, 76841.61579192069, 89630.55184724164, 106236.0, 107211.0, 107895.46052269689, 63655.68172159109, 67575.0, 74272.0, 89445.0, 91401.0, 76038.6198069006, 79293.60353198196, 79626.6018669903, 82522.76215856767, 83595.5820220895, 8027.1972802719265, 8095.554744488874, 8101.675932962616, 8170.795730106557, 8285.668573257244, 52255.73872130615, 52407.47592524026, 53415.73292133515, 53696.46303536915, 54793.72603136959, 64785.67607161934, 74254.62872685603, 74941.62529187319, 76231.61884190544, 78392.0, 10710.678679639623, 10902.073323767569, 11004.39475828837, 11311.0, 11442.885571144516, 10343.94828025855, 11245.887541124484, 11930.701732456759, 12118.575849845212, 12212.87787122153, 12091.334976576021, 12267.386630668252, 12292.201006934522, 12394.81407778902, 13460.25968571738, 12624.936875315932, 13371.799423008468, 13460.394282257126, 13522.985776066638, 13526.391312391163, 12292.631221063382, 12692.873071269169, 12914.935425322814, 12959.352032398154, 13160.670983225496, 10576.524056417325, 10616.416097114732, 10831.891681083089, 10860.891391085988, 11098.61154859576, 10417.739556511147, 11000.22998390137, 11127.609048868891, 11138.88861111411, 11150.498227579616], "value": 6922.633719016793, "unit": "score"}], "name": "dromaeo_css", "value": 6995.128887992472, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 124.0, 172.0, 115.0, 113.0, 115.0, 114.0, 113.0, 113.0, 113.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 118.0, 154.0, 133.0, 143.0, 114.0, 113.0, 145.0, 119.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 184.0, 181.0, 181.0, 181.0, 182.0, 183.0, 183.0, 185.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 58.0, 89.0, 60.0, 58.0, 60.0, 59.0, 82.0, 58.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 94.0, 70.0, 69.0, 69.0, 89.0, 69.0, 69.0, 91.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 86.0, 88.0, 85.0, 89.0, 85.0, 88.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 93.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 82.0, 80.0, 83.0, 82.0, 84.0, 83.0, 85.0, 83.0], "value": 82.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 57.0, 56.0, 58.0, 59.0, 59.0, 58.0, 58.0, 58.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 73.0, 80.0, 73.0, 71.0, 69.0, 71.0, 69.0, 70.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 85.0, 83.0, 84.0, 84.0, 85.0, 86.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 150.0, 154.0, 153.0, 144.0, 149.0, 152.0, 149.0, 192.0, 167.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 172.0, 178.0, 171.0, 170.0, 173.0, 170.0, 172.0, 169.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 70.0, 73.0, 82.0, 86.0, 87.0, 78.0, 68.0], "value": 75.3, "unit": "score"}], "name": "kraken", "value": 1458.1, "alertThreshold": 2.0}]} 02:50:12 INFO - Return code: 0 02:50:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:50:12 INFO - # TBPL SUCCESS # 02:50:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:50:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:50:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:50:12 INFO - 2016-12-08 02:50:12.982 screenresolution[1659:10421] starting screenresolution argv=screenresolution get 02:50:12 INFO - 2016-12-08 02:50:12.986 screenresolution[1659:10421] Display 0: 1600x1200x32@60 02:50:12 INFO - 2016-12-08 02:50:12.992 screenresolution[1660:10424] starting screenresolution argv=screenresolution list 02:50:12 INFO - Available Modes on Display 0 02:50:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:50:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:50:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:50:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:50:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:50:13 INFO - Intel Iris: 02:50:13 INFO - Chipset Model: Intel Iris 02:50:13 INFO - Type: GPU 02:50:13 INFO - Bus: Built-In 02:50:13 INFO - VRAM (Dynamic, Max): 1536 MB 02:50:13 INFO - Vendor: Intel (0x8086) 02:50:13 INFO - Device ID: 0x0a2e 02:50:13 INFO - Revision ID: 0x0009 02:50:13 INFO - Displays: 02:50:13 INFO - Display: 02:50:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:50:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:50:13 INFO - Display Serial Number: 1600x1200 60 02:50:13 INFO - Main Display: Yes 02:50:13 INFO - Mirror: Off 02:50:13 INFO - Online: Yes 02:50:13 INFO - Rotation: Supported 02:50:13 INFO - Return code: 0 02:50:13 INFO - Running post-action listener: _resource_record_post_action 02:50:13 INFO - [mozharness: 2016-12-08 10:50:13.242481Z] Finished run-tests step (success) 02:50:13 INFO - Running post-run listener: _resource_record_post_run 02:50:13 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32422912; Write bytes: 655480320; Read time: 201; Write time: 1871 02:50:13 INFO - TinderboxPrint: CPU usage
53.3% 02:50:13 INFO - TinderboxPrint: I/O read bytes / time
32,422,912 / 201 02:50:13 INFO - TinderboxPrint: I/O write bytes / time
655,480,320 / 1,871 02:50:13 INFO - TinderboxPrint: CPU idle
2,263.4 (46.7%) 02:50:13 INFO - TinderboxPrint: CPU system
972.1 (20.0%) 02:50:13 INFO - TinderboxPrint: CPU user
1,614.1 (33.3%) 02:50:13 INFO - TinderboxPrint: Swap in / out
377,434,112 / 0 02:50:13 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345752064; Write bytes: 346691584; Read time: 26759; Write time: 714 02:50:13 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 30317568; Write bytes: 301596160; Read time: 180; Write time: 1127 02:50:13 INFO - Running post-run listener: _upload_blobber_files 02:50:13 INFO - Blob upload gear active. 02:50:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:50:13 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 02:50:13 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'] 02:50:13 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 02:50:14 INFO - (blobuploader) - INFO - Open directory for files ... 02:50:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:50:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:50:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:15 INFO - (blobuploader) - INFO - Done attempting. 02:50:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:50:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:50:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:16 INFO - (blobuploader) - INFO - Done attempting. 02:50:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:50:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:50:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:19 INFO - (blobuploader) - INFO - Done attempting. 02:50:19 INFO - (blobuploader) - INFO - Iteration through files over. 02:50:19 INFO - Return code: 0 02:50:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:50:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:50:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b831417182a731a70a83b46e10e63688743473984e2ec1523c7bc2bed42dec020058454080986c894ef5b1fced3e4aaba1a783f82841a95508413a891d17b83c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/432b73c3508fc7736b92ca188d01d189d40db8d28ab8cb06728d5e80ab8a6a06c46c8eee823206ee5f010a5a3354e4ca7b12675c0e49e24e50137c80d4370ce6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4142b7d7eac216b82854feaa8fe50038f9547c9924e3e9bd56aeec0d5f8bdff113e217d7d4aed3eceb5ba85ca7fccd68cae9a49027601135e4e0d2914e003978"} 02:50:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:50:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:50:19 INFO - Contents: 02:50:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b831417182a731a70a83b46e10e63688743473984e2ec1523c7bc2bed42dec020058454080986c894ef5b1fced3e4aaba1a783f82841a95508413a891d17b83c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/432b73c3508fc7736b92ca188d01d189d40db8d28ab8cb06728d5e80ab8a6a06c46c8eee823206ee5f010a5a3354e4ca7b12675c0e49e24e50137c80d4370ce6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4142b7d7eac216b82854feaa8fe50038f9547c9924e3e9bd56aeec0d5f8bdff113e217d7d4aed3eceb5ba85ca7fccd68cae9a49027601135e4e0d2914e003978"} 02:50:19 INFO - Running post-run listener: copy_logs_to_upload_dir 02:50:19 INFO - Copying logs to upload dir... 02:50:19 INFO - mkdir: /builds/slave/test/build/upload/logs 02:50:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.049213 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-08 02:50:19.164910) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 02:50:19.168426) ========= 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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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/b831417182a731a70a83b46e10e63688743473984e2ec1523c7bc2bed42dec020058454080986c894ef5b1fced3e4aaba1a783f82841a95508413a891d17b83c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/432b73c3508fc7736b92ca188d01d189d40db8d28ab8cb06728d5e80ab8a6a06c46c8eee823206ee5f010a5a3354e4ca7b12675c0e49e24e50137c80d4370ce6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4142b7d7eac216b82854feaa8fe50038f9547c9924e3e9bd56aeec0d5f8bdff113e217d7d4aed3eceb5ba85ca7fccd68cae9a49027601135e4e0d2914e003978"} build_url:https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011064 build_url: 'https://queue.taskcluster.net/v1/task/M2zErFzYS8exTpbO3KOaNw/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/b831417182a731a70a83b46e10e63688743473984e2ec1523c7bc2bed42dec020058454080986c894ef5b1fced3e4aaba1a783f82841a95508413a891d17b83c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/432b73c3508fc7736b92ca188d01d189d40db8d28ab8cb06728d5e80ab8a6a06c46c8eee823206ee5f010a5a3354e4ca7b12675c0e49e24e50137c80d4370ce6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4142b7d7eac216b82854feaa8fe50038f9547c9924e3e9bd56aeec0d5f8bdff113e217d7d4aed3eceb5ba85ca7fccd68cae9a49027601135e4e0d2914e003978"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 02:50:19.203756) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 02:50:19.204148) ========= 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.EvcyDWSkiL/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.MLCNwIAf5j/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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.005110 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 02:50:19.253169) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 02:50:19.253546) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 02:50:19.253964) ========= ========= Total master_lag: 0.19 =========