builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1482710388.0 results: success (0) buildid: 20161225135937 builduid: 4b08b05a29e94a829026bc02ea9eeab7 revision: 39582d06756a9ff47993d4097b4fb25f041be406 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:47.996210) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:47.996707) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:47.996997) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:48.154881) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:48.155170) ========= 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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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-25 15:59:48-- 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% 40.5M=0s 2016-12-25 15:59:48 (40.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167564 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:48.347375) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:48.347710) ========= 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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.025964 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:48.396656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:48.397012) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 39582d06756a9ff47993d4097b4fb25f041be406 --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 39582d06756a9ff47993d4097b4fb25f041be406 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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-25 15:59:48,487 truncating revision to first 12 chars 2016-12-25 15:59:48,488 Setting DEBUG logging. 2016-12-25 15:59:48,488 attempt 1/10 2016-12-25 15:59:48,488 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/39582d06756a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-25 15:59:48,860 unpacking tar archive at: mozilla-aurora-39582d06756a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.665151 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:49.085711) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:49.086030) ========= script_repo_revision: 39582d06756a9ff47993d4097b4fb25f041be406 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:49.086426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:49.086692) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 15:59:49.105963) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-25 15:59:49.106341) ========= /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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:59:49 INFO - MultiFileLogger online at 20161225 15:59:49 in /builds/slave/test 15:59:49 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 15:59:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:59:49 INFO - {'append_to_log': False, 15:59:49 INFO - 'base_work_dir': '/builds/slave/test', 15:59:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:59:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:59:49 INFO - 'branch': 'Mozilla-Aurora', 15:59:49 INFO - 'buildbot_json_path': 'buildprops.json', 15:59:49 INFO - 'config_files': ('talos/mac_config.py',), 15:59:49 INFO - 'default_actions': ('clobber', 15:59:49 INFO - 'read-buildbot-config', 15:59:49 INFO - 'download-and-extract', 15:59:49 INFO - 'populate-webroot', 15:59:49 INFO - 'create-virtualenv', 15:59:49 INFO - 'install', 15:59:49 INFO - 'run-tests'), 15:59:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:59:49 INFO - 'download_minidump_stackwalk': True, 15:59:49 INFO - 'download_symbols': 'ondemand', 15:59:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:59:49 INFO - 'tooltool.py': '/tools/tooltool.py', 15:59:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:59:49 INFO - '/tools/misc-python/virtualenv.py')}, 15:59:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:59:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:59:49 INFO - 'installer_path': 'installer.exe', 15:59:49 INFO - 'log_level': 'info', 15:59:49 INFO - 'log_name': 'talos', 15:59:49 INFO - 'log_to_console': True, 15:59:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:59:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:59:49 INFO - 'opt_config_files': (), 15:59:49 INFO - 'pip_index': False, 15:59:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:59:49 INFO - 'cmd': ('bash', 15:59:49 INFO - '-c', 15:59:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:59:49 INFO - 'enabled': True, 15:59:49 INFO - 'halt_on_failure': False, 15:59:49 INFO - 'name': 'check_screen_resolution'},), 15:59:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:59:49 INFO - 'cmd': ('bash', 15:59:49 INFO - '-c', 15:59:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:59:49 INFO - 'enabled': True, 15:59:49 INFO - 'halt_on_failure': False, 15:59:49 INFO - 'name': 'check_screen_resolution'},), 15:59:49 INFO - 'run_cmd_checks_enabled': True, 15:59:49 INFO - 'sps_profile': False, 15:59:49 INFO - 'sps_profile_interval': 0, 15:59:49 INFO - 'suite': 'dromaeojs', 15:59:49 INFO - 'system_bits': '32', 15:59:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:59:49 INFO - 'title': 't-yosemite-r7-0095', 15:59:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:59:49 INFO - 'use_talos_json': True, 15:59:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:59:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:59:49 INFO - 'work_dir': 'build'} 15:59:49 INFO - [mozharness: 2016-12-25 23:59:49.312426Z] Running clobber step. 15:59:49 INFO - Running pre-action listener: _resource_record_pre_action 15:59:49 INFO - Running main action method: clobber 15:59:49 INFO - rmtree: /builds/slave/test/build 15:59:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:59:51 INFO - Running post-action listener: _resource_record_post_action 15:59:51 INFO - [mozharness: 2016-12-25 23:59:51.547889Z] Finished clobber step (success) 15:59:51 INFO - [mozharness: 2016-12-25 23:59:51.548098Z] Running read-buildbot-config step. 15:59:51 INFO - Running pre-action listener: _resource_record_pre_action 15:59:51 INFO - Running main action method: read_buildbot_config 15:59:51 INFO - Using buildbot properties: 15:59:51 INFO - { 15:59:51 INFO - "project": "", 15:59:51 INFO - "product": "firefox", 15:59:51 INFO - "script_repo_revision": "production", 15:59:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:59:51 INFO - "repository": "", 15:59:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:59:51 INFO - "buildid": "20161225135937", 15:59:51 INFO - "pgo_build": "False", 15:59:51 INFO - "basedir": "/builds/slave/test", 15:59:51 INFO - "buildnumber": 50, 15:59:51 INFO - "slavename": "t-yosemite-r7-0095", 15:59:51 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:59:51 INFO - "platform": "yosemite_r7", 15:59:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:59:51 INFO - "branch": "mozilla-aurora", 15:59:51 INFO - "repo_path": "releases/mozilla-aurora", 15:59:51 INFO - "revision": "39582d06756a9ff47993d4097b4fb25f041be406", 15:59:51 INFO - "stage_platform": "macosx64", 15:59:51 INFO - "builduid": "4b08b05a29e94a829026bc02ea9eeab7", 15:59:51 INFO - "slavebuilddir": "test" 15:59:51 INFO - } 15:59:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:59:51 INFO - Running post-action listener: _resource_record_post_action 15:59:51 INFO - [mozharness: 2016-12-25 23:59:51.552136Z] Finished read-buildbot-config step (success) 15:59:51 INFO - [mozharness: 2016-12-25 23:59:51.552283Z] Running download-and-extract step. 15:59:51 INFO - Running pre-action listener: _resource_record_pre_action 15:59:51 INFO - Running main action method: download_and_extract 15:59:51 INFO - mkdir: /builds/slave/test/build/tests 15:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:51 INFO - trying https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:59:51 INFO - Downloading https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/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 15:59:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/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 15:59:52 INFO - Downloaded 1199 bytes. 15:59:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:59:52 INFO - Using the following test package requirements: 15:59:52 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:59:52 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:59:52 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:59:52 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:59:52 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:59:52 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:59:52 INFO - u'jsshell-mac.zip'], 15:59:52 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:59:52 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:59:52 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:59:52 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:59:52 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:59:52 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:59:52 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:59:52 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:59:52 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:59:52 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:59:52 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:59:52 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:59:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:59:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:59:52 INFO - Fetch https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:59:53 INFO - Http code: 200 15:59:53 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 15:59:53 INFO - x-amz-cf-id: K2BEkcdejGRmVstqNKMUOevHBr_huP-cZNUD_51r_ScP0LfsLPtvFA== 15:59:53 INFO - x-amz-version-id: IwzmkvdFgeq7nkgqIU4tsFZXZRzEDdYv 15:59:53 INFO - Expected file size: 16271104 15:59:53 INFO - Obtained file size: 16271104 16:00:00 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 16:00:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:00:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:00:00 INFO - Fetch https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:00:00 INFO - Http code: 200 16:00:00 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 16:00:00 INFO - x-amz-cf-id: kWDycL4Uya7EZXIxdG5WIDds1XUth5ajb8OpIG0NKr75fQ-kUqzLFg== 16:00:00 INFO - x-amz-version-id: IwzmkvdFgeq7nkgqIU4tsFZXZRzEDdYv 16:00:01 INFO - Expected file size: 16271104 16:00:01 INFO - Obtained file size: 16271104 16:00:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 16:00:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:00:07 INFO - Fetch https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 16:00:08 INFO - Http code: 200 16:00:08 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 16:00:08 INFO - x-amz-cf-id: PsTYxWYsjLia1CrcS-Zra9Irs07L1NGujNIkzved7M8lXn5Q5wYFrQ== 16:00:08 INFO - x-amz-version-id: XDLg2SSvhe.rKn4ULRaE5V_h9.h9uhOu 16:00:08 INFO - Expected file size: 14409038 16:00:08 INFO - Obtained file size: 14409038 16:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:09 INFO - trying https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:00:09 INFO - Downloading https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 16:00:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/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 16:00:11 INFO - Downloaded 109825022 bytes. 16:00:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:00:11 INFO - mkdir: /builds/slave/test/properties 16:00:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:00:11 INFO - Writing to file /builds/slave/test/properties/build_url 16:00:11 INFO - Contents: 16:00:11 INFO - build_url:https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:00:12 INFO - Running post-action listener: _resource_record_post_action 16:00:12 INFO - Running post-action listener: set_extra_try_arguments 16:00:12 INFO - [mozharness: 2016-12-26 00:00:12.553953Z] Finished download-and-extract step (success) 16:00:12 INFO - [mozharness: 2016-12-26 00:00:12.554120Z] Running populate-webroot step. 16:00:12 INFO - Running pre-action listener: _resource_record_pre_action 16:00:12 INFO - Running main action method: populate_webroot 16:00:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:00:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:00:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:00:12 INFO - '--tppagecycles', 16:00:12 INFO - '7'], 16:00:12 INFO - 'tests': ['tsvgm']}}, 16:00:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:00:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:00:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:00:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:00:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['damp', 'tps']}, 16:00:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'tests': ['damp', 'tps']}, 16:00:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['dromaeo_dom']}, 16:00:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:00:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['basic_compositor_video']}, 16:00:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:00:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other-e10s': {'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['tsvgx', 16:00:12 INFO - 'tsvgr_opacity', 16:00:12 INFO - 'tart', 16:00:12 INFO - 'tscrollx', 16:00:12 INFO - 'cart']}, 16:00:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:00:12 INFO - 'tsvgr_opacity', 16:00:12 INFO - 'tart', 16:00:12 INFO - 'tscrollx', 16:00:12 INFO - 'cart']}, 16:00:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['tp5o']}, 16:00:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'tests': ['tp5o']}, 16:00:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--disable-e10s', 16:00:12 INFO - '--xperf_path', 16:00:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:12 INFO - 'tests': ['tp5n']}, 16:00:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--xperf_path', 16:00:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:12 INFO - 'tests': ['tp5n']}}, 16:00:12 INFO - 'talos.zip': {'path': '', 16:00:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:00:12 INFO - Running post-action listener: _resource_record_post_action 16:00:12 INFO - [mozharness: 2016-12-26 00:00:12.573219Z] Finished populate-webroot step (success) 16:00:12 INFO - [mozharness: 2016-12-26 00:00:12.573359Z] Running create-virtualenv step. 16:00:12 INFO - Running pre-action listener: _resource_record_pre_action 16:00:12 INFO - Running main action method: create_virtualenv 16:00:12 INFO - Creating virtualenv /builds/slave/test/build/venv 16:00:12 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 16:00:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:00:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:00:12 INFO - Using real prefix '/tools/python27' 16:00:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:00:13 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:16 INFO - Installing pip.................done. 16:00:16 INFO - Return code: 0 16:00:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:00:16 INFO - Reading from file tmpfile_stdout 16:00:16 INFO - Output received: 16:00:16 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) 16:00:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:00:16 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')]} 16:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:16 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')]} 16:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:16 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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:16 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 16:00:16 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 16:00:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:16 INFO - 'HOME': '/Users/cltbld', 16:00:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:16 INFO - 'LOGNAME': 'cltbld', 16:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:16 INFO - 'MOZ_NO_REMOTE': '1', 16:00:16 INFO - 'NO_EM_RESTART': '1', 16:00:16 INFO - 'PAGER': '/bin/cat', 16:00:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:16 INFO - 'PWD': '/builds/slave/test', 16:00:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:16 INFO - 'SHELL': '/bin/bash', 16:00:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:16 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:16 INFO - 'USER': 'cltbld', 16:00:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:16 INFO - 'XPC_FLAGS': '0x0', 16:00:16 INFO - 'XPC_SERVICE_NAME': '0', 16:00:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:16 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 16:00:16 INFO - Cleaning up... 16:00:16 INFO - Return code: 0 16:00:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:00:16 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')]} 16:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:16 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')]} 16:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:16 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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:16 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 16:00:16 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 16:00:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:16 INFO - 'HOME': '/Users/cltbld', 16:00:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:16 INFO - 'LOGNAME': 'cltbld', 16:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:16 INFO - 'MOZ_NO_REMOTE': '1', 16:00:16 INFO - 'NO_EM_RESTART': '1', 16:00:16 INFO - 'PAGER': '/bin/cat', 16:00:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:16 INFO - 'PWD': '/builds/slave/test', 16:00:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:16 INFO - 'SHELL': '/bin/bash', 16:00:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:16 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:16 INFO - 'USER': 'cltbld', 16:00:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:16 INFO - 'XPC_FLAGS': '0x0', 16:00:16 INFO - 'XPC_SERVICE_NAME': '0', 16:00:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:17 INFO - Downloading/unpacking psutil>=3.1.1 16:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:20 INFO - Installing collected packages: psutil 16:00:20 INFO - Running setup.py install for psutil 16:00:21 INFO - building 'psutil._psutil_osx' extension 16:00:21 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 16:00:21 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 16:00:21 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 16:00:21 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 16:00:21 INFO - building 'psutil._psutil_posix' extension 16:00:21 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 16:00:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:00:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:00:21 INFO - ^ 16:00:21 INFO - 1 warning generated. 16:00:21 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 16:00:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:22 INFO - Successfully installed psutil 16:00:22 INFO - Cleaning up... 16:00:22 INFO - Return code: 0 16:00:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:22 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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:22 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 16:00:22 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 16:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:22 INFO - 'HOME': '/Users/cltbld', 16:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:22 INFO - 'LOGNAME': 'cltbld', 16:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:22 INFO - 'MOZ_NO_REMOTE': '1', 16:00:22 INFO - 'NO_EM_RESTART': '1', 16:00:22 INFO - 'PAGER': '/bin/cat', 16:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:22 INFO - 'PWD': '/builds/slave/test', 16:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:22 INFO - 'SHELL': '/bin/bash', 16:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:22 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:22 INFO - 'USER': 'cltbld', 16:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:22 INFO - 'XPC_FLAGS': '0x0', 16:00:22 INFO - 'XPC_SERVICE_NAME': '0', 16:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:00:25 INFO - Installing collected packages: mozsystemmonitor 16:00:25 INFO - Running setup.py install for mozsystemmonitor 16:00:25 INFO - Successfully installed mozsystemmonitor 16:00:25 INFO - Cleaning up... 16:00:25 INFO - Return code: 0 16:00:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:00:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:25 INFO - 'HOME': '/Users/cltbld', 16:00:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:25 INFO - 'LOGNAME': 'cltbld', 16:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:25 INFO - 'MOZ_NO_REMOTE': '1', 16:00:25 INFO - 'NO_EM_RESTART': '1', 16:00:25 INFO - 'PAGER': '/bin/cat', 16:00:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:25 INFO - 'PWD': '/builds/slave/test', 16:00:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:25 INFO - 'SHELL': '/bin/bash', 16:00:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:25 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:25 INFO - 'USER': 'cltbld', 16:00:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:25 INFO - 'XPC_FLAGS': '0x0', 16:00:25 INFO - 'XPC_SERVICE_NAME': '0', 16:00:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:25 INFO - Downloading/unpacking jsonschema==2.5.1 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:00:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:28 INFO - Downloading functools32-3.2.3-2.tar.gz 16:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:00:28 INFO - warning: no files found matching '*.txt' 16:00:28 INFO - no previously-included directories found matching 'build' 16:00:28 INFO - no previously-included directories found matching 'dist' 16:00:28 INFO - no previously-included directories found matching '.git*' 16:00:28 INFO - Installing collected packages: jsonschema, functools32 16:00:28 INFO - Running setup.py install for functools32 16:00:28 INFO - warning: no files found matching '*.txt' 16:00:28 INFO - no previously-included directories found matching 'build' 16:00:28 INFO - no previously-included directories found matching 'dist' 16:00:28 INFO - no previously-included directories found matching '.git*' 16:00:28 INFO - Successfully installed jsonschema functools32 16:00:28 INFO - Cleaning up... 16:00:29 INFO - Return code: 0 16:00:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:00:29 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')]} 16:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:29 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')]} 16:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:29 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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:29 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 16:00:29 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 16:00:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:29 INFO - 'HOME': '/Users/cltbld', 16:00:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:29 INFO - 'LOGNAME': 'cltbld', 16:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:29 INFO - 'MOZ_NO_REMOTE': '1', 16:00:29 INFO - 'NO_EM_RESTART': '1', 16:00:29 INFO - 'PAGER': '/bin/cat', 16:00:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:29 INFO - 'PWD': '/builds/slave/test', 16:00:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:29 INFO - 'SHELL': '/bin/bash', 16:00:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:29 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:29 INFO - 'USER': 'cltbld', 16:00:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:29 INFO - 'XPC_FLAGS': '0x0', 16:00:29 INFO - 'XPC_SERVICE_NAME': '0', 16:00:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:00:29 INFO - Cleaning up... 16:00:29 INFO - Return code: 0 16:00:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:00:29 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')]} 16:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:29 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')]} 16:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:29 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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:29 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 16:00:29 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 16:00:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:29 INFO - 'HOME': '/Users/cltbld', 16:00:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:29 INFO - 'LOGNAME': 'cltbld', 16:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:29 INFO - 'MOZ_NO_REMOTE': '1', 16:00:29 INFO - 'NO_EM_RESTART': '1', 16:00:29 INFO - 'PAGER': '/bin/cat', 16:00:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:29 INFO - 'PWD': '/builds/slave/test', 16:00:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:29 INFO - 'SHELL': '/bin/bash', 16:00:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:29 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:29 INFO - 'USER': 'cltbld', 16:00:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:29 INFO - 'XPC_FLAGS': '0x0', 16:00:29 INFO - 'XPC_SERVICE_NAME': '0', 16:00:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:29 INFO - Downloading/unpacking blobuploader==1.2.4 16:00:29 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 16:00:29 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 16:00:29 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 16:00:29 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 16:00:29 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 16:00:29 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 16:00:32 INFO - Downloading blobuploader-1.2.4.tar.gz 16:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:00:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:00:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00:33 INFO - Downloading docopt-0.6.1.tar.gz 16:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:00:33 INFO - Installing collected packages: blobuploader, requests, docopt 16:00:33 INFO - Running setup.py install for blobuploader 16:00:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:00:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:00:33 INFO - Running setup.py install for requests 16:00:34 INFO - Running setup.py install for docopt 16:00:34 INFO - Successfully installed blobuploader requests docopt 16:00:34 INFO - Cleaning up... 16:00:34 INFO - Return code: 0 16:00:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:34 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')]} 16:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:34 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')]} 16:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:34 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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:34 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 16:00:34 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 16:00:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:34 INFO - 'HOME': '/Users/cltbld', 16:00:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:34 INFO - 'LOGNAME': 'cltbld', 16:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:34 INFO - 'MOZ_NO_REMOTE': '1', 16:00:34 INFO - 'NO_EM_RESTART': '1', 16:00:34 INFO - 'PAGER': '/bin/cat', 16:00:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:34 INFO - 'PWD': '/builds/slave/test', 16:00:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:34 INFO - 'SHELL': '/bin/bash', 16:00:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:34 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:34 INFO - 'USER': 'cltbld', 16:00:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:34 INFO - 'XPC_FLAGS': '0x0', 16:00:34 INFO - 'XPC_SERVICE_NAME': '0', 16:00:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:34 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-iw5Cj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:34 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-lagwbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:34 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-0wvUxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:34 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-HPqP31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-4szoyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-xaub0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-6DGQ9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ia8lgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-PAjlAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-QZ8tEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-jpNCQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-FE4eo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-3JgVFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:35 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-V7QvMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:36 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-IZY_4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:36 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-WE3e19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:36 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-z_LBE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:00:36 INFO - Running setup.py install for manifestparser 16:00:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:00:36 INFO - Running setup.py install for mozcrash 16:00:36 INFO - Running setup.py install for mozdebug 16:00:36 INFO - Running setup.py install for mozdevice 16:00:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:00:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:00:37 INFO - Running setup.py install for mozfile 16:00:37 INFO - Running setup.py install for mozhttpd 16:00:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:00:37 INFO - Running setup.py install for mozinfo 16:00:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:00:37 INFO - Running setup.py install for mozInstall 16:00:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:00:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:00:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:00:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:00:37 INFO - Running setup.py install for mozleak 16:00:37 INFO - Running setup.py install for mozlog 16:00:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:00:37 INFO - Running setup.py install for moznetwork 16:00:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:00:38 INFO - Running setup.py install for mozprocess 16:00:38 INFO - Running setup.py install for mozprofile 16:00:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:00:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:00:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:00:38 INFO - Running setup.py install for mozrunner 16:00:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:00:38 INFO - Running setup.py install for mozscreenshot 16:00:38 INFO - Running setup.py install for moztest 16:00:38 INFO - Running setup.py install for mozversion 16:00:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:00:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:00:38 INFO - Cleaning up... 16:00:38 INFO - Return code: 0 16:00:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:38 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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:38 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 16:00:38 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 16:00:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:38 INFO - 'HOME': '/Users/cltbld', 16:00:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:38 INFO - 'LOGNAME': 'cltbld', 16:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:38 INFO - 'MOZ_NO_REMOTE': '1', 16:00:38 INFO - 'NO_EM_RESTART': '1', 16:00:38 INFO - 'PAGER': '/bin/cat', 16:00:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:38 INFO - 'PWD': '/builds/slave/test', 16:00:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:38 INFO - 'SHELL': '/bin/bash', 16:00:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:38 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:38 INFO - 'USER': 'cltbld', 16:00:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:38 INFO - 'XPC_FLAGS': '0x0', 16:00:38 INFO - 'XPC_SERVICE_NAME': '0', 16:00:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:39 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-hiTeoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:39 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)) 16:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:39 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-48WNrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:39 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)) 16:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:39 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-LjxLSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:39 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)) 16:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:39 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-0NvBSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:39 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)) 16:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:39 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ZXDTJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:39 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)) 16:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:39 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-gAL52L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:39 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)) 16:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:39 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-nnG82E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:39 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)) 16:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:39 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-KWetPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Bw6EFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-0GKJBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ySGSgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-d6nqil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-lFKZsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-9qj3FK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-HGhg88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-a579kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:40 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)) 16:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:40 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-CjKL_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:41 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)) 16:00:41 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)) 16:00:41 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)) 16:00:41 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)) 16:00:41 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)) 16:00:41 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)) 16:00:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:00:41 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 16:00:41 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 16:00:41 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 16:00:41 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 16:00:41 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 16:00:41 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 16:00:43 INFO - Downloading blessings-1.6.tar.gz 16:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:43 INFO - Installing collected packages: blessings 16:00:43 INFO - Running setup.py install for blessings 16:00:43 INFO - Successfully installed blessings 16:00:43 INFO - Cleaning up... 16:00:44 INFO - Return code: 0 16:00:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:00:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:44 INFO - Reading from file tmpfile_stdout 16:00:44 INFO - Current package versions: 16:00:44 INFO - blessings == 1.6 16:00:44 INFO - blobuploader == 1.2.4 16:00:44 INFO - docopt == 0.6.1 16:00:44 INFO - functools32 == 3.2.3-2 16:00:44 INFO - jsonschema == 2.5.1 16:00:44 INFO - manifestparser == 1.1 16:00:44 INFO - mozInstall == 1.12 16:00:44 INFO - mozcrash == 1.0 16:00:44 INFO - mozdebug == 0.1 16:00:44 INFO - mozdevice == 0.48 16:00:44 INFO - mozfile == 1.2 16:00:44 INFO - mozhttpd == 0.7 16:00:44 INFO - mozinfo == 0.9 16:00:44 INFO - mozleak == 0.1 16:00:44 INFO - mozlog == 3.4 16:00:44 INFO - moznetwork == 0.27 16:00:44 INFO - mozprocess == 0.23 16:00:44 INFO - mozprofile == 0.28 16:00:44 INFO - mozrunner == 6.13 16:00:44 INFO - mozscreenshot == 0.1 16:00:44 INFO - mozsystemmonitor == 0.3 16:00:44 INFO - moztest == 0.7 16:00:44 INFO - mozversion == 1.4 16:00:44 INFO - psutil == 3.1.1 16:00:44 INFO - requests == 1.2.3 16:00:44 INFO - wsgiref == 0.1.2 16:00:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:44 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 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00:44 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 16:00:44 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 16:00:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:44 INFO - 'HOME': '/Users/cltbld', 16:00:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:44 INFO - 'LOGNAME': 'cltbld', 16:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:44 INFO - 'MOZ_NO_REMOTE': '1', 16:00:44 INFO - 'NO_EM_RESTART': '1', 16:00:44 INFO - 'PAGER': '/bin/cat', 16:00:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:44 INFO - 'PWD': '/builds/slave/test', 16:00:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:44 INFO - 'SHELL': '/bin/bash', 16:00:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:44 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:44 INFO - 'USER': 'cltbld', 16:00:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:44 INFO - 'XPC_FLAGS': '0x0', 16:00:44 INFO - 'XPC_SERVICE_NAME': '0', 16:00:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 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)) 16:00:44 INFO - Cleaning up... 16:00:44 INFO - Return code: 0 16:00:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b26c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5484a6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, '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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 16:00: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', 'jsonschema'] in /builds/slave/test/build 16:00: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 jsonschema 16:00:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:00:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:44 INFO - 'HOME': '/Users/cltbld', 16:00:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:44 INFO - 'LOGNAME': 'cltbld', 16:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:44 INFO - 'MOZ_NO_REMOTE': '1', 16:00:44 INFO - 'NO_EM_RESTART': '1', 16:00:44 INFO - 'PAGER': '/bin/cat', 16:00:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:44 INFO - 'PWD': '/builds/slave/test', 16:00:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:44 INFO - 'SHELL': '/bin/bash', 16:00:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:00:44 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:00:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:44 INFO - 'USER': 'cltbld', 16:00:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:44 INFO - 'XPC_FLAGS': '0x0', 16:00:44 INFO - 'XPC_SERVICE_NAME': '0', 16:00:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:00:44 INFO - Cleaning up... 16:00:44 INFO - Return code: 0 16:00:44 INFO - Running post-action listener: _resource_record_post_action 16:00:44 INFO - Running post-action listener: _start_resource_monitoring 16:00:44 INFO - Starting resource monitoring. 16:00:44 INFO - [mozharness: 2016-12-26 00:00:44.860480Z] Finished create-virtualenv step (success) 16:00:44 INFO - [mozharness: 2016-12-26 00:00:44.861482Z] Running install step. 16:00:44 INFO - Running pre-action listener: _resource_record_pre_action 16:00:44 INFO - Running main action method: install 16:00:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:45 INFO - Reading from file tmpfile_stdout 16:00:45 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:00:45 INFO - Reading from file tmpfile_stdout 16:00:45 INFO - Output received: 16:00:45 INFO - Usage: mozinstall [options] installer 16:00:45 INFO - Options: 16:00:45 INFO - -h, --help show this help message and exit 16:00:45 INFO - -d DEST, --destination=DEST 16:00:45 INFO - Directory to install application into. [default: 16:00:45 INFO - "/builds/slave/test"] 16:00:45 INFO - --app=APP Application being installed. [default: firefox] 16:00:45 INFO - mkdir: /builds/slave/test/build/application 16:00:45 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'] 16:00:45 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 16:01:16 INFO - Reading from file tmpfile_stdout 16:01:16 INFO - Output received: 16:01:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:01:16 INFO - Running post-action listener: _resource_record_post_action 16:01:16 INFO - [mozharness: 2016-12-26 00:01:16.609218Z] Finished install step (success) 16:01:16 INFO - [mozharness: 2016-12-26 00:01:16.609373Z] Running run-tests step. 16:01:16 INFO - Running pre-action listener: _resource_record_pre_action 16:01:16 INFO - Running main action method: run_tests 16:01:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:01:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:01:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:01:16 INFO - 2016-12-25 16:01:16.624 screenresolution[1639:11787] starting screenresolution argv=screenresolution get 16:01:16 INFO - 2016-12-25 16:01:16.627 screenresolution[1639:11787] Display 0: 1600x1200x32@60 16:01:16 INFO - 2016-12-25 16:01:16.632 screenresolution[1640:11789] starting screenresolution argv=screenresolution list 16:01:16 INFO - Available Modes on Display 0 16:01:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:01:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:01:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:01:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:01:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:01:16 INFO - Intel Iris: 16:01:16 INFO - Chipset Model: Intel Iris 16:01:16 INFO - Type: GPU 16:01:16 INFO - Bus: Built-In 16:01:16 INFO - VRAM (Dynamic, Max): 1536 MB 16:01:16 INFO - Vendor: Intel (0x8086) 16:01:16 INFO - Device ID: 0x0a2e 16:01:16 INFO - Revision ID: 0x0009 16:01:16 INFO - Displays: 16:01:16 INFO - Display: 16:01:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:01:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:01:16 INFO - Display Serial Number: 1600x1200 60 16:01:16 INFO - Main Display: Yes 16:01:16 INFO - Mirror: Off 16:01:16 INFO - Online: Yes 16:01:16 INFO - Rotation: Supported 16:01:16 INFO - Return code: 0 16:01:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:01:16 INFO - Python 2.7.3 16:01:16 INFO - Return code: 0 16:01:16 INFO - grabbing minidump binary from tooltool 16:01:16 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')]} 16:01:16 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 0x10b10ad30>, '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 0x10af70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b102b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:01:16 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 16:01:16 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 16:01:16 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 16:01:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:01:16 INFO - Return code: 0 16:01:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:01:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:01:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:01:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:01:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:01:16 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/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 16:01:16 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/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0095 --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 16:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DDqD3Qj9KR/Render', 16:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:16 INFO - 'HOME': '/Users/cltbld', 16:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:16 INFO - 'LOGNAME': 'cltbld', 16:01:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:01:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:16 INFO - 'MOZ_NO_REMOTE': '1', 16:01:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:01:16 INFO - 'NO_EM_RESTART': '1', 16:01:16 INFO - 'PAGER': '/bin/cat', 16:01:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:16 INFO - 'PWD': '/builds/slave/test', 16:01:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:16 INFO - 'SHELL': '/bin/bash', 16:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sR0C2X9hc1/Listeners', 16:01:16 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 16:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:16 INFO - 'USER': 'cltbld', 16:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:16 INFO - 'XPC_FLAGS': '0x0', 16:01:16 INFO - 'XPC_SERVICE_NAME': '0', 16:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:16 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/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 16:01:17 INFO - mozversion application_buildid: 20161225135937 16:01:17 INFO - mozversion application_changeset: 39582d06756a9ff47993d4097b4fb25f041be406 16:01:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:01:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:01:17 INFO - mozversion application_name: Firefox 16:01:17 INFO - mozversion application_remotingname: firefox-dev 16:01:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:01:17 INFO - mozversion application_vendor: Mozilla 16:01:17 INFO - mozversion application_version: 52.0a2 16:01:17 INFO - mozversion platform_buildid: 20161225135937 16:01:17 INFO - mozversion platform_changeset: 39582d06756a9ff47993d4097b4fb25f041be406 16:01:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:01:17 INFO - mozversion platform_version: 52.0a2 16:01:17 INFO - using testdate: 1482710477 16:01:17 INFO - actual date: 1482710477 16:01:17 INFO - starting webserver on 'localhost:49246' 16:01:17 INFO - SUITE-START | Running 2 tests 16:01:17 INFO - TEST-START | dromaeo_css 16:01:17 INFO - operating with platform_type : mac_ 16:01:17 INFO - Initialising browser for dromaeo_css test... 16:01:17 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpGfWOJw/profile http://localhost:49246/getInfo.html) 16:01:19 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 16:01:19 INFO - PROCESS | 1650 | colorDepth:24 16:01:19 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 16:01:19 INFO - PROCESS | 1650 | __metrics 16:01:20 INFO - PROCESS | 1650 | 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" 16:01:20 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:01:20 INFO - TEST-INFO | 1650: exit 0 16:01:20 INFO - Browser initialized. 16:01:20 INFO - Running cycle 1/1 for dromaeo_css test... 16:01:20 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpGfWOJw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:33 INFO - PROCESS | 1653 | RSS: Main: 223055872 16:01:33 INFO - PROCESS | 1653 | 16:02:24 INFO - PROCESS | 1653 | 1482710544581 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] 16:04:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 16:04:39 INFO - PROCESS | 1653 | RSS: Main: 368250880 16:04:39 INFO - PROCESS | 1653 | 16:07:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 16:07:43 INFO - PROCESS | 1653 | RSS: Main: 346767360 16:07:43 INFO - PROCESS | 1653 | 16:10:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 16:10:46 INFO - PROCESS | 1653 | RSS: Main: 322916352 16:10:46 INFO - PROCESS | 1653 | 16:13:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 16:13:49 INFO - PROCESS | 1653 | RSS: Main: 313954304 16:13:49 INFO - PROCESS | 1653 | 16:16:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 16:16:53 INFO - PROCESS | 1653 | RSS: Main: 306597888 16:16:53 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 16:19:56 INFO - PROCESS | 1653 | RSS: Main: 359469056 16:19:56 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | __start_tp_report 16:19:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 16:19:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 16:19:56 INFO - PROCESS | 1653 | |i|pagename|runs| 16:19:56 INFO - PROCESS | 1653 | |0;dojo.html;3343.047231539012;3348.916277093073;3373.0555444475553;3403.7447191460615;3412.164019815146;4682.578567928886;4794.568488836008;5595.832125036245;5645.774169033239;5655.179998900157;4731.290306454033;5628.774849006045;5676.176954341618;5702.486776190142;5705.087186050222;5134.845954621367;5160.432352441228;5180.715060671661;5256.264123022761;5269.314989051418;5138.434772175057;5151.278820965068;5165.121929272014;5201.141811601081;5295.258663787073;5161.870953226162;5180.326557547513;5186.377634683843;5229.712365819878;5249.8950020999555;5203.609729270282;5208.3489563804715;5225.921611175835;5270.314859068297;5282.286891269668;5154.433012368637;5217;5217;5226.52961233487;5293.761780719877;4718.976405117953;5531.893621275721;5539.9169012464845;5578.832635020915;5604.523615492688;6107.725152368154;6115.847103822395;6156.758237652896;6176.567640265138;6217.316095229521;6229.190205273331;6233.158523599347;6260.248770147611;6271.592346497463;6277.183966084425;5657.547396208376;5706.572007099484;5719.142128680731;5748.166515855261;5755.712214389222;6182.567220294626;6220.471259942956;6223.377662233831;6228;6235.781747638902;6120.051392034242;6142.477889379453;6147.139400483936;6149.907751383733;6156.569040167145;6049.032154855202;6113.174721412642;6113.296970848321;6141.539384546177;6202.131701561785;6126.969365153146;6183.84540386485;6197.659128747962;6204.81385558434;6212.223472065992;6135.233095863017;6141.938580614136;6199.907001394983;6223.471004964538;6230;4811.927821082687;4879.121758083508;4919.040787046563;4930.063287975345;4955.108080545537;4340.500842403164;4385.232584297735;4389.692721509464;4404.185225733249;4453.55464453552;5716.885662286731;5727.942720572824;5732.226149469851;5732.7706891723865;5742.13867919807;6214.130021796952;6238.781642642576;6251.062340648939;6260.217472815898;6260.593061450991;5515.531179849758;5652.717364131777;5671.149327600893;5681.460261275172;5731.025725626653;5350.3312085989255;5381.919271210935;5399.974004939049;5408.972295263966;5412.83761487156;6289.49684025286;6291.496680265568;6318.905216421757;6319.431251187415;6337.36626337381;5966.880662386815;6051.152838602511;6139.662318572343;6275.152854364603;6279.152314437492;6181.81454556364;6204.348543402878;6208.286047104641;6214.9067763982675;6316.463100636497;6154.107654390178;6174.567780255518;6175.660338681416;6184.752609895734;6186.443220110032;5955.940440595712;6219.564630475823;6251.187345645037;6252.280987686474;6291.96854015727;5930.940690593211;5963.493103086286;5972.850678733066;5994.3705910878725;6116.327204007466;6021.397860213943;6087.965045942112;6089.269287685329;6094.299155597163;6126.326104128453;5855.736491857791;5905.4685078343155;5912.615679980788;5918.940810591839;5937.376575459687;5912.674802885883;5977.551683623658;6059.878802424015;6082.057281121433;6089.573729839046;5397.9460205397445;5614.877024595015;5856.619319744203;5877.94122058774;5942.376050514634 16:19:56 INFO - PROCESS | 1653 | |1;ext.html;473.1743108276059;483.16171442547176;491.6484713429898;529.9735013249339;550.0017468295047;28176.859115704417;28951.710482895192;29260.56109158359;29314.413711725807;29362.559561606566;38923.61076389235;40307;40375.59624403755;41894.581054189446;41904.79047604765;9306.906930930672;9443.150116489514;9447.905520944805;9464.858027129598;9473.857892131624;9339.252859771203;9440.858387124199;9445.57494912727;9450.243980481579;9455.905440945571;9459.668911588096;9473.715788526355;9490.05099490049;9499;9520.714378568655;9288.349815512916;9418.717438476857;9434.433933963985;9441.386309889835;9443.480608566491;9357.251419886392;9407.858882116774;9420.905790942072;9430.387024843432;9445.905540944572;38107;38446.42330365047;38461.03847403809;38469;38475;3103.8603262853226;3220.500822372536;3246.8376581170846;3415.334009868077;3571.928561428757;28654.426911461862;29298.12105636834;29318.37722192224;29716.702832971827;29784.553231701542;3124.6406663233793;3127.1869313978527;3161.351922855809;3182.299894023334;3272.1655977725527;430063;430070;430428.8478557618;430806;432695;33084.3383132336;34049.14877128042;54945.1758223627;55397.892042159736;55470.722646387316;34372.82813585966;34918.12704682403;34929.825350873085;34987;35055.64944350575;14155;14308.356123974447;14317.570472885622;14323.928380358031;14334.641633959234;11605.477753501113;12017.879821201675;12143.635690929286;12167.513299468099;12174.208676436003;7685.615719214017;7722.382209423233;7785.3771698264;7792.064952205772;7807.375409967189;3227.8224697641385;3231.337575796956;3239.5626590410466;3282.081017315108;3289.227031647561;893.4415990006246;921.1169040901934;1006.9244806639531;1024.1243736605245;1054.7204990677476;28824.991125310513;28915.855420723183;29438.705612944024;29642.851785741364;29648.85175574108;829.6308142876362;886.2865393358279;994.8060128274917;1007.7077647482295;1078.8651418572679;14259.358328875225;15035.323410446555;15065;15404.46084387041;15432.92283538575;591112;683274;683846;686028;686449;49696.00607987892;54443.45556544384;55023.17465238024;55053.72473137689;55110;1236.9196002259823;1343.3081962789172;1354.5326862232514;1396.958091257264;1398.9230592317317;29058.7094129056;29713.98861153803;29831.552526711683;30699.693003069682;30794.846025769726;52013.69965750886;53905.46094539004;53994.730026349614;56514.434855650914;56559;626792;685733;710709.4464527644;714692;843680;38987.80506097451;39066;39222;39773.60226397699;39849;43304.56695433131;43422.91442713957;43506.56493435025;43741.125177496906;43978;35693;35764.4635330465;36190.09524761797;36607.08482287964;37472.625273748;36037.81981090078;36546.26907461783;36574.4513832298;36816.52733890614;37303.44044839384;35049.82475087608;35486.112847177996;36147.45778813267;36177.63822361848;36268.27463450663;30489.695103048685;32327.030189094367;32423;32546.674533254365;32652.346953061282;24048.797560122275;24568.754312456647;24709.382265443506;24984.75015249897;24987.375315617253 16:19:56 INFO - PROCESS | 1653 | |2;jquery.html;344.63296589132557;359.45542503107794;371.843825593251;374.87441707028154;394.58371418153837;14141.646458838533;14775.630609234775;15476.303566339513;17185.7422138668;19749.012549372546;16327.755083673754;18529.722054169164;19232.03839808011;19829.40511784645;19915.00424978749;5691.117876729103;6020.277566692003;6023.126646636257;6036.2152920120325;6047.637141771485;5605.215269862222;5865.58940874139;5921.496672782797;5968.820935371946;5977.671228082454;5796.188533605298;6019.187409699678;6022.066579680157;6042;6056.818295451144;5820.592558520926;5847.356790753014;5900.232969713953;5944.762209511614;5958.672273024982;5550.333959924835;5755;5762.394948530427;5764.16419619157;5765.1352297155345;12946.741065178738;13365.997550183774;13390.866091339156;13390.933045334808;13412.731745365038;1933.7002764571373;2090.1221486975355;2165.805077543013;2172.2180015194517;2859.4852926473227;9758.463284519348;9867.901320986843;9936.205103591768;10347.79304413915;10350.171986241157;1449.8622630850052;1833.578276996285;1833.8074502177212;1872.382113902409;1875.2499000399812;78337;111605;123251;126367.73632263743;130104;11142.610008649659;11253.774924501628;11280.605178818701;11471;11651.475683594259;14069.648258793408;14148.292585370897;14522.927385363217;14533.78199327011;14541.563753087425;8330.958345208235;8434.662613495391;8436.578171091422;8547.658093676184;8591.44155629882;8354.498730076215;8368.3305335573;8369.455985360932;8524.914750852537;8542.871856922151;4463.7991290391365;4539.31910213464;4560.042391097906;4591.517890621503;4652.720836749806;1633.5752704296967;1850.63912537054;1916.377177417345;1924.769027716683;1927.5181204698824;748.3788455581854;769.899913011305;794.5828440068894;905.8143080668447;909.4588719711761;19088;20718.792812071988;20794.688079678817;20832.270870519762;21314.36056918295;888.8577827547562;894.3918135667678;957.9329446938787;959.52023988006;970.9902900971041;10453.84319235212;10593.258471906893;10820.783584328427;10825.24223304377;10837.783244335069;525585;526311.368443163;526520;613031;624548;108843;125896;127861.36069319594;134341.32829335792;138534.92197617044;948.0188005414501;971.5482300730195;1001.4441984698417;1002.3384566186428;1005.1707341443339;18258.078707406752;18721.344752933583;18948.441960335178;18973.810261897757;19085.85484870912;53044.73477632587;54713.72643136918;55284.72357638186;56233.31300061005;56675.433245667016;71648;74813;75593.62203188949;76682;77515;7238.335182908066;7483.251674832473;7892.092409372766;7979.9601001994615;8009.439339246197;7108.431325494052;9270.6291748332;10701.357918524913;12220.439699936685;12306.938465307616;9920.206383489447;10574.788504230026;10746.785064298514;10752.13982881352;10853.511591978222;10525.684229473129;10549.472526373804;10634.41510716918;10798.568057277935;10846;10527.631532896312;10834.02493775564;11139.498722557666;11233.157513186381;11238.10095192399;7760.185180556186;7933.603319834101;8022.358211342962;8175.1416101308505;8238.176182381714;7958.243966823143;9526.190273826802;10614.522346494272;10948.56205751761;11090.445477726244 16:19:56 INFO - PROCESS | 1653 | |3;mootools.html;1296.760099381615;1455.6360909772554;1471.2423102102418;1629.3726915137668;1815.3374018483257;7063.6468176591225;7224.385927196195;7458.962705186474;7770.300672939435;8181.3863960203;8184.058833234165;8213.096559378479;8327.62525686344;8332.541710205936;8338.499690018603;2242.932712018634;2272.4432514034083;2275.0103704888425;2277.089164334263;2281.1445707859552;2322.9883850580723;2360.7875291223786;2379.2267513058237;2380.8571485710827;2384.129792625688;2304.8040916522114;2314.9884250578725;2338.251759436981;2338.4153961509624;2342.390978345625;2228.1087564973977;2247.819894555355;2247.8876056197123;2253.4704344479032;2280.464090938628;2316.0504193280844;2333.1484008337006;2333.661619065226;2340.5552944940405;2343.0159333080146;8173.71392001283;8329.916700833035;8331.708390206375;8352.624131914079;8364.121767214476;5021.949780502221;5053.4946505349535;5213.113770658997;5216.374035115772;5227.163653815409;3508.736844736655;3596.6403359664087;3601.603823579404;3605.080704420382;3606.2246616977477;1043.9530221139992;1065.6003998500562;1089.7874914391618;1096.873859506159;1099.901008909194;10923.344599324066;10974.012338889459;11012.834807477895;11013.449327533592;11038.503267352908;4328;4413.735175889393;4456.264716321771;4465.464144302639;4466;8527.786805329917;8655.13448655142;8705.564721763887;8724.520151391735;8732.825343493094;5111.258867464197;5115.053715062725;5118.974405128025;5119.180931051014;5126;4090.447789548373;4172.520160181557;4725.64557658177;4813.229883218738;4833.009233107205;3387;3421.8460169292443;3445.707114895212;3508.7719298245534;3512.2975404918607;867.6919693508839;1072.2547829258747;1074.4466599701161;1079.6922876980022;1088.1892989722708;729.1796728680234;737.6274981134493;772.3898120484754;837.392890154633;875.4484674654926;4510.143072816154;5339.439358867341;5451.264079349237;5634.464725851038;5656.91514627281;884.4737381258141;889.9777505562413;902.7246689759686;903.6340282185726;950.9429434233969;3640.2901434220603;3777.9433308500393;3781.9432708509394;3833.6933045356304;3835.6356146166067;9729.221662267002;10254.38473691566;10254.948725256327;10259.948700256451;10270.383776973256;10207.540660670287;10213.08082272599;10218.642347517949;10230.488475576192;10267.589296428208;7839.443414468758;7855.803604909923;7954.880676789853;7982.121966583672;8007.839843203104;3874.6706529945272;5252.684838909678;5343.4389389113585;5428.918566221589;5611.7194140292;9357.111074447917;9449.716508504756;9535.618575256913;9618.422894626345;9620.663276785559;9061.048589897966;9067.319951003834;9339.719808405487;9451.338406311492;9579.712608621752;6603.504737144637;6841.863162736817;6850.869606515069;7064.011038454723;7328.96335518319;7653.579053151907;7967.760967170994;7975.680972761024;8030.558319292261;8033.6786528538205;5785.884282314246;7113.786586402416;7225.963870180615;7264.818379540342;7394.186639469762;6858.965705171442;7036.366726994595;7110.253423390676;7125.82185445368;7161.534500257467;6454.5159113065765;6486.9351306488215;6581.506387021088;6599.307072757483;6621.304763000008;4758.381410416624;4789.544993225638;4831.251156070674;4859.101066302604;4864.683795553277;4527.1172121436;4567.611753001032;4577.931331030104;4716.33971244033;4753.881152971203 16:19:56 INFO - PROCESS | 1653 | |4;prototype.html;256.3872345095223;280.6940434925931;310.6179399338813;315.6685480245743;318.2870370370371;4816.879578010547;4966.751662416878;5012.498750124985;5211.872876274234;5239;4201.411802347666;4457.8662640120765;4999.975000125003;5141.82003629873;5157.76790044448;3356.362291164683;4067.5322337931075;4087.9386809197886;4091.9795401022952;4096.73371230869;3795.9430608540893;4062.1469491406842;4072.3687828386646;4073.816678249471;4081.7346872453195;3308.5277051711996;3419.863205471778;3914.3149948758996;3989.9202015959663;4012.879613611596;3735.1969326594776;3789.9242015159543;3810.5998870118515;3880.282147802637;3987.3819557968563;2925.634295713036;3464.8787292444645;3475.8435870385642;3486.960128324933;3491.476278558211;4090.6522945549664;5208.583313334924;5212.869678258036;5240.947590524084;5342.358916929955;4258.510271318807;4574.176648203322;4577.931331030004;4584.610308123813;4641.0949864776385;4656.254999200111;4902.3626928499425;5568.554515638739;5571.470710282517;5583.162525621189;3489.4820752972537;3825.852244326719;3834.69322454203;3837.424386342043;3843.9231215375817;4785.521447855222;5637.968852270606;5776.711164441761;5854.736536855809;5893.941060589382;3148.637906640766;3806.9340584636348;3828.693704503633;3865.3042452358845;3871.838448465449;3349.966500335014;3489.4068008438626;3922.5685174630767;3943.132510847609;3962.385830196324;4227.6406505447385;4569.657275704336;4586.6789324747615;4626.236670949255;4690.092142871374;4209;4618.976905115453;5387.622866399314;5523.834284971457;5597.888042239132;1043.6973277749369;1111.6109361723331;1164.6564263542275;1223.3760782001243;1230.7661544306552;801.4149670740276;811.6063709101081;814.5744946343898;870.1646419437285;870.7287360453682;4475.440569928759;4582.9541704582525;4998.550130488274;5077.217472982652;5077.568406685399;4848.442429120626;5329.467053294717;5408.59435542328;5459.997001649156;5492.258545096361;4169.895752606149;4357.70765795691;4415.801288942005;4645.260565244943;4862.902741945141;2458.5451691436783;2612.908548200804;2783.4850552647827;2795.762360199406;2848.0917785084043;5187.559057480081;5471.607603285988;5500.614956952975;5578.155140878182;5595.076812326002;4922.433920099163;4990.182510379563;5047.848564543069;5050.4697006814495;5471.261379713687;4760.619150468024;5261.158214685632;5468.507834294853;5518.806841760519;5538.3723661002105;4554.749488778149;4628.884277893079;5245.947540524546;5745.166950792028;5806.883862322815;5246.111399895958;5660.943390566206;5693.857653558529;5713.371529131709;5733.254676891977;5741.497686411202;5947.9702601486715;5950.095969289786;5950.315713692808;5963.2844058713235;3841.1741475582885;3864.5748967612976;3883.30100581898;3892.4355968384994;3894.240623078586;5664.971675141598;5772.624779389326;5779.092899343123;5779.734450035717;5794.565407594363;4709.693869898446;4924.753762311799;5330.280412144466;5590.7763689453595;5619.80330688424;4771.81841328798;5529.529989950899;5548.805791797267;5596.832095037155;5598.608097433141;4324.891877703082;5266.473352664703;5336.439673834347;5386.683725997702;5470.72646367672;4848.7090774553635;5282.788688452419;5563.666180029211;5570;5579.386267510652;4049.8785036448953;4536.72779633223;4895.534924182197;5105.004524117761;5457.672539647634 16:19:56 INFO - PROCESS | 1653 | |5;yui.html;675.4461341699802;682.4779044031313;692.6398272898094;705.59428328711;729.6935287179385;13902.443902243911;14482.637934051654;14572.708545829077;14572.70854582909;14574.781378279335;12449.439775210114;13034.543790967318;13115.081944263906;13123;13130.540431084914;783.0603276068712;797.7925739307793;798.7803354077646;800.8358286551272;801.8636831738619;623.6164758673406;647.808896375568;649.9285078641345;770.8034451214933;793.4683761879526;831.8918540589715;847.3052097280232;847.4195176304221;848.270487380855;848.6011574559947;822.5106061893164;839.7648658375665;841.3437518735366;842.296682270305;842.5408152556871;486.0934357423415;498.3820063121703;594.925634295713;601.3145014683265;611.4710775179482;13945.511907083304;13989.440422383092;13990;13995.860041399557;14012.22932738699;12131.757364852654;12303.75392492154;12418.192817466926;12420.875791242062;12651.557195498206;11698.824517632242;11721.648350549498;11865.52537898483;11937.224080434744;11944.940275298568;4035.8183881725395;4118.794060297003;4263.637590804786;4312.396264522938;4319.33050377192;80371.59814200892;80558.59720701417;80755.19244807535;82420.58789706013;84963.72554411751;8536.70121545743;9617.326787124974;10054.145397641136;10089.798204035878;10374.533146008447;18266.634667306844;18968.905155474135;19018.52453688669;19050;19228.71156932647;7373.262673732584;7385.409167266605;7402.074740657418;7511.985881905982;7557.244275572509;4019.9196016079513;4060.6548443382053;4174.7077704561;4197.97901010493;4257.063446041866;3125.5155450905145;3234.110619579634;3234.886778962725;3238.0933338665213;3238.6437491875877;615.7813976038442;617.8331850400367;624.9218847644045;627.6359711367396;654.9770758023542;493.5927859515914;598.8981873081586;604.1602172979474;617.2222999021176;640.4012248547623;7510.962445187739;7685.730999414994;7692.92307076922;8417.721834869813;8565.914340856636;554.7808615596816;575.008111008507;575.4676923845426;586.6568057686301;609.387565496673;3920.3727403615285;4091.97954010228;4120.566565482456;4132.152908653718;4203.4115223868685;70030;70275.29724702687;95023.0497695028;99733;106935.39596906053;63715;68993.31006689969;74173;90255;95034;62155.689221553606;81269.34447905482;81914.59042704749;83302;86978;6978.685959131953;7971.3112707149485;8010.359171266401;8134.43058985889;8300.543470109202;52341.21488177604;54256.914861703335;54552.90894182015;54859;55300.72349638226;51225.74387128041;59079.70460147672;65821;68878.24487020462;79218.60390698009;10038.046385593358;10381.221408394551;10566.154707623526;10570.154387649123;10597.364158150536;10764.40795756241;11002.174836887105;11112.333260004425;11274.661760147208;11714.531418743154;9271.72184834429;10432.530536126045;10961.945190273998;11002.33985960845;11066.667999960015;10544.103751181236;10694.946525267635;10846.511906964048;10905.890941090487;11015.72460688457;8752.78118047054;10429.26995110335;10501.632442864464;10730.087942524973;10832.350058996486;8784.824303514022;8811.251043661105;9332.07246665361;9620.518974051129;9709.271804614824;8560.828783424173;9000.739896414376;9001.729948101567;9030.683926062819;9436.338197081695 16:19:56 INFO - PROCESS | 1653 | __end_tp_report 16:19:56 INFO - PROCESS | 1653 | __start_cc_report 16:19:56 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1703 16:19:56 INFO - PROCESS | 1653 | __end_cc_report 16:19:56 INFO - PROCESS | 1653 | __startTimestamp1482711596757__endTimestamp 16:19:56 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | ------- Summary: start ------- 16:19:56 INFO - PROCESS | 1653 | Number of tests: 6 16:19:56 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5687.14 Median:5912.67 stddev:642.75 (10.9%) stddev-sans-first:617.85 16:19:56 INFO - PROCESS | 1653 | Values: 3343.0 3348.9 3373.1 3403.7 3412.2 4682.6 4794.6 5595.8 5645.8 5655.2 4731.3 5628.8 5676.2 5702.5 5705.1 5134.8 5160.4 5180.7 5256.3 5269.3 5138.4 5151.3 5165.1 5201.1 5295.3 5161.9 5180.3 5186.4 5229.7 5249.9 5203.6 5208.3 5225.9 5270.3 5282.3 5154.4 5217.0 5217.0 5226.5 5293.8 4719.0 5531.9 5539.9 5578.8 5604.5 6107.7 6115.8 6156.8 6176.6 6217.3 6229.2 6233.2 6260.2 6271.6 6277.2 5657.5 5706.6 5719.1 5748.2 5755.7 6182.6 6220.5 6223.4 6228.0 6235.8 6120.1 6142.5 6147.1 6149.9 6156.6 6049.0 6113.2 6113.3 6141.5 6202.1 6127.0 6183.8 6197.7 6204.8 6212.2 6135.2 6141.9 6199.9 6223.5 6230.0 4811.9 4879.1 4919.0 4930.1 4955.1 4340.5 4385.2 4389.7 4404.2 4453.6 5716.9 5727.9 5732.2 5732.8 5742.1 6214.1 6238.8 6251.1 6260.2 6260.6 5515.5 5652.7 5671.1 5681.5 5731.0 5350.3 5381.9 5400.0 5409.0 5412.8 6289.5 6291.5 6318.9 6319.4 6337.4 5966.9 6051.2 6139.7 6275.2 6279.2 6181.8 6204.3 6208.3 6214.9 6316.5 6154.1 6174.6 6175.7 6184.8 6186.4 5955.9 6219.6 6251.2 6252.3 6292.0 5930.9 5963.5 5972.9 5994.4 6116.3 6021.4 6088.0 6089.3 6094.3 6126.3 5855.7 5905.5 5912.6 5918.9 5937.4 5912.7 5977.6 6059.9 6082.1 6089.6 5397.9 5614.9 5856.6 5877.9 5942.4 16:19:56 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:71063.28 Median:37388.03 stddev:166566.40 (445.5%) stddev-sans-first:166949.34 16:19:56 INFO - PROCESS | 1653 | Values: 473.2 483.2 491.6 530.0 550.0 28176.9 28951.7 29260.6 29314.4 29362.6 38923.6 40307.0 40375.6 41894.6 41904.8 9306.9 9443.2 9447.9 9464.9 9473.9 9339.3 9440.9 9445.6 9450.2 9455.9 9459.7 9473.7 9490.1 9499.0 9520.7 9288.3 9418.7 9434.4 9441.4 9443.5 9357.3 9407.9 9420.9 9430.4 9445.9 38107.0 38446.4 38461.0 38469.0 38475.0 3103.9 3220.5 3246.8 3415.3 3571.9 28654.4 29298.1 29318.4 29716.7 29784.6 3124.6 3127.2 3161.4 3182.3 3272.2 430063.0 430070.0 430428.8 430806.0 432695.0 33084.3 34049.1 54945.2 55397.9 55470.7 34372.8 34918.1 34929.8 34987.0 35055.6 14155.0 14308.4 14317.6 14323.9 14334.6 11605.5 12017.9 12143.6 12167.5 12174.2 7685.6 7722.4 7785.4 7792.1 7807.4 3227.8 3231.3 3239.6 3282.1 3289.2 893.4 921.1 1006.9 1024.1 1054.7 28825.0 28915.9 29438.7 29642.9 29648.9 829.6 886.3 994.8 1007.7 1078.9 14259.4 15035.3 15065.0 15404.5 15432.9 591112.0 683274.0 683846.0 686028.0 686449.0 49696.0 54443.5 55023.2 55053.7 55110.0 1236.9 1343.3 1354.5 1397.0 1398.9 29058.7 29714.0 29831.6 30699.7 30794.8 52013.7 53905.5 53994.7 56514.4 56559.0 626792.0 685733.0 710709.4 714692.0 843680.0 38987.8 39066.0 39222.0 39773.6 39849.0 43304.6 43422.9 43506.6 43741.1 43978.0 35693.0 35764.5 36190.1 36607.1 37472.6 36037.8 36546.3 36574.5 36816.5 37303.4 35049.8 35486.1 36147.5 36177.6 36268.3 30489.7 32327.0 32423.0 32546.7 32652.3 24048.8 24568.8 24709.4 24984.8 24987.4 16:19:56 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33251.05 Median:365.65 stddev:94681.55 (25894.1%) stddev-sans-first:94914.92 16:19:56 INFO - PROCESS | 1653 | Values: 344.6 359.5 371.8 374.9 394.6 14141.6 14775.6 15476.3 17185.7 19749.0 16327.8 18529.7 19232.0 19829.4 19915.0 5691.1 6020.3 6023.1 6036.2 6047.6 5605.2 5865.6 5921.5 5968.8 5977.7 5796.2 6019.2 6022.1 6042.0 6056.8 5820.6 5847.4 5900.2 5944.8 5958.7 5550.3 5755.0 5762.4 5764.2 5765.1 12946.7 13366.0 13390.9 13390.9 13412.7 1933.7 2090.1 2165.8 2172.2 2859.5 9758.5 9867.9 9936.2 10347.8 10350.2 1449.9 1833.6 1833.8 1872.4 1875.2 78337.0 111605.0 123251.0 126367.7 130104.0 11142.6 11253.8 11280.6 11471.0 11651.5 14069.6 14148.3 14522.9 14533.8 14541.6 8331.0 8434.7 8436.6 8547.7 8591.4 8354.5 8368.3 8369.5 8524.9 8542.9 4463.8 4539.3 4560.0 4591.5 4652.7 1633.6 1850.6 1916.4 1924.8 1927.5 748.4 769.9 794.6 905.8 909.5 19088.0 20718.8 20794.7 20832.3 21314.4 888.9 894.4 957.9 959.5 971.0 10453.8 10593.3 10820.8 10825.2 10837.8 525585.0 526311.4 526520.0 613031.0 624548.0 108843.0 125896.0 127861.4 134341.3 138534.9 948.0 971.5 1001.4 1002.3 1005.2 18258.1 18721.3 18948.4 18973.8 19085.9 53044.7 54713.7 55284.7 56233.3 56675.4 71648.0 74813.0 75593.6 76682.0 77515.0 7238.3 7483.3 7892.1 7980.0 8009.4 7108.4 9270.6 10701.4 12220.4 12306.9 9920.2 10574.8 10746.8 10752.1 10853.5 10525.7 10549.5 10634.4 10798.6 10846.0 10527.6 10834.0 11139.5 11233.2 11238.1 7760.2 7933.6 8022.4 8175.1 8238.2 7958.2 9526.2 10614.5 10948.6 11090.4 16:19:56 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5365.13 Median:5037.72 stddev:2984.68 (59.2%) stddev-sans-first:2977.39 16:19:56 INFO - PROCESS | 1653 | Values: 1296.8 1455.6 1471.2 1629.4 1815.3 7063.6 7224.4 7459.0 7770.3 8181.4 8184.1 8213.1 8327.6 8332.5 8338.5 2242.9 2272.4 2275.0 2277.1 2281.1 2323.0 2360.8 2379.2 2380.9 2384.1 2304.8 2315.0 2338.3 2338.4 2342.4 2228.1 2247.8 2247.9 2253.5 2280.5 2316.1 2333.1 2333.7 2340.6 2343.0 8173.7 8329.9 8331.7 8352.6 8364.1 5021.9 5053.5 5213.1 5216.4 5227.2 3508.7 3596.6 3601.6 3605.1 3606.2 1044.0 1065.6 1089.8 1096.9 1099.9 10923.3 10974.0 11012.8 11013.4 11038.5 4328.0 4413.7 4456.3 4465.5 4466.0 8527.8 8655.1 8705.6 8724.5 8732.8 5111.3 5115.1 5119.0 5119.2 5126.0 4090.4 4172.5 4725.6 4813.2 4833.0 3387.0 3421.8 3445.7 3508.8 3512.3 867.7 1072.3 1074.4 1079.7 1088.2 729.2 737.6 772.4 837.4 875.4 4510.1 5339.4 5451.3 5634.5 5656.9 884.5 890.0 902.7 903.6 950.9 3640.3 3777.9 3781.9 3833.7 3835.6 9729.2 10254.4 10254.9 10259.9 10270.4 10207.5 10213.1 10218.6 10230.5 10267.6 7839.4 7855.8 7954.9 7982.1 8007.8 3874.7 5252.7 5343.4 5428.9 5611.7 9357.1 9449.7 9535.6 9618.4 9620.7 9061.0 9067.3 9339.7 9451.3 9579.7 6603.5 6841.9 6850.9 7064.0 7329.0 7653.6 7967.8 7975.7 8030.6 8033.7 5785.9 7113.8 7226.0 7264.8 7394.2 6859.0 7036.4 7110.3 7125.8 7161.5 6454.5 6486.9 6581.5 6599.3 6621.3 4758.4 4789.5 4831.3 4859.1 4864.7 4527.1 4567.6 4577.9 4716.3 4753.9 16:19:56 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4382.44 Median:4801.20 stddev:1361.16 (28.4%) stddev-sans-first:1329.28 16:19:56 INFO - PROCESS | 1653 | Values: 256.4 280.7 310.6 315.7 318.3 4816.9 4966.8 5012.5 5211.9 5239.0 4201.4 4457.9 5000.0 5141.8 5157.8 3356.4 4067.5 4087.9 4092.0 4096.7 3795.9 4062.1 4072.4 4073.8 4081.7 3308.5 3419.9 3914.3 3989.9 4012.9 3735.2 3789.9 3810.6 3880.3 3987.4 2925.6 3464.9 3475.8 3487.0 3491.5 4090.7 5208.6 5212.9 5240.9 5342.4 4258.5 4574.2 4577.9 4584.6 4641.1 4656.3 4902.4 5568.6 5571.5 5583.2 3489.5 3825.9 3834.7 3837.4 3843.9 4785.5 5638.0 5776.7 5854.7 5893.9 3148.6 3806.9 3828.7 3865.3 3871.8 3350.0 3489.4 3922.6 3943.1 3962.4 4227.6 4569.7 4586.7 4626.2 4690.1 4209.0 4619.0 5387.6 5523.8 5597.9 1043.7 1111.6 1164.7 1223.4 1230.8 801.4 811.6 814.6 870.2 870.7 4475.4 4583.0 4998.6 5077.2 5077.6 4848.4 5329.5 5408.6 5460.0 5492.3 4169.9 4357.7 4415.8 4645.3 4862.9 2458.5 2612.9 2783.5 2795.8 2848.1 5187.6 5471.6 5500.6 5578.2 5595.1 4922.4 4990.2 5047.8 5050.5 5471.3 4760.6 5261.2 5468.5 5518.8 5538.4 4554.7 4628.9 5245.9 5745.2 5806.9 5246.1 5660.9 5693.9 5713.4 5733.3 5741.5 5948.0 5950.1 5950.3 5963.3 3841.2 3864.6 3883.3 3892.4 3894.2 5665.0 5772.6 5779.1 5779.7 5794.6 4709.7 4924.8 5330.3 5590.8 5619.8 4771.8 5529.5 5548.8 5596.8 5598.6 4324.9 5266.5 5336.4 5386.7 5470.7 4848.7 5282.8 5563.7 5570.0 5579.4 4049.9 4536.7 4895.5 5105.0 5457.7 16:19:56 INFO - PROCESS | 1653 | 16:19:56 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:18271.29 Median:29837.32 stddev:26375.55 (88.4%) stddev-sans-first:26416.45 16:19:56 INFO - PROCESS | 1653 | Values: 675.4 682.5 692.6 705.6 729.7 13902.4 14482.6 14572.7 14572.7 14574.8 12449.4 13034.5 13115.1 13123.0 13130.5 783.1 797.8 798.8 800.8 801.9 623.6 647.8 649.9 770.8 793.5 831.9 847.3 847.4 848.3 848.6 822.5 839.8 841.3 842.3 842.5 486.1 498.4 594.9 601.3 611.5 13945.5 13989.4 13990.0 13995.9 14012.2 12131.8 12303.8 12418.2 12420.9 12651.6 11698.8 11721.6 11865.5 11937.2 11944.9 4035.8 4118.8 4263.6 4312.4 4319.3 80371.6 80558.6 80755.2 82420.6 84963.7 8536.7 9617.3 10054.1 10089.8 10374.5 18266.6 18968.9 19018.5 19050.0 19228.7 7373.3 7385.4 7402.1 7512.0 7557.2 4019.9 4060.7 4174.7 4198.0 4257.1 3125.5 3234.1 3234.9 3238.1 3238.6 615.8 617.8 624.9 627.6 655.0 493.6 598.9 604.2 617.2 640.4 7511.0 7685.7 7692.9 8417.7 8565.9 554.8 575.0 575.5 586.7 609.4 3920.4 4092.0 4120.6 4132.2 4203.4 70030.0 70275.3 95023.0 99733.0 106935.4 63715.0 68993.3 74173.0 90255.0 95034.0 62155.7 81269.3 81914.6 83302.0 86978.0 6978.7 7971.3 8010.4 8134.4 8300.5 52341.2 54256.9 54552.9 54859.0 55300.7 51225.7 59079.7 65821.0 68878.2 79218.6 10038.0 10381.2 10566.2 10570.2 10597.4 10764.4 11002.2 11112.3 11274.7 11714.5 9271.7 10432.5 10961.9 11002.3 11066.7 10544.1 10694.9 10846.5 10905.9 11015.7 8752.8 10429.3 10501.6 10730.1 10832.4 8784.8 8811.3 9332.1 9620.5 9709.3 8560.8 9000.7 9001.7 9030.7 9436.3 16:19:56 INFO - PROCESS | 1653 | -------- Summary: end -------- 16:19:56 INFO - PROCESS | 1653 | 16:19:57 INFO - TEST-INFO | 1653: exit 0 16:19:57 INFO - TEST-OK | dromaeo_css | took 1120245ms 16:19:57 INFO - TEST-START | kraken 16:19:57 INFO - operating with platform_type : mac_ 16:19:57 INFO - Initialising browser for kraken test... 16:19:57 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpYSAPMU/profile http://localhost:49246/getInfo.html) 16:19:59 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 16:19:59 INFO - PROCESS | 1658 | colorDepth:24 16:19:59 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 16:19:59 INFO - PROCESS | 1658 | __metrics 16:20:00 INFO - PROCESS | 1658 | 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" 16:20:00 INFO - PROCESS | 1658 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:20:00 INFO - TEST-INFO | 1658: exit 0 16:20:00 INFO - Browser initialized. 16:20:00 INFO - Running cycle 1/1 for kraken test... 16:20:00 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpYSAPMU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:13 INFO - PROCESS | 1659 | RSS: Main: 223010816 16:20:13 INFO - PROCESS | 1659 | 16:20:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 16:20:17 INFO - PROCESS | 1659 | RSS: Main: 270655488 16:20:17 INFO - PROCESS | 1659 | 16:20:19 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 16:20:20 INFO - PROCESS | 1659 | RSS: Main: 284172288 16:20:20 INFO - PROCESS | 1659 | 16:20:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 16:20:23 INFO - PROCESS | 1659 | RSS: Main: 382119936 16:20:23 INFO - PROCESS | 1659 | 16:20:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 16:20:26 INFO - PROCESS | 1659 | RSS: Main: 284659712 16:20:26 INFO - PROCESS | 1659 | 16:20:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:20:29 INFO - PROCESS | 1659 | RSS: Main: 286711808 16:20:29 INFO - PROCESS | 1659 | 16:20:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 16:20:32 INFO - PROCESS | 1659 | RSS: Main: 325771264 16:20:32 INFO - PROCESS | 1659 | 16:20:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 16:20:36 INFO - PROCESS | 1659 | RSS: Main: 326504448 16:20:36 INFO - PROCESS | 1659 | 16:20:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 16:20:39 INFO - PROCESS | 1659 | RSS: Main: 323878912 16:20:39 INFO - PROCESS | 1659 | 16:20:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:20:41 INFO - PROCESS | 1659 | RSS: Main: 299155456 16:20:41 INFO - PROCESS | 1659 | 16:20:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:20:44 INFO - PROCESS | 1659 | RSS: Main: 301649920 16:20:44 INFO - PROCESS | 1659 | 16:20:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:20:47 INFO - PROCESS | 1659 | RSS: Main: 304766976 16:20:47 INFO - PROCESS | 1659 | 16:20:49 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:20:50 INFO - PROCESS | 1659 | RSS: Main: 313581568 16:20:50 INFO - PROCESS | 1659 | 16:20:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:20:54 INFO - PROCESS | 1659 | RSS: Main: 313716736 16:20:54 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 16:20:56 INFO - PROCESS | 1659 | RSS: Main: 357167104 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | __start_tp_report 16:20:56 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 16:20:56 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 16:20:56 INFO - PROCESS | 1659 | |i|pagename|runs| 16:20:56 INFO - PROCESS | 1659 | |0;ai-astar;105;115;115;116;115;113;114;115;115;115 16:20:56 INFO - PROCESS | 1659 | |1;audio-beat-detection;145;110;111;143;112;114;111;107;140;109 16:20:56 INFO - PROCESS | 1659 | |2;audio-dft;185;182;184;187;186;184;186;186;186;184 16:20:56 INFO - PROCESS | 1659 | |3;audio-fft;82;60;59;88;59;60;59;59;81;57 16:20:56 INFO - PROCESS | 1659 | |4;audio-oscillator;88;72;95;70;69;70;73;54;56;112 16:20:56 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;91;87;91;88;90;87;91;88;90;87 16:20:56 INFO - PROCESS | 1659 | |6;imaging-darkroom;99;98;110;162;162;99;99;99;99;98 16:20:56 INFO - PROCESS | 1659 | |7;imaging-desaturate;83;82;78;78;81;82;85;84;81;82 16:20:56 INFO - PROCESS | 1659 | |8;json-parse-financial;56;55;54;54;59;59;60;58;58;57 16:20:56 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;70;73;75;72;74;70;69;69;70;71 16:20:56 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;83;84;82;82;86;83;83;81;86;83 16:20:56 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;141;141;146;136;138;143;143;144;181;157 16:20:56 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;166;165;163;166;168;165;163;161;167;165 16:20:56 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;68;68;66;68;66;71;89;88;86;80 16:20:56 INFO - PROCESS | 1659 | __end_tp_report 16:20:56 INFO - PROCESS | 1659 | __start_cc_report 16:20:56 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2102 16:20:56 INFO - PROCESS | 1659 | __end_cc_report 16:20:56 INFO - PROCESS | 1659 | __startTimestamp1482711656323__endTimestamp 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | ------- Summary: start ------- 16:20:56 INFO - PROCESS | 1659 | Number of tests: 14 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:3.19 (2.8%) stddev-sans-first:0.83 16:20:56 INFO - PROCESS | 1659 | Values: 105.0 115.0 115.0 116.0 115.0 113.0 114.0 115.0 115.0 115.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:120.20 Median:113.00 stddev:15.65 (13.9%) stddev-sans-first:13.79 16:20:56 INFO - PROCESS | 1659 | Values: 145.0 110.0 111.0 143.0 112.0 114.0 111.0 107.0 140.0 109.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:185.00 Median:186.00 stddev:1.49 (0.8%) stddev-sans-first:1.58 16:20:56 INFO - PROCESS | 1659 | Values: 185.0 182.0 184.0 187.0 186.0 184.0 186.0 186.0 186.0 184.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.00 stddev:12.08 (20.1%) stddev-sans-first:11.41 16:20:56 INFO - PROCESS | 1659 | Values: 82.0 60.0 59.0 88.0 59.0 60.0 59.0 59.0 81.0 57.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:71.00 stddev:17.74 (25.0%) stddev-sans-first:18.26 16:20:56 INFO - PROCESS | 1659 | Values: 88.0 72.0 95.0 70.0 69.0 70.0 73.0 54.0 56.0 112.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:90.00 stddev:1.76 (2.0%) stddev-sans-first:1.72 16:20:56 INFO - PROCESS | 1659 | Values: 91.0 87.0 91.0 88.0 90.0 87.0 91.0 88.0 90.0 87.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:99.00 stddev:26.33 (26.6%) stddev-sans-first:27.47 16:20:56 INFO - PROCESS | 1659 | Values: 99.0 98.0 110.0 162.0 162.0 99.0 99.0 99.0 99.0 98.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:82.00 stddev:2.27 (2.8%) stddev-sans-first:2.35 16:20:56 INFO - PROCESS | 1659 | Values: 83.0 82.0 78.0 78.0 81.0 82.0 85.0 84.0 81.0 82.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:58.00 stddev:2.16 (3.7%) stddev-sans-first:2.26 16:20:56 INFO - PROCESS | 1659 | Values: 56.0 55.0 54.0 54.0 59.0 59.0 60.0 58.0 58.0 57.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:2.11 (3.0%) stddev-sans-first:2.19 16:20:56 INFO - PROCESS | 1659 | Values: 70.0 73.0 75.0 72.0 74.0 70.0 69.0 69.0 70.0 71.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.00 stddev:1.64 (2.0%) stddev-sans-first:1.73 16:20:56 INFO - PROCESS | 1659 | Values: 83.0 84.0 82.0 82.0 86.0 83.0 83.0 81.0 86.0 83.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:143.50 stddev:13.22 (9.2%) stddev-sans-first:13.84 16:20:56 INFO - PROCESS | 1659 | Values: 141.0 141.0 146.0 136.0 138.0 143.0 143.0 144.0 181.0 157.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:165.50 stddev:2.08 (1.3%) stddev-sans-first:2.17 16:20:56 INFO - PROCESS | 1659 | Values: 166.0 165.0 163.0 166.0 168.0 165.0 163.0 161.0 167.0 165.0 16:20:56 INFO - PROCESS | 1659 | 16:20:56 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:75.50 stddev:9.64 (12.8%) stddev-sans-first:9.88 16:20:56 INFO - PROCESS | 1659 | Values: 68.0 68.0 66.0 68.0 66.0 71.0 89.0 88.0 86.0 80.0 16:20:56 INFO - PROCESS | 1659 | -------- Summary: end -------- 16:20:56 INFO - PROCESS | 1659 | 16:20:57 INFO - TEST-INFO | 1659: exit 0 16:20:57 INFO - TEST-OK | kraken | took 59947ms 16:20:57 INFO - SUITE-END | took 1180s 16:20:57 INFO - Completed test suite (00:19:40) 16:20:57 INFO - javascript benchmark 16:20:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3343.047231539012, 3348.916277093073, 3373.0555444475553, 3403.7447191460615, 3412.164019815146, 4682.578567928886, 4794.568488836008, 5595.832125036245, 5645.774169033239, 5655.179998900157, 4731.290306454033, 5628.774849006045, 5676.176954341618, 5702.486776190142, 5705.087186050222, 5134.845954621367, 5160.432352441228, 5180.715060671661, 5256.264123022761, 5269.314989051418, 5138.434772175057, 5151.278820965068, 5165.121929272014, 5201.141811601081, 5295.258663787073, 5161.870953226162, 5180.326557547513, 5186.377634683843, 5229.712365819878, 5249.8950020999555, 5203.609729270282, 5208.3489563804715, 5225.921611175835, 5270.314859068297, 5282.286891269668, 5154.433012368637, 5217.0, 5217.0, 5226.52961233487, 5293.761780719877, 4718.976405117953, 5531.893621275721, 5539.9169012464845, 5578.832635020915, 5604.523615492688, 6107.725152368154, 6115.847103822395, 6156.758237652896, 6176.567640265138, 6217.316095229521, 6229.190205273331, 6233.158523599347, 6260.248770147611, 6271.592346497463, 6277.183966084425, 5657.547396208376, 5706.572007099484, 5719.142128680731, 5748.166515855261, 5755.712214389222, 6182.567220294626, 6220.471259942956, 6223.377662233831, 6228.0, 6235.781747638902, 6120.051392034242, 6142.477889379453, 6147.139400483936, 6149.907751383733, 6156.569040167145, 6049.032154855202, 6113.174721412642, 6113.296970848321, 6141.539384546177, 6202.131701561785, 6126.969365153146, 6183.84540386485, 6197.659128747962, 6204.81385558434, 6212.223472065992, 6135.233095863017, 6141.938580614136, 6199.907001394983, 6223.471004964538, 6230.0, 4811.927821082687, 4879.121758083508, 4919.040787046563, 4930.063287975345, 4955.108080545537, 4340.500842403164, 4385.232584297735, 4389.692721509464, 4404.185225733249, 4453.55464453552, 5716.885662286731, 5727.942720572824, 5732.226149469851, 5732.7706891723865, 5742.13867919807, 6214.130021796952, 6238.781642642576, 6251.062340648939, 6260.217472815898, 6260.593061450991, 5515.531179849758, 5652.717364131777, 5671.149327600893, 5681.460261275172, 5731.025725626653, 5350.3312085989255, 5381.919271210935, 5399.974004939049, 5408.972295263966, 5412.83761487156, 6289.49684025286, 6291.496680265568, 6318.905216421757, 6319.431251187415, 6337.36626337381, 5966.880662386815, 6051.152838602511, 6139.662318572343, 6275.152854364603, 6279.152314437492, 6181.81454556364, 6204.348543402878, 6208.286047104641, 6214.9067763982675, 6316.463100636497, 6154.107654390178, 6174.567780255518, 6175.660338681416, 6184.752609895734, 6186.443220110032, 5955.940440595712, 6219.564630475823, 6251.187345645037, 6252.280987686474, 6291.96854015727, 5930.940690593211, 5963.493103086286, 5972.850678733066, 5994.3705910878725, 6116.327204007466, 6021.397860213943, 6087.965045942112, 6089.269287685329, 6094.299155597163, 6126.326104128453, 5855.736491857791, 5905.4685078343155, 5912.615679980788, 5918.940810591839, 5937.376575459687, 5912.674802885883, 5977.551683623658, 6059.878802424015, 6082.057281121433, 6089.573729839046, 5397.9460205397445, 5614.877024595015, 5856.619319744203, 5877.94122058774, 5942.376050514634], "value": 5646.444628015446, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.1743108276059, 483.16171442547176, 491.6484713429898, 529.9735013249339, 550.0017468295047, 28176.859115704417, 28951.710482895192, 29260.56109158359, 29314.413711725807, 29362.559561606566, 38923.61076389235, 40307.0, 40375.59624403755, 41894.581054189446, 41904.79047604765, 9306.906930930672, 9443.150116489514, 9447.905520944805, 9464.858027129598, 9473.857892131624, 9339.252859771203, 9440.858387124199, 9445.57494912727, 9450.243980481579, 9455.905440945571, 9459.668911588096, 9473.715788526355, 9490.05099490049, 9499.0, 9520.714378568655, 9288.349815512916, 9418.717438476857, 9434.433933963985, 9441.386309889835, 9443.480608566491, 9357.251419886392, 9407.858882116774, 9420.905790942072, 9430.387024843432, 9445.905540944572, 38107.0, 38446.42330365047, 38461.03847403809, 38469.0, 38475.0, 3103.8603262853226, 3220.500822372536, 3246.8376581170846, 3415.334009868077, 3571.928561428757, 28654.426911461862, 29298.12105636834, 29318.37722192224, 29716.702832971827, 29784.553231701542, 3124.6406663233793, 3127.1869313978527, 3161.351922855809, 3182.299894023334, 3272.1655977725527, 430063.0, 430070.0, 430428.8478557618, 430806.0, 432695.0, 33084.3383132336, 34049.14877128042, 54945.1758223627, 55397.892042159736, 55470.722646387316, 34372.82813585966, 34918.12704682403, 34929.825350873085, 34987.0, 35055.64944350575, 14155.0, 14308.356123974447, 14317.570472885622, 14323.928380358031, 14334.641633959234, 11605.477753501113, 12017.879821201675, 12143.635690929286, 12167.513299468099, 12174.208676436003, 7685.615719214017, 7722.382209423233, 7785.3771698264, 7792.064952205772, 7807.375409967189, 3227.8224697641385, 3231.337575796956, 3239.5626590410466, 3282.081017315108, 3289.227031647561, 893.4415990006246, 921.1169040901934, 1006.9244806639531, 1024.1243736605245, 1054.7204990677476, 28824.991125310513, 28915.855420723183, 29438.705612944024, 29642.851785741364, 29648.85175574108, 829.6308142876362, 886.2865393358279, 994.8060128274917, 1007.7077647482295, 1078.8651418572679, 14259.358328875225, 15035.323410446555, 15065.0, 15404.46084387041, 15432.92283538575, 591112.0, 683274.0, 683846.0, 686028.0, 686449.0, 49696.00607987892, 54443.45556544384, 55023.17465238024, 55053.72473137689, 55110.0, 1236.9196002259823, 1343.3081962789172, 1354.5326862232514, 1396.958091257264, 1398.9230592317317, 29058.7094129056, 29713.98861153803, 29831.552526711683, 30699.693003069682, 30794.846025769726, 52013.69965750886, 53905.46094539004, 53994.730026349614, 56514.434855650914, 56559.0, 626792.0, 685733.0, 710709.4464527644, 714692.0, 843680.0, 38987.80506097451, 39066.0, 39222.0, 39773.60226397699, 39849.0, 43304.56695433131, 43422.91442713957, 43506.56493435025, 43741.125177496906, 43978.0, 35693.0, 35764.4635330465, 36190.09524761797, 36607.08482287964, 37472.625273748, 36037.81981090078, 36546.26907461783, 36574.4513832298, 36816.52733890614, 37303.44044839384, 35049.82475087608, 35486.112847177996, 36147.45778813267, 36177.63822361848, 36268.27463450663, 30489.695103048685, 32327.030189094367, 32423.0, 32546.674533254365, 32652.346953061282, 24048.797560122275, 24568.754312456647, 24709.382265443506, 24984.75015249897, 24987.375315617253], "value": 18231.01358598827, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.63296589132557, 359.45542503107794, 371.843825593251, 374.87441707028154, 394.58371418153837, 14141.646458838533, 14775.630609234775, 15476.303566339513, 17185.7422138668, 19749.012549372546, 16327.755083673754, 18529.722054169164, 19232.03839808011, 19829.40511784645, 19915.00424978749, 5691.117876729103, 6020.277566692003, 6023.126646636257, 6036.2152920120325, 6047.637141771485, 5605.215269862222, 5865.58940874139, 5921.496672782797, 5968.820935371946, 5977.671228082454, 5796.188533605298, 6019.187409699678, 6022.066579680157, 6042.0, 6056.818295451144, 5820.592558520926, 5847.356790753014, 5900.232969713953, 5944.762209511614, 5958.672273024982, 5550.333959924835, 5755.0, 5762.394948530427, 5764.16419619157, 5765.1352297155345, 12946.741065178738, 13365.997550183774, 13390.866091339156, 13390.933045334808, 13412.731745365038, 1933.7002764571373, 2090.1221486975355, 2165.805077543013, 2172.2180015194517, 2859.4852926473227, 9758.463284519348, 9867.901320986843, 9936.205103591768, 10347.79304413915, 10350.171986241157, 1449.8622630850052, 1833.578276996285, 1833.8074502177212, 1872.382113902409, 1875.2499000399812, 78337.0, 111605.0, 123251.0, 126367.73632263743, 130104.0, 11142.610008649659, 11253.774924501628, 11280.605178818701, 11471.0, 11651.475683594259, 14069.648258793408, 14148.292585370897, 14522.927385363217, 14533.78199327011, 14541.563753087425, 8330.958345208235, 8434.662613495391, 8436.578171091422, 8547.658093676184, 8591.44155629882, 8354.498730076215, 8368.3305335573, 8369.455985360932, 8524.914750852537, 8542.871856922151, 4463.7991290391365, 4539.31910213464, 4560.042391097906, 4591.517890621503, 4652.720836749806, 1633.5752704296967, 1850.63912537054, 1916.377177417345, 1924.769027716683, 1927.5181204698824, 748.3788455581854, 769.899913011305, 794.5828440068894, 905.8143080668447, 909.4588719711761, 19088.0, 20718.792812071988, 20794.688079678817, 20832.270870519762, 21314.36056918295, 888.8577827547562, 894.3918135667678, 957.9329446938787, 959.52023988006, 970.9902900971041, 10453.84319235212, 10593.258471906893, 10820.783584328427, 10825.24223304377, 10837.783244335069, 525585.0, 526311.368443163, 526520.0, 613031.0, 624548.0, 108843.0, 125896.0, 127861.36069319594, 134341.32829335792, 138534.92197617044, 948.0188005414501, 971.5482300730195, 1001.4441984698417, 1002.3384566186428, 1005.1707341443339, 18258.078707406752, 18721.344752933583, 18948.441960335178, 18973.810261897757, 19085.85484870912, 53044.73477632587, 54713.72643136918, 55284.72357638186, 56233.31300061005, 56675.433245667016, 71648.0, 74813.0, 75593.62203188949, 76682.0, 77515.0, 7238.335182908066, 7483.251674832473, 7892.092409372766, 7979.9601001994615, 8009.439339246197, 7108.431325494052, 9270.6291748332, 10701.357918524913, 12220.439699936685, 12306.938465307616, 9920.206383489447, 10574.788504230026, 10746.785064298514, 10752.13982881352, 10853.511591978222, 10525.684229473129, 10549.472526373804, 10634.41510716918, 10798.568057277935, 10846.0, 10527.631532896312, 10834.02493775564, 11139.498722557666, 11233.157513186381, 11238.10095192399, 7760.185180556186, 7933.603319834101, 8022.358211342962, 8175.1416101308505, 8238.176182381714, 7958.243966823143, 9526.190273826802, 10614.522346494272, 10948.56205751761, 11090.445477726244], "value": 9114.34989210112, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.760099381615, 1455.6360909772554, 1471.2423102102418, 1629.3726915137668, 1815.3374018483257, 7063.6468176591225, 7224.385927196195, 7458.962705186474, 7770.300672939435, 8181.3863960203, 8184.058833234165, 8213.096559378479, 8327.62525686344, 8332.541710205936, 8338.499690018603, 2242.932712018634, 2272.4432514034083, 2275.0103704888425, 2277.089164334263, 2281.1445707859552, 2322.9883850580723, 2360.7875291223786, 2379.2267513058237, 2380.8571485710827, 2384.129792625688, 2304.8040916522114, 2314.9884250578725, 2338.251759436981, 2338.4153961509624, 2342.390978345625, 2228.1087564973977, 2247.819894555355, 2247.8876056197123, 2253.4704344479032, 2280.464090938628, 2316.0504193280844, 2333.1484008337006, 2333.661619065226, 2340.5552944940405, 2343.0159333080146, 8173.71392001283, 8329.916700833035, 8331.708390206375, 8352.624131914079, 8364.121767214476, 5021.949780502221, 5053.4946505349535, 5213.113770658997, 5216.374035115772, 5227.163653815409, 3508.736844736655, 3596.6403359664087, 3601.603823579404, 3605.080704420382, 3606.2246616977477, 1043.9530221139992, 1065.6003998500562, 1089.7874914391618, 1096.873859506159, 1099.901008909194, 10923.344599324066, 10974.012338889459, 11012.834807477895, 11013.449327533592, 11038.503267352908, 4328.0, 4413.735175889393, 4456.264716321771, 4465.464144302639, 4466.0, 8527.786805329917, 8655.13448655142, 8705.564721763887, 8724.520151391735, 8732.825343493094, 5111.258867464197, 5115.053715062725, 5118.974405128025, 5119.180931051014, 5126.0, 4090.447789548373, 4172.520160181557, 4725.64557658177, 4813.229883218738, 4833.009233107205, 3387.0, 3421.8460169292443, 3445.707114895212, 3508.7719298245534, 3512.2975404918607, 867.6919693508839, 1072.2547829258747, 1074.4466599701161, 1079.6922876980022, 1088.1892989722708, 729.1796728680234, 737.6274981134493, 772.3898120484754, 837.392890154633, 875.4484674654926, 4510.143072816154, 5339.439358867341, 5451.264079349237, 5634.464725851038, 5656.91514627281, 884.4737381258141, 889.9777505562413, 902.7246689759686, 903.6340282185726, 950.9429434233969, 3640.2901434220603, 3777.9433308500393, 3781.9432708509394, 3833.6933045356304, 3835.6356146166067, 9729.221662267002, 10254.38473691566, 10254.948725256327, 10259.948700256451, 10270.383776973256, 10207.540660670287, 10213.08082272599, 10218.642347517949, 10230.488475576192, 10267.589296428208, 7839.443414468758, 7855.803604909923, 7954.880676789853, 7982.121966583672, 8007.839843203104, 3874.6706529945272, 5252.684838909678, 5343.4389389113585, 5428.918566221589, 5611.7194140292, 9357.111074447917, 9449.716508504756, 9535.618575256913, 9618.422894626345, 9620.663276785559, 9061.048589897966, 9067.319951003834, 9339.719808405487, 9451.338406311492, 9579.712608621752, 6603.504737144637, 6841.863162736817, 6850.869606515069, 7064.011038454723, 7328.96335518319, 7653.579053151907, 7967.760967170994, 7975.680972761024, 8030.558319292261, 8033.6786528538205, 5785.884282314246, 7113.786586402416, 7225.963870180615, 7264.818379540342, 7394.186639469762, 6858.965705171442, 7036.366726994595, 7110.253423390676, 7125.82185445368, 7161.534500257467, 6454.5159113065765, 6486.9351306488215, 6581.506387021088, 6599.307072757483, 6621.304763000008, 4758.381410416624, 4789.544993225638, 4831.251156070674, 4859.101066302604, 4864.683795553277, 4527.1172121436, 4567.611753001032, 4577.931331030104, 4716.33971244033, 4753.881152971203], "value": 4322.572077676962, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.3872345095223, 280.6940434925931, 310.6179399338813, 315.6685480245743, 318.2870370370371, 4816.879578010547, 4966.751662416878, 5012.498750124985, 5211.872876274234, 5239.0, 4201.411802347666, 4457.8662640120765, 4999.975000125003, 5141.82003629873, 5157.76790044448, 3356.362291164683, 4067.5322337931075, 4087.9386809197886, 4091.9795401022952, 4096.73371230869, 3795.9430608540893, 4062.1469491406842, 4072.3687828386646, 4073.816678249471, 4081.7346872453195, 3308.5277051711996, 3419.863205471778, 3914.3149948758996, 3989.9202015959663, 4012.879613611596, 3735.1969326594776, 3789.9242015159543, 3810.5998870118515, 3880.282147802637, 3987.3819557968563, 2925.634295713036, 3464.8787292444645, 3475.8435870385642, 3486.960128324933, 3491.476278558211, 4090.6522945549664, 5208.583313334924, 5212.869678258036, 5240.947590524084, 5342.358916929955, 4258.510271318807, 4574.176648203322, 4577.931331030004, 4584.610308123813, 4641.0949864776385, 4656.254999200111, 4902.3626928499425, 5568.554515638739, 5571.470710282517, 5583.162525621189, 3489.4820752972537, 3825.852244326719, 3834.69322454203, 3837.424386342043, 3843.9231215375817, 4785.521447855222, 5637.968852270606, 5776.711164441761, 5854.736536855809, 5893.941060589382, 3148.637906640766, 3806.9340584636348, 3828.693704503633, 3865.3042452358845, 3871.838448465449, 3349.966500335014, 3489.4068008438626, 3922.5685174630767, 3943.132510847609, 3962.385830196324, 4227.6406505447385, 4569.657275704336, 4586.6789324747615, 4626.236670949255, 4690.092142871374, 4209.0, 4618.976905115453, 5387.622866399314, 5523.834284971457, 5597.888042239132, 1043.6973277749369, 1111.6109361723331, 1164.6564263542275, 1223.3760782001243, 1230.7661544306552, 801.4149670740276, 811.6063709101081, 814.5744946343898, 870.1646419437285, 870.7287360453682, 4475.440569928759, 4582.9541704582525, 4998.550130488274, 5077.217472982652, 5077.568406685399, 4848.442429120626, 5329.467053294717, 5408.59435542328, 5459.997001649156, 5492.258545096361, 4169.895752606149, 4357.70765795691, 4415.801288942005, 4645.260565244943, 4862.902741945141, 2458.5451691436783, 2612.908548200804, 2783.4850552647827, 2795.762360199406, 2848.0917785084043, 5187.559057480081, 5471.607603285988, 5500.614956952975, 5578.155140878182, 5595.076812326002, 4922.433920099163, 4990.182510379563, 5047.848564543069, 5050.4697006814495, 5471.261379713687, 4760.619150468024, 5261.158214685632, 5468.507834294853, 5518.806841760519, 5538.3723661002105, 4554.749488778149, 4628.884277893079, 5245.947540524546, 5745.166950792028, 5806.883862322815, 5246.111399895958, 5660.943390566206, 5693.857653558529, 5713.371529131709, 5733.254676891977, 5741.497686411202, 5947.9702601486715, 5950.095969289786, 5950.315713692808, 5963.2844058713235, 3841.1741475582885, 3864.5748967612976, 3883.30100581898, 3892.4355968384994, 3894.240623078586, 5664.971675141598, 5772.624779389326, 5779.092899343123, 5779.734450035717, 5794.565407594363, 4709.693869898446, 4924.753762311799, 5330.280412144466, 5590.7763689453595, 5619.80330688424, 4771.81841328798, 5529.529989950899, 5548.805791797267, 5596.832095037155, 5598.608097433141, 4324.891877703082, 5266.473352664703, 5336.439673834347, 5386.683725997702, 5470.72646367672, 4848.7090774553635, 5282.788688452419, 5563.666180029211, 5570.0, 5579.386267510652, 4049.8785036448953, 4536.72779633223, 4895.534924182197, 5105.004524117761, 5457.672539647634], "value": 3943.4274305596023, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.4461341699802, 682.4779044031313, 692.6398272898094, 705.59428328711, 729.6935287179385, 13902.443902243911, 14482.637934051654, 14572.708545829077, 14572.70854582909, 14574.781378279335, 12449.439775210114, 13034.543790967318, 13115.081944263906, 13123.0, 13130.540431084914, 783.0603276068712, 797.7925739307793, 798.7803354077646, 800.8358286551272, 801.8636831738619, 623.6164758673406, 647.808896375568, 649.9285078641345, 770.8034451214933, 793.4683761879526, 831.8918540589715, 847.3052097280232, 847.4195176304221, 848.270487380855, 848.6011574559947, 822.5106061893164, 839.7648658375665, 841.3437518735366, 842.296682270305, 842.5408152556871, 486.0934357423415, 498.3820063121703, 594.925634295713, 601.3145014683265, 611.4710775179482, 13945.511907083304, 13989.440422383092, 13990.0, 13995.860041399557, 14012.22932738699, 12131.757364852654, 12303.75392492154, 12418.192817466926, 12420.875791242062, 12651.557195498206, 11698.824517632242, 11721.648350549498, 11865.52537898483, 11937.224080434744, 11944.940275298568, 4035.8183881725395, 4118.794060297003, 4263.637590804786, 4312.396264522938, 4319.33050377192, 80371.59814200892, 80558.59720701417, 80755.19244807535, 82420.58789706013, 84963.72554411751, 8536.70121545743, 9617.326787124974, 10054.145397641136, 10089.798204035878, 10374.533146008447, 18266.634667306844, 18968.905155474135, 19018.52453688669, 19050.0, 19228.71156932647, 7373.262673732584, 7385.409167266605, 7402.074740657418, 7511.985881905982, 7557.244275572509, 4019.9196016079513, 4060.6548443382053, 4174.7077704561, 4197.97901010493, 4257.063446041866, 3125.5155450905145, 3234.110619579634, 3234.886778962725, 3238.0933338665213, 3238.6437491875877, 615.7813976038442, 617.8331850400367, 624.9218847644045, 627.6359711367396, 654.9770758023542, 493.5927859515914, 598.8981873081586, 604.1602172979474, 617.2222999021176, 640.4012248547623, 7510.962445187739, 7685.730999414994, 7692.92307076922, 8417.721834869813, 8565.914340856636, 554.7808615596816, 575.008111008507, 575.4676923845426, 586.6568057686301, 609.387565496673, 3920.3727403615285, 4091.97954010228, 4120.566565482456, 4132.152908653718, 4203.4115223868685, 70030.0, 70275.29724702687, 95023.0497695028, 99733.0, 106935.39596906053, 63715.0, 68993.31006689969, 74173.0, 90255.0, 95034.0, 62155.689221553606, 81269.34447905482, 81914.59042704749, 83302.0, 86978.0, 6978.685959131953, 7971.3112707149485, 8010.359171266401, 8134.43058985889, 8300.543470109202, 52341.21488177604, 54256.914861703335, 54552.90894182015, 54859.0, 55300.72349638226, 51225.74387128041, 59079.70460147672, 65821.0, 68878.24487020462, 79218.60390698009, 10038.046385593358, 10381.221408394551, 10566.154707623526, 10570.154387649123, 10597.364158150536, 10764.40795756241, 11002.174836887105, 11112.333260004425, 11274.661760147208, 11714.531418743154, 9271.72184834429, 10432.530536126045, 10961.945190273998, 11002.33985960845, 11066.667999960015, 10544.103751181236, 10694.946525267635, 10846.511906964048, 10905.890941090487, 11015.72460688457, 8752.78118047054, 10429.26995110335, 10501.632442864464, 10730.087942524973, 10832.350058996486, 8784.824303514022, 8811.251043661105, 9332.07246665361, 9620.518974051129, 9709.271804614824, 8560.828783424173, 9000.739896414376, 9001.729948101567, 9030.683926062819, 9436.338197081695], "value": 6664.165127934479, "unit": "score"}], "name": "dromaeo_css", "value": 6885.780768526041, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 115.0, 116.0, 115.0, 113.0, 114.0, 115.0, 115.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 111.0, 143.0, 112.0, 114.0, 111.0, 107.0, 140.0, 109.0], "value": 120.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 184.0, 187.0, 186.0, 184.0, 186.0, 186.0, 186.0, 184.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 59.0, 88.0, 59.0, 60.0, 59.0, 59.0, 81.0, 57.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 95.0, 70.0, 69.0, 70.0, 73.0, 54.0, 56.0, 112.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 91.0, 88.0, 90.0, 87.0, 91.0, 88.0, 90.0, 87.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 110.0, 162.0, 162.0, 99.0, 99.0, 99.0, 99.0, 98.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 78.0, 78.0, 81.0, 82.0, 85.0, 84.0, 81.0, 82.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 54.0, 59.0, 59.0, 60.0, 58.0, 58.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 75.0, 72.0, 74.0, 70.0, 69.0, 69.0, 70.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 82.0, 82.0, 86.0, 83.0, 83.0, 81.0, 86.0, 83.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 146.0, 136.0, 138.0, 143.0, 143.0, 144.0, 181.0, 157.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 163.0, 166.0, 168.0, 165.0, 163.0, 161.0, 167.0, 165.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 66.0, 68.0, 66.0, 71.0, 89.0, 88.0, 86.0, 80.0], "value": 75.0, "unit": "score"}], "name": "kraken", "value": 1442.9, "alertThreshold": 2.0}]} 16:20:57 INFO - Return code: 0 16:20:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:20:57 INFO - # TBPL SUCCESS # 16:20:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:20:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:20:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:20:57 INFO - 2016-12-25 16:20:57.615 screenresolution[1663:14080] starting screenresolution argv=screenresolution get 16:20:57 INFO - 2016-12-25 16:20:57.618 screenresolution[1663:14080] Display 0: 1600x1200x32@60 16:20:57 INFO - 2016-12-25 16:20:57.625 screenresolution[1664:14084] starting screenresolution argv=screenresolution list 16:20:57 INFO - Available Modes on Display 0 16:20:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:20:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:20:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:20:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:20:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:20:57 INFO - Intel Iris: 16:20:57 INFO - Chipset Model: Intel Iris 16:20:57 INFO - Type: GPU 16:20:57 INFO - Bus: Built-In 16:20:57 INFO - VRAM (Dynamic, Max): 1536 MB 16:20:57 INFO - Vendor: Intel (0x8086) 16:20:57 INFO - Device ID: 0x0a2e 16:20:57 INFO - Revision ID: 0x0009 16:20:57 INFO - Displays: 16:20:57 INFO - Display: 16:20:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:20:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:20:57 INFO - Display Serial Number: 1600x1200 60 16:20:57 INFO - Main Display: Yes 16:20:57 INFO - Mirror: Off 16:20:57 INFO - Online: Yes 16:20:57 INFO - Rotation: Supported 16:20:57 INFO - Return code: 0 16:20:57 INFO - Running post-action listener: _resource_record_post_action 16:20:57 INFO - [mozharness: 2016-12-26 00:20:57.881007Z] Finished run-tests step (success) 16:20:57 INFO - Running post-run listener: _resource_record_post_run 16:20:57 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 33557504; Write bytes: 661972992; Read time: 313; Write time: 1864 16:20:57 INFO - TinderboxPrint: CPU usage
53.4% 16:20:57 INFO - TinderboxPrint: I/O read bytes / time
33,557,504 / 313 16:20:57 INFO - TinderboxPrint: I/O write bytes / time
661,972,992 / 1,864 16:20:57 INFO - TinderboxPrint: CPU idle
2,260.4 (46.6%) 16:20:57 INFO - TinderboxPrint: CPU system
970.7 (20.0%) 16:20:57 INFO - TinderboxPrint: CPU user
1,618.7 (33.4%) 16:20:57 INFO - TinderboxPrint: Swap in / out
378,261,504 / 0 16:20:57 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340900352; Write bytes: 334390784; Read time: 26595; Write time: 610 16:20:57 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 32582656; Write bytes: 310936064; Read time: 298; Write time: 1205 16:20:58 INFO - Running post-run listener: _upload_blobber_files 16:20:58 INFO - Blob upload gear active. 16:20:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:20:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:20:58 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'] 16:20:58 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 16:20:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:20:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:20:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:20:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:59 INFO - (blobuploader) - INFO - Done attempting. 16:20:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:20:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:21:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:00 INFO - (blobuploader) - INFO - Done attempting. 16:21:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:21:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:03 INFO - (blobuploader) - INFO - Done attempting. 16:21:03 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:03 INFO - Return code: 0 16:21:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/995ecb91d258e1b334c7781837a19286ba36e95853bd444a9c614693e5089447f6325c0fe27c94b2d857dff91984f862182925c4baf3b00d105b63a9bb321474", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8526164fb4cf28964d4383dff641e2107cedb5a7eab3b7b9756ea0e59713fb8fbbb646320bc7c74ee53708e928ee53690a9f72fe15f2279fa61bad61a4cb551b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61a08417abb367f52a7bce5f0f15206f265996c148ac504e1314415153a3cbdfacde952db092bdfc8a2da90a88ec63ec67c8744048deceb842370741968c4b06"} 16:21:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:03 INFO - Contents: 16:21:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/995ecb91d258e1b334c7781837a19286ba36e95853bd444a9c614693e5089447f6325c0fe27c94b2d857dff91984f862182925c4baf3b00d105b63a9bb321474", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8526164fb4cf28964d4383dff641e2107cedb5a7eab3b7b9756ea0e59713fb8fbbb646320bc7c74ee53708e928ee53690a9f72fe15f2279fa61bad61a4cb551b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61a08417abb367f52a7bce5f0f15206f265996c148ac504e1314415153a3cbdfacde952db092bdfc8a2da90a88ec63ec67c8744048deceb842370741968c4b06"} 16:21:03 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:03 INFO - Copying logs to upload dir... 16:21:03 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.324881 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-25 16:21:03.488654) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 16:21:03.493228) ========= 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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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/995ecb91d258e1b334c7781837a19286ba36e95853bd444a9c614693e5089447f6325c0fe27c94b2d857dff91984f862182925c4baf3b00d105b63a9bb321474", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8526164fb4cf28964d4383dff641e2107cedb5a7eab3b7b9756ea0e59713fb8fbbb646320bc7c74ee53708e928ee53690a9f72fe15f2279fa61bad61a4cb551b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61a08417abb367f52a7bce5f0f15206f265996c148ac504e1314415153a3cbdfacde952db092bdfc8a2da90a88ec63ec67c8744048deceb842370741968c4b06"} build_url:https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/cIe4R2EJT0KwQg0Ydsj6wg/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/995ecb91d258e1b334c7781837a19286ba36e95853bd444a9c614693e5089447f6325c0fe27c94b2d857dff91984f862182925c4baf3b00d105b63a9bb321474", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8526164fb4cf28964d4383dff641e2107cedb5a7eab3b7b9756ea0e59713fb8fbbb646320bc7c74ee53708e928ee53690a9f72fe15f2279fa61bad61a4cb551b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61a08417abb367f52a7bce5f0f15206f265996c148ac504e1314415153a3cbdfacde952db092bdfc8a2da90a88ec63ec67c8744048deceb842370741968c4b06"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 16:21:03.531104) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 16:21:03.531487) ========= 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.DDqD3Qj9KR/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.sR0C2X9hc1/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.005317 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 16:21:03.579678) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 16:21:03.583036) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 16:21:03.583411) ========= ========= Total master_lag: 0.20 =========