builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0319 starttime: 1481731718.52 results: success (0) buildid: 20161214064001 builduid: 996d637145d44f09a99fbbf4b3c7f17c revision: 7bfeec8395e5ca28421d92f3bf51d56feafc0319 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.518961) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.519421) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.519739) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.552243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.552685) ========= 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.QuExDRTZRU/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.wcs7LgVfzd/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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-14 08:08:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.4M=0.001s 2016-12-14 08:08:38 (19.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130479 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.707959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.708353) ========= 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.QuExDRTZRU/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.wcs7LgVfzd/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.025571 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.760297) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:38.760806) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7bfeec8395e5ca28421d92f3bf51d56feafc0319 --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 7bfeec8395e5ca28421d92f3bf51d56feafc0319 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QuExDRTZRU/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.wcs7LgVfzd/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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-14 08:08:38,856 truncating revision to first 12 chars 2016-12-14 08:08:38,856 Setting DEBUG logging. 2016-12-14 08:08:38,856 attempt 1/10 2016-12-14 08:08:38,856 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7bfeec8395e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 08:08:39,503 unpacking tar archive at: mozilla-aurora-7bfeec8395e5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.921422 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:39.706827) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:39.707181) ========= script_repo_revision: 7bfeec8395e5ca28421d92f3bf51d56feafc0319 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:39.707621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:39.708496) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 08:08:39.731911) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-14 08:08:39.732294) ========= /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.QuExDRTZRU/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.wcs7LgVfzd/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:08:39 INFO - MultiFileLogger online at 20161214 08:08:39 in /builds/slave/test 08:08:39 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 08:08:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:08:39 INFO - {'append_to_log': False, 08:08:39 INFO - 'base_work_dir': '/builds/slave/test', 08:08:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:08:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:08:39 INFO - 'branch': 'Mozilla-Aurora', 08:08:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:08:39 INFO - 'config_files': ('talos/mac_config.py',), 08:08:39 INFO - 'default_actions': ('clobber', 08:08:39 INFO - 'read-buildbot-config', 08:08:39 INFO - 'download-and-extract', 08:08:39 INFO - 'populate-webroot', 08:08:39 INFO - 'create-virtualenv', 08:08:39 INFO - 'install', 08:08:39 INFO - 'run-tests'), 08:08:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:08:39 INFO - 'download_minidump_stackwalk': True, 08:08:39 INFO - 'download_symbols': 'ondemand', 08:08:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:08:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:08:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:08:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:08:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:08:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:08:39 INFO - 'installer_path': 'installer.exe', 08:08:39 INFO - 'log_level': 'info', 08:08:39 INFO - 'log_name': 'talos', 08:08:39 INFO - 'log_to_console': True, 08:08:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:08:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:08:39 INFO - 'opt_config_files': (), 08:08:39 INFO - 'pip_index': False, 08:08:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:39 INFO - 'cmd': ('bash', 08:08:39 INFO - '-c', 08:08:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:39 INFO - 'enabled': True, 08:08:39 INFO - 'halt_on_failure': False, 08:08:39 INFO - 'name': 'check_screen_resolution'},), 08:08:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:39 INFO - 'cmd': ('bash', 08:08:39 INFO - '-c', 08:08:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:39 INFO - 'enabled': True, 08:08:39 INFO - 'halt_on_failure': False, 08:08:39 INFO - 'name': 'check_screen_resolution'},), 08:08:39 INFO - 'run_cmd_checks_enabled': True, 08:08:39 INFO - 'sps_profile': False, 08:08:39 INFO - 'sps_profile_interval': 0, 08:08:39 INFO - 'suite': 'dromaeojs', 08:08:39 INFO - 'system_bits': '32', 08:08:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:08:39 INFO - 'title': 't-yosemite-r7-0319', 08:08:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:08:39 INFO - 'use_talos_json': True, 08:08:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:08:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:08:39 INFO - 'work_dir': 'build'} 08:08:39 INFO - [mozharness: 2016-12-14 16:08:39.930808Z] Running clobber step. 08:08:39 INFO - Running pre-action listener: _resource_record_pre_action 08:08:39 INFO - Running main action method: clobber 08:08:39 INFO - rmtree: /builds/slave/test/build 08:08:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:08:42 INFO - Running post-action listener: _resource_record_post_action 08:08:42 INFO - [mozharness: 2016-12-14 16:08:42.236598Z] Finished clobber step (success) 08:08:42 INFO - [mozharness: 2016-12-14 16:08:42.236740Z] Running read-buildbot-config step. 08:08:42 INFO - Running pre-action listener: _resource_record_pre_action 08:08:42 INFO - Running main action method: read_buildbot_config 08:08:42 INFO - Using buildbot properties: 08:08:42 INFO - { 08:08:42 INFO - "project": "", 08:08:42 INFO - "product": "firefox", 08:08:42 INFO - "script_repo_revision": "production", 08:08:42 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:08:42 INFO - "repository": "", 08:08:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:08:42 INFO - "buildid": "20161214064001", 08:08:42 INFO - "pgo_build": "False", 08:08:42 INFO - "basedir": "/builds/slave/test", 08:08:42 INFO - "buildnumber": 41, 08:08:42 INFO - "slavename": "t-yosemite-r7-0319", 08:08:42 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:08:42 INFO - "platform": "yosemite_r7", 08:08:42 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:08:42 INFO - "branch": "mozilla-aurora", 08:08:42 INFO - "repo_path": "releases/mozilla-aurora", 08:08:42 INFO - "revision": "7bfeec8395e5ca28421d92f3bf51d56feafc0319", 08:08:42 INFO - "stage_platform": "macosx64", 08:08:42 INFO - "builduid": "996d637145d44f09a99fbbf4b3c7f17c", 08:08:42 INFO - "slavebuilddir": "test" 08:08:42 INFO - } 08:08:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:08:42 INFO - Running post-action listener: _resource_record_post_action 08:08:42 INFO - [mozharness: 2016-12-14 16:08:42.239820Z] Finished read-buildbot-config step (success) 08:08:42 INFO - [mozharness: 2016-12-14 16:08:42.239918Z] Running download-and-extract step. 08:08:42 INFO - Running pre-action listener: _resource_record_pre_action 08:08:42 INFO - Running main action method: download_and_extract 08:08:42 INFO - mkdir: /builds/slave/test/build/tests 08:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:42 INFO - trying https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:08:42 INFO - Downloading https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/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 08:08:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/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 08:08:43 INFO - Downloaded 1199 bytes. 08:08:43 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:08:43 INFO - Using the following test package requirements: 08:08:43 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:08:43 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:43 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:08:43 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:43 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:08:43 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:43 INFO - u'jsshell-mac.zip'], 08:08:43 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:43 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:08:43 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:08:43 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:43 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:08:43 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:43 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:08:43 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:43 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:08:43 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:08:43 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:08:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:08:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:08:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:08:43 INFO - Fetch https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:08:44 INFO - Http code: 200 08:08:44 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 08:08:44 INFO - x-amz-cf-id: EglPK7M10un6xergtSRm8Hj27SWkB5Dr8qr5OldIqBMz6kGQogKMjA== 08:08:44 INFO - x-amz-version-id: 9z8YGeWiYHkxXxbLNkhn6hySLcwz0Scx 08:08:44 INFO - Expected file size: 16280940 08:08:44 INFO - Obtained file size: 16280940 08:08:50 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 08:08:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:08:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:08:50 INFO - Fetch https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:08:51 INFO - Http code: 200 08:08:51 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 08:08:51 INFO - x-amz-cf-id: zGQAQ4mNS_4D10AFw1jbJKWcSlkFMBB3weJ4uyNNRx6mDXsui4mFUw== 08:08:51 INFO - x-amz-version-id: 9z8YGeWiYHkxXxbLNkhn6hySLcwz0Scx 08:08:51 INFO - Expected file size: 16280940 08:08:51 INFO - Obtained file size: 16280940 08:08:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:08:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:08:58 INFO - Fetch https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:08:58 INFO - Http code: 200 08:08:58 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 08:08:58 INFO - x-amz-cf-id: Eg8si0a3MEXrdJYal0q4hb66sbBKPrpET9mYmhPCRo_7aNPWJsbTOw== 08:08:58 INFO - x-amz-version-id: 0I2DjqBNxCH1.HGJVvQZ.lF.0K5o1rtD 08:08:59 INFO - Expected file size: 14409038 08:08:59 INFO - Obtained file size: 14409038 08:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:59 INFO - trying https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:08:59 INFO - Downloading https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/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 08:09:02 INFO - Downloaded 109766849 bytes. 08:09:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:09:02 INFO - mkdir: /builds/slave/test/properties 08:09:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:09:02 INFO - Writing to file /builds/slave/test/properties/build_url 08:09:02 INFO - Contents: 08:09:02 INFO - build_url:https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:09:03 INFO - Running post-action listener: _resource_record_post_action 08:09:03 INFO - Running post-action listener: set_extra_try_arguments 08:09:03 INFO - [mozharness: 2016-12-14 16:09:03.253212Z] Finished download-and-extract step (success) 08:09:03 INFO - [mozharness: 2016-12-14 16:09:03.253342Z] Running populate-webroot step. 08:09:03 INFO - Running pre-action listener: _resource_record_pre_action 08:09:03 INFO - Running main action method: populate_webroot 08:09:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:09:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:09:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:09:03 INFO - '--tppagecycles', 08:09:03 INFO - '7'], 08:09:03 INFO - 'tests': ['tsvgm']}}, 08:09:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:09:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:09:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:09:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:09:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:03 INFO - 'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:03 INFO - 'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['damp', 'tps']}, 08:09:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:03 INFO - 'tests': ['damp', 'tps']}, 08:09:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['dromaeo_dom']}, 08:09:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:09:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['basic_compositor_video']}, 08:09:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:09:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['a11yr', 08:09:03 INFO - 'ts_paint', 08:09:03 INFO - 'tpaint', 08:09:03 INFO - 'sessionrestore', 08:09:03 INFO - 'sessionrestore_no_auto_restore', 08:09:03 INFO - 'tabpaint']}, 08:09:03 INFO - 'other-e10s': {'tests': ['a11yr', 08:09:03 INFO - 'ts_paint', 08:09:03 INFO - 'tpaint', 08:09:03 INFO - 'sessionrestore', 08:09:03 INFO - 'sessionrestore_no_auto_restore', 08:09:03 INFO - 'tabpaint']}, 08:09:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:09:03 INFO - 'ts_paint', 08:09:03 INFO - 'tpaint', 08:09:03 INFO - 'sessionrestore', 08:09:03 INFO - 'sessionrestore_no_auto_restore', 08:09:03 INFO - 'tabpaint']}, 08:09:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:09:03 INFO - 'ts_paint', 08:09:03 INFO - 'tpaint', 08:09:03 INFO - 'sessionrestore', 08:09:03 INFO - 'sessionrestore_no_auto_restore', 08:09:03 INFO - 'tabpaint']}, 08:09:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['a11yr', 08:09:03 INFO - 'ts_paint', 08:09:03 INFO - 'tpaint', 08:09:03 INFO - 'sessionrestore', 08:09:03 INFO - 'sessionrestore_no_auto_restore', 08:09:03 INFO - 'tabpaint']}, 08:09:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['a11yr', 08:09:03 INFO - 'ts_paint', 08:09:03 INFO - 'tpaint', 08:09:03 INFO - 'sessionrestore', 08:09:03 INFO - 'sessionrestore_no_auto_restore', 08:09:03 INFO - 'tabpaint']}, 08:09:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['tsvgx', 08:09:03 INFO - 'tsvgr_opacity', 08:09:03 INFO - 'tart', 08:09:03 INFO - 'tscrollx', 08:09:03 INFO - 'cart']}, 08:09:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:09:03 INFO - 'tsvgr_opacity', 08:09:03 INFO - 'tart', 08:09:03 INFO - 'tscrollx', 08:09:03 INFO - 'cart']}, 08:09:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:03 INFO - 'talos_options': ['--disable-e10s'], 08:09:03 INFO - 'tests': ['tp5o']}, 08:09:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:03 INFO - 'tests': ['tp5o']}, 08:09:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:03 INFO - 'talos_options': ['--disable-e10s', 08:09:03 INFO - '--xperf_path', 08:09:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:03 INFO - 'tests': ['tp5n']}, 08:09:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:03 INFO - 'talos_options': ['--xperf_path', 08:09:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:03 INFO - 'tests': ['tp5n']}}, 08:09:03 INFO - 'talos.zip': {'path': '', 08:09:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:09:03 INFO - Running post-action listener: _resource_record_post_action 08:09:03 INFO - [mozharness: 2016-12-14 16:09:03.272513Z] Finished populate-webroot step (success) 08:09:03 INFO - [mozharness: 2016-12-14 16:09:03.272648Z] Running create-virtualenv step. 08:09:03 INFO - Running pre-action listener: _resource_record_pre_action 08:09:03 INFO - Running main action method: create_virtualenv 08:09:03 INFO - Creating virtualenv /builds/slave/test/build/venv 08:09:03 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 08:09:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:09:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:09:03 INFO - Using real prefix '/tools/python27' 08:09:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:09:04 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:07 INFO - Installing pip.................done. 08:09:07 INFO - Return code: 0 08:09:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:09:07 INFO - Reading from file tmpfile_stdout 08:09:07 INFO - Output received: 08:09:07 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) 08:09:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09:07 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')]} 08:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:07 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')]} 08:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:07 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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:07 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 08:09:07 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 08:09:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:07 INFO - 'HOME': '/Users/cltbld', 08:09:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:07 INFO - 'LOGNAME': 'cltbld', 08:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:07 INFO - 'MOZ_NO_REMOTE': '1', 08:09:07 INFO - 'NO_EM_RESTART': '1', 08:09:07 INFO - 'PAGER': '/bin/cat', 08:09:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:07 INFO - 'PWD': '/builds/slave/test', 08:09:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:07 INFO - 'SHELL': '/bin/bash', 08:09:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:07 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:07 INFO - 'USER': 'cltbld', 08:09:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:07 INFO - 'XPC_FLAGS': '0x0', 08:09:07 INFO - 'XPC_SERVICE_NAME': '0', 08:09:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:07 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 08:09:07 INFO - Cleaning up... 08:09:07 INFO - Return code: 0 08:09:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:09:07 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')]} 08:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:07 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')]} 08:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:07 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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:07 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 08:09:07 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 08:09:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:07 INFO - 'HOME': '/Users/cltbld', 08:09:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:07 INFO - 'LOGNAME': 'cltbld', 08:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:07 INFO - 'MOZ_NO_REMOTE': '1', 08:09:07 INFO - 'NO_EM_RESTART': '1', 08:09:07 INFO - 'PAGER': '/bin/cat', 08:09:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:07 INFO - 'PWD': '/builds/slave/test', 08:09:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:07 INFO - 'SHELL': '/bin/bash', 08:09:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:07 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:07 INFO - 'USER': 'cltbld', 08:09:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:07 INFO - 'XPC_FLAGS': '0x0', 08:09:07 INFO - 'XPC_SERVICE_NAME': '0', 08:09:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:07 INFO - Downloading/unpacking psutil>=3.1.1 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:07 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 08:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:11 INFO - Installing collected packages: psutil 08:09:11 INFO - Running setup.py install for psutil 08:09:11 INFO - building 'psutil._psutil_osx' extension 08:09:11 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 08:09:11 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 08:09:12 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 08:09:12 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 08:09:12 INFO - building 'psutil._psutil_posix' extension 08:09:12 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 08:09:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:09:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:09:12 INFO - ^ 08:09:12 INFO - 1 warning generated. 08:09:12 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 08:09:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:12 INFO - Successfully installed psutil 08:09:12 INFO - Cleaning up... 08:09:12 INFO - Return code: 0 08:09:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:09:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:12 INFO - 'HOME': '/Users/cltbld', 08:09:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:12 INFO - 'LOGNAME': 'cltbld', 08:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:12 INFO - 'MOZ_NO_REMOTE': '1', 08:09:12 INFO - 'NO_EM_RESTART': '1', 08:09:12 INFO - 'PAGER': '/bin/cat', 08:09:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:12 INFO - 'PWD': '/builds/slave/test', 08:09:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:12 INFO - 'SHELL': '/bin/bash', 08:09:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:12 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:12 INFO - 'USER': 'cltbld', 08:09:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:12 INFO - 'XPC_FLAGS': '0x0', 08:09:12 INFO - 'XPC_SERVICE_NAME': '0', 08:09:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:09:15 INFO - Installing collected packages: mozsystemmonitor 08:09:15 INFO - Running setup.py install for mozsystemmonitor 08:09:15 INFO - Successfully installed mozsystemmonitor 08:09:15 INFO - Cleaning up... 08:09:15 INFO - Return code: 0 08:09:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:09:15 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')]} 08:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:15 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')]} 08:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:15 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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:15 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 08:09:15 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 08:09:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:15 INFO - 'HOME': '/Users/cltbld', 08:09:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:15 INFO - 'LOGNAME': 'cltbld', 08:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:15 INFO - 'MOZ_NO_REMOTE': '1', 08:09:15 INFO - 'NO_EM_RESTART': '1', 08:09:15 INFO - 'PAGER': '/bin/cat', 08:09:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:15 INFO - 'PWD': '/builds/slave/test', 08:09:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:15 INFO - 'SHELL': '/bin/bash', 08:09:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:15 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:15 INFO - 'USER': 'cltbld', 08:09:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:15 INFO - 'XPC_FLAGS': '0x0', 08:09:15 INFO - 'XPC_SERVICE_NAME': '0', 08:09:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:15 INFO - Downloading/unpacking jsonschema==2.5.1 08:09:15 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 08:09:15 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 08:09:15 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 08:09:15 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 08:09:15 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 08:09:15 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 08:09:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:09:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:09:18 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 08:09:18 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 08:09:18 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 08:09:18 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 08:09:18 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 08:09:18 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 08:09:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:09:19 INFO - warning: no files found matching '*.txt' 08:09:19 INFO - no previously-included directories found matching 'build' 08:09:19 INFO - no previously-included directories found matching 'dist' 08:09:19 INFO - no previously-included directories found matching '.git*' 08:09:19 INFO - Installing collected packages: jsonschema, functools32 08:09:19 INFO - Running setup.py install for functools32 08:09:19 INFO - warning: no files found matching '*.txt' 08:09:19 INFO - no previously-included directories found matching 'build' 08:09:19 INFO - no previously-included directories found matching 'dist' 08:09:19 INFO - no previously-included directories found matching '.git*' 08:09:19 INFO - Successfully installed jsonschema functools32 08:09:19 INFO - Cleaning up... 08:09:19 INFO - Return code: 0 08:09:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:09:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:19 INFO - 'HOME': '/Users/cltbld', 08:09:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:19 INFO - 'LOGNAME': 'cltbld', 08:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:19 INFO - 'MOZ_NO_REMOTE': '1', 08:09:19 INFO - 'NO_EM_RESTART': '1', 08:09:19 INFO - 'PAGER': '/bin/cat', 08:09:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:19 INFO - 'PWD': '/builds/slave/test', 08:09:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:19 INFO - 'SHELL': '/bin/bash', 08:09:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:19 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:19 INFO - 'USER': 'cltbld', 08:09:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:19 INFO - 'XPC_FLAGS': '0x0', 08:09:19 INFO - 'XPC_SERVICE_NAME': '0', 08:09:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:09:19 INFO - Cleaning up... 08:09:19 INFO - Return code: 0 08:09:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:09:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:19 INFO - 'HOME': '/Users/cltbld', 08:09:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:19 INFO - 'LOGNAME': 'cltbld', 08:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:19 INFO - 'MOZ_NO_REMOTE': '1', 08:09:19 INFO - 'NO_EM_RESTART': '1', 08:09:19 INFO - 'PAGER': '/bin/cat', 08:09:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:19 INFO - 'PWD': '/builds/slave/test', 08:09:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:19 INFO - 'SHELL': '/bin/bash', 08:09:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:19 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:19 INFO - 'USER': 'cltbld', 08:09:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:19 INFO - 'XPC_FLAGS': '0x0', 08:09:19 INFO - 'XPC_SERVICE_NAME': '0', 08:09:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:19 INFO - Downloading/unpacking blobuploader==1.2.4 08:09:19 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 08:09:19 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 08:09:19 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 08:09:19 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 08:09:19 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 08:09:19 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 08:09:22 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:23 INFO - Downloading docopt-0.6.1.tar.gz 08:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:23 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:23 INFO - Running setup.py install for blobuploader 08:09:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:23 INFO - Running setup.py install for requests 08:09:24 INFO - Running setup.py install for docopt 08:09:24 INFO - Successfully installed blobuploader requests docopt 08:09:24 INFO - Cleaning up... 08:09:24 INFO - Return code: 0 08:09:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:24 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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:24 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 08:09:24 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 08:09:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:24 INFO - 'HOME': '/Users/cltbld', 08:09:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:24 INFO - 'LOGNAME': 'cltbld', 08:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:24 INFO - 'MOZ_NO_REMOTE': '1', 08:09:24 INFO - 'NO_EM_RESTART': '1', 08:09:24 INFO - 'PAGER': '/bin/cat', 08:09:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:24 INFO - 'PWD': '/builds/slave/test', 08:09:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:24 INFO - 'SHELL': '/bin/bash', 08:09:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:24 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:24 INFO - 'USER': 'cltbld', 08:09:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:24 INFO - 'XPC_FLAGS': '0x0', 08:09:24 INFO - 'XPC_SERVICE_NAME': '0', 08:09:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:24 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-UOVO6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-MuCet4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-VFv0MB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-MrM1iX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-eSn6FI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-GGjBu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-W42mEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-ZZmHkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-YpmaPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-1CU5OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-9f5lTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-9TG6a3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-ofHpM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-sH4_ka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-oYLj8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-9M5Qu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-1wHI1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:26 INFO - Running setup.py install for manifestparser 08:09:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:26 INFO - Running setup.py install for mozcrash 08:09:26 INFO - Running setup.py install for mozdebug 08:09:27 INFO - Running setup.py install for mozdevice 08:09:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:27 INFO - Running setup.py install for mozfile 08:09:27 INFO - Running setup.py install for mozhttpd 08:09:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:27 INFO - Running setup.py install for mozinfo 08:09:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:27 INFO - Running setup.py install for mozInstall 08:09:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:09:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:09:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:09:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:09:27 INFO - Running setup.py install for mozleak 08:09:27 INFO - Running setup.py install for mozlog 08:09:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:09:28 INFO - Running setup.py install for moznetwork 08:09:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:09:28 INFO - Running setup.py install for mozprocess 08:09:28 INFO - Running setup.py install for mozprofile 08:09:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:09:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:09:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:09:28 INFO - Running setup.py install for mozrunner 08:09:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:09:28 INFO - Running setup.py install for mozscreenshot 08:09:28 INFO - Running setup.py install for moztest 08:09:29 INFO - Running setup.py install for mozversion 08:09:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:09:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:09:29 INFO - Cleaning up... 08:09:29 INFO - Return code: 0 08:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:29 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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:29 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 08:09:29 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 08:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:29 INFO - 'HOME': '/Users/cltbld', 08:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:29 INFO - 'LOGNAME': 'cltbld', 08:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:29 INFO - 'MOZ_NO_REMOTE': '1', 08:09:29 INFO - 'NO_EM_RESTART': '1', 08:09:29 INFO - 'PAGER': '/bin/cat', 08:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:29 INFO - 'PWD': '/builds/slave/test', 08:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:29 INFO - 'SHELL': '/bin/bash', 08:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:29 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:29 INFO - 'USER': 'cltbld', 08:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:29 INFO - 'XPC_FLAGS': '0x0', 08:09:29 INFO - 'XPC_SERVICE_NAME': '0', 08:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:29 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-hdM1dB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:29 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)) 08:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:29 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-dFbcnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:29 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)) 08:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:29 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-JSdkCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:29 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)) 08:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:29 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-y6SboO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:29 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)) 08:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:29 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-JZvFLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-Crnze_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-7iZ2WH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-V9iu0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-1nvm6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-nSvADb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-58gbcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-arHX7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-5G8zQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:30 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)) 08:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:30 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-htqcCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:31 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)) 08:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:31 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-XCGTky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:31 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)) 08:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:31 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-OA_d_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:31 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)) 08:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:31 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-A_jY0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:31 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)) 08:09:31 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)) 08:09:31 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)) 08:09:31 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)) 08:09:31 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)) 08:09:31 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)) 08:09:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:34 INFO - Downloading blessings-1.6.tar.gz 08:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:09:34 INFO - Installing collected packages: blessings 08:09:34 INFO - Running setup.py install for blessings 08:09:34 INFO - Successfully installed blessings 08:09:34 INFO - Cleaning up... 08:09:34 INFO - Return code: 0 08:09:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:34 INFO - Reading from file tmpfile_stdout 08:09:34 INFO - Current package versions: 08:09:34 INFO - blessings == 1.6 08:09:34 INFO - blobuploader == 1.2.4 08:09:34 INFO - docopt == 0.6.1 08:09:34 INFO - functools32 == 3.2.3-2 08:09:34 INFO - jsonschema == 2.5.1 08:09:34 INFO - manifestparser == 1.1 08:09:34 INFO - mozInstall == 1.12 08:09:34 INFO - mozcrash == 1.0 08:09:34 INFO - mozdebug == 0.1 08:09:34 INFO - mozdevice == 0.48 08:09:34 INFO - mozfile == 1.2 08:09:34 INFO - mozhttpd == 0.7 08:09:34 INFO - mozinfo == 0.9 08:09:34 INFO - mozleak == 0.1 08:09:34 INFO - mozlog == 3.4 08:09:34 INFO - moznetwork == 0.27 08:09:34 INFO - mozprocess == 0.23 08:09:34 INFO - mozprofile == 0.28 08:09:34 INFO - mozrunner == 6.13 08:09:34 INFO - mozscreenshot == 0.1 08:09:34 INFO - mozsystemmonitor == 0.3 08:09:34 INFO - moztest == 0.7 08:09:34 INFO - mozversion == 1.4 08:09:34 INFO - psutil == 3.1.1 08:09:34 INFO - requests == 1.2.3 08:09:34 INFO - wsgiref == 0.1.2 08:09:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:35 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')]} 08:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:35 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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:35 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 08:09:35 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 08:09:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:35 INFO - 'HOME': '/Users/cltbld', 08:09:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:35 INFO - 'LOGNAME': 'cltbld', 08:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:35 INFO - 'MOZ_NO_REMOTE': '1', 08:09:35 INFO - 'NO_EM_RESTART': '1', 08:09:35 INFO - 'PAGER': '/bin/cat', 08:09:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:35 INFO - 'PWD': '/builds/slave/test', 08:09:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:35 INFO - 'SHELL': '/bin/bash', 08:09:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:35 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:35 INFO - 'USER': 'cltbld', 08:09:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:35 INFO - 'XPC_FLAGS': '0x0', 08:09:35 INFO - 'XPC_SERVICE_NAME': '0', 08:09:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 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)) 08:09:35 INFO - Cleaning up... 08:09:35 INFO - Return code: 0 08:09:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:09:35 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')]} 08:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:35 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')]} 08:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:35 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 0x10b057490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9182c1c4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, '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.QuExDRTZRU/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.wcs7LgVfzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:09:35 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 08:09:35 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 08:09:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:09:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:35 INFO - 'HOME': '/Users/cltbld', 08:09:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:35 INFO - 'LOGNAME': 'cltbld', 08:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:35 INFO - 'MOZ_NO_REMOTE': '1', 08:09:35 INFO - 'NO_EM_RESTART': '1', 08:09:35 INFO - 'PAGER': '/bin/cat', 08:09:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:35 INFO - 'PWD': '/builds/slave/test', 08:09:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:35 INFO - 'SHELL': '/bin/bash', 08:09:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:09:35 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:09:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:35 INFO - 'USER': 'cltbld', 08:09:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:35 INFO - 'XPC_FLAGS': '0x0', 08:09:35 INFO - 'XPC_SERVICE_NAME': '0', 08:09:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:09:35 INFO - Cleaning up... 08:09:35 INFO - Return code: 0 08:09:35 INFO - Running post-action listener: _resource_record_post_action 08:09:35 INFO - Running post-action listener: _start_resource_monitoring 08:09:35 INFO - Starting resource monitoring. 08:09:35 INFO - [mozharness: 2016-12-14 16:09:35.829653Z] Finished create-virtualenv step (success) 08:09:35 INFO - [mozharness: 2016-12-14 16:09:35.830631Z] Running install step. 08:09:35 INFO - Running pre-action listener: _resource_record_pre_action 08:09:35 INFO - Running main action method: install 08:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:36 INFO - Reading from file tmpfile_stdout 08:09:36 INFO - Detecting whether we're running mozinstall >=1.0... 08:09:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:09:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:09:36 INFO - Reading from file tmpfile_stdout 08:09:36 INFO - Output received: 08:09:36 INFO - Usage: mozinstall [options] installer 08:09:36 INFO - Options: 08:09:36 INFO - -h, --help show this help message and exit 08:09:36 INFO - -d DEST, --destination=DEST 08:09:36 INFO - Directory to install application into. [default: 08:09:36 INFO - "/builds/slave/test"] 08:09:36 INFO - --app=APP Application being installed. [default: firefox] 08:09:36 INFO - mkdir: /builds/slave/test/build/application 08:09:36 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'] 08:09:36 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 08:10:06 INFO - Reading from file tmpfile_stdout 08:10:06 INFO - Output received: 08:10:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:10:06 INFO - Running post-action listener: _resource_record_post_action 08:10:06 INFO - [mozharness: 2016-12-14 16:10:06.890585Z] Finished install step (success) 08:10:06 INFO - [mozharness: 2016-12-14 16:10:06.890721Z] Running run-tests step. 08:10:06 INFO - Running pre-action listener: _resource_record_pre_action 08:10:06 INFO - Running main action method: run_tests 08:10:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:06 INFO - 2016-12-14 08:10:06.904 screenresolution[1618:7912] starting screenresolution argv=screenresolution get 08:10:06 INFO - 2016-12-14 08:10:06.907 screenresolution[1618:7912] Display 0: 1600x1200x32@60 08:10:06 INFO - 2016-12-14 08:10:06.913 screenresolution[1619:7914] starting screenresolution argv=screenresolution list 08:10:06 INFO - Available Modes on Display 0 08:10:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:07 INFO - Intel Iris: 08:10:07 INFO - Chipset Model: Intel Iris 08:10:07 INFO - Type: GPU 08:10:07 INFO - Bus: Built-In 08:10:07 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:07 INFO - Vendor: Intel (0x8086) 08:10:07 INFO - Device ID: 0x0a2e 08:10:07 INFO - Revision ID: 0x0009 08:10:07 INFO - Displays: 08:10:07 INFO - Display: 08:10:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:07 INFO - Display Serial Number: 1600x1200 60 08:10:07 INFO - Main Display: Yes 08:10:07 INFO - Mirror: Off 08:10:07 INFO - Online: Yes 08:10:07 INFO - Rotation: Supported 08:10:07 INFO - Return code: 0 08:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:07 INFO - Python 2.7.3 08:10:07 INFO - Return code: 0 08:10:07 INFO - grabbing minidump binary from tooltool 08:10:07 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')]} 08:10:07 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 0x10ae6bd30>, '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 0x10acd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae63b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:07 INFO - Return code: 0 08:10:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:10:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:10:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:07 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/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--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 08:10:07 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/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0319 --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 08:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QuExDRTZRU/Render', 08:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:07 INFO - 'HOME': '/Users/cltbld', 08:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:07 INFO - 'LOGNAME': 'cltbld', 08:10:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:07 INFO - 'MOZ_NO_REMOTE': '1', 08:10:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:07 INFO - 'NO_EM_RESTART': '1', 08:10:07 INFO - 'PAGER': '/bin/cat', 08:10:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:07 INFO - 'PWD': '/builds/slave/test', 08:10:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:07 INFO - 'SHELL': '/bin/bash', 08:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wcs7LgVfzd/Listeners', 08:10:07 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:07 INFO - 'USER': 'cltbld', 08:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:07 INFO - 'XPC_FLAGS': '0x0', 08:10:07 INFO - 'XPC_SERVICE_NAME': '0', 08:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:07 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/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--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 08:10:07 INFO - mozversion application_buildid: 20161214064001 08:10:07 INFO - mozversion application_changeset: 7bfeec8395e5ca28421d92f3bf51d56feafc0319 08:10:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:10:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:07 INFO - mozversion application_name: Firefox 08:10:07 INFO - mozversion application_remotingname: firefox-dev 08:10:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:10:07 INFO - mozversion application_vendor: Mozilla 08:10:07 INFO - mozversion application_version: 52.0a2 08:10:07 INFO - mozversion platform_buildid: 20161214064001 08:10:07 INFO - mozversion platform_changeset: 7bfeec8395e5ca28421d92f3bf51d56feafc0319 08:10:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:10:07 INFO - mozversion platform_version: 52.0a2 08:10:07 INFO - using testdate: 1481731807 08:10:07 INFO - actual date: 1481731807 08:10:07 INFO - starting webserver on 'localhost:49242' 08:10:07 INFO - SUITE-START | Running 2 tests 08:10:07 INFO - TEST-START | dromaeo_css 08:10:07 INFO - operating with platform_type : mac_ 08:10:07 INFO - Initialising browser for dromaeo_css test... 08:10:07 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpHecjdj/profile http://localhost:49242/getInfo.html) 08:10:10 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 08:10:10 INFO - PROCESS | 1629 | colorDepth:24 08:10:10 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 08:10:10 INFO - PROCESS | 1629 | __metrics 08:10:10 INFO - PROCESS | 1629 | 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" 08:10:10 INFO - PROCESS | 1629 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:10:11 INFO - TEST-INFO | 1629: exit 0 08:10:11 INFO - Browser initialized. 08:10:11 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:11 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpHecjdj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:23 INFO - PROCESS | 1631 | RSS: Main: 222294016 08:10:23 INFO - PROCESS | 1631 | 08:11:15 INFO - PROCESS | 1631 | 1481731875652 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] 08:13:28 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 08:13:30 INFO - PROCESS | 1631 | RSS: Main: 345329664 08:13:30 INFO - PROCESS | 1631 | 08:16:33 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 08:16:33 INFO - PROCESS | 1631 | RSS: Main: 347377664 08:16:33 INFO - PROCESS | 1631 | 08:19:36 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 08:19:36 INFO - PROCESS | 1631 | RSS: Main: 346144768 08:19:36 INFO - PROCESS | 1631 | 08:22:38 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 08:22:39 INFO - PROCESS | 1631 | RSS: Main: 319488000 08:22:39 INFO - PROCESS | 1631 | 08:25:43 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 08:25:43 INFO - PROCESS | 1631 | RSS: Main: 306786304 08:25:43 INFO - PROCESS | 1631 | 08:28:46 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 08:28:47 INFO - PROCESS | 1631 | RSS: Main: 482574336 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - PROCESS | 1631 | __start_tp_report 08:28:47 INFO - PROCESS | 1631 | _x_x_mozilla_page_load 08:28:47 INFO - PROCESS | 1631 | _x_x_mozilla_page_load_details 08:28:47 INFO - PROCESS | 1631 | |i|pagename|runs| 08:28:47 INFO - PROCESS | 1631 | |0;dojo.html;2851.2016635342106;3303.388873058486;3328.2844188499453;3333.633300336963;3394.456886898097;5621.4940655341015;5629.577781666371;5651.095824668054;5704.973104841127;5728.627639203454;5575.8606034849145;5580.190872323511;5623.100303951369;5667.263255776754;5673.659580425167;5111.897762044757;5137.5633071188995;5148.897022059557;5179.61152913533;5190.92213616796;5143.5113664201845;5153.458886816887;5164.509371609691;5239.1093514102695;5295;4349.782510874444;5213.921791173136;5215.843524694265;5233.528982391584;5240.37115546135;5163.096458119833;5165.99263143689;5187.195984622352;5187.299714538527;5196.818111366084;5109.642325037249;5167.870803229912;5168.3539557555305;5197.66215196011;5200.167973124282;4753.405824271966;5513.117901135839;5560.193771903092;5687.061634830248;5699.68651724155;6085.3914608539235;6099.939000609981;6120.538559592075;6178.876422471525;6179.196704428396;6222.222222222223;6280.591761535531;6288.496920246369;6290.308066112724;6293.622382657055;5649.971750141305;5710.086386178275;5750.022496175702;5791.936286709806;5807.88384232313;5196.298499702568;6172.382761723792;6262.279837818618;6271.749130034838;6302.275238347557;6071.635701857815;6093.75624974996;6095.664738439428;6097.054956481664;6116.877662446726;5371.543418809367;5730.08318669011;6216.533759968021;6222.160008398899;6241.126242326078;5672.874774306876;5686.2039314496005;6128.3503509891225;6160.537959653044;6176.413240742122;6221.129041934133;6225.533085018642;6226.813195604049;6234.0337247726675;6240.843978900564;4884.072026314989;4919.1391506486225;4924.532169443897;4927.384076990376;4937.580305673986;4357.368181613649;4383.408239887638;4408.0302333487225;4427.512973572904;4463.910721785611;5774.971125144348;5794.1308803679785;5799.971000145057;5840.036393994943;5843.444872737083;6212.689365531705;6218.9378106218355;6227.190465239581;6228.5017198624;6231.813045608639;5609.411011843779;5731.083026715705;5777.075667893201;5791.768329266866;5799.2750906136735;5342.679439233659;5349.277847490617;5353.4646535346155;5358.089124848785;5358.54452371545;6230.532710046765;6242.65665388408;6270.0908368287255;6274.843128921813;6286.968565157145;6230.6884655766125;6259.405356491126;6261.96869015652;6263.311035786151;6265.686715664289;6298.08677741734;6317.557770956173;6319.146915166578;6327.114204011534;6329.367063293818;4791.396992930316;6069.939300607114;6124;6129.019356902874;6191.504679625709;6059.545534085049;6156.784512542219;6236.656983865931;6238.812835614939;6249.781257656142;6113.266408031065;6118.755249790137;6125.203723515914;6128.448439640454;6141.570090093651;5915;5986.850328741816;6103.6032657877095;6110.236220472441;6129.478994285358;5047.798088076436;5849.53203743708;5885.823425297248;5899.4985426239255;5927.051671732502;6137.560853825989;6141.823559979257;6147.508199343953;6148.907766383597;6157.692115394302;5906.763729450774;5948.970255148696;5963.28440587115;5964.045752679724;5989.790357337472 08:28:47 INFO - PROCESS | 1631 | |1;ext.html;452.3328091065678;506.1976766824583;519.5557798082639;523.552362729866;545.6453305351519;30090.84954575227;38600;39775.20449591007;39815;40029.999250018765;40547;40882.59117408825;40919;41155.794221028926;41194.79402602983;9140.131687489678;9313;9318.953405232964;9334.626614935394;9346.485943273117;9250.629974801;9357.157855792977;9361.251099912024;9365.297602679791;9368.953155234214;9320.114589114057;9376.203022743075;9392.718218453458;9402.529873506332;9404.29467789915;9309.487978161198;9346.67286644971;9364.204042656382;9371.85942210864;9386.812263754686;9261.675841345588;9271;9321.673741419017;9352.859707104399;9395.436273823592;36886;37235;37255.068623284365;37287;37351.62648373509;3160.668129846356;3636.5999740028574;3780.111673756665;3804.2771873343977;3827.617238276178;27939.58090628642;28379.574306385213;29412.26469338262;29521.261968450744;30069.69930300691;3090.2892334762905;3175.7935734177204;3201.471757160066;3204.5033019881957;3242.983785081083;514359;519185;519292;519953;520183.39908300596;32518.674813251564;33435.83282083574;60119.69940150359;60645.09032364518;60832;29310;34096;34610.134746631535;34647.65352346495;34678.653213468046;12392.75214495705;14073.507427239998;14104.224267665171;14105;14139.85860141406;11869.703257418634;11975;11977.281363118242;11982.64052078439;11987.820182697267;7601.239876012354;7608.429367797326;7638.083429988437;7764.767056988299;7772.028496437945;3162.6204855417495;3216.2281052547687;3216.5818443602184;3220.838958052088;3263.7878537894962;793.2305663506389;857.5969294431607;1003.372891942536;1007.6120693532896;1036.5905467340508;27979.860100699774;28882.711172888;29441;29512.852435737685;29543.113706588836;843.8945131858518;1009.7475631092227;1016.9694909152737;1039.1998161415668;1076.9892301076889;14982.475613353481;15017.549473515812;15277.694446111016;15379.61550961235;15410.845891541165;639068;641967;643463;645457;646614;54275.72862135664;54944.45055549473;54966;54966.90066198654;55013;1351.3716122003316;1365.7268546290782;1390.5202705066736;1390.986090139106;1395.232622057864;28287.434251314447;28980.565291521067;29031.854840726504;29174.56238156472;29533.114006579835;47797.522024779304;47983.840727166666;48108.518914810404;48121.518784811706;48191.5180848187;576594;580304;582401;583190;583927;25324.620130698422;25685.871570642026;25738.613920791577;25749.227523174333;25836.8708156458;43470;43585;44241;44670.77664611656;44913.77543112264;36296.09259768527;36743;36823.07942301464;36842.631573684994;37269.6273037277;30063.398732025675;31935.201619959687;36321.636783631824;36532.634673653985;36969.70606028776;28176.29559261035;31188.532172016985;32008;35944.101397465274;36509.634903651684;31751.523727144573;32731.34537309288;33374.499382508795;33384.49923251104;33419.83290083534;24651.753482465665;24864.12975545847;24916.75083249144;25187;25315.87342063278 08:28:47 INFO - PROCESS | 1631 | |2;jquery.html;354.2383874669462;370.80532720321827;375.352516908333;383.48038407957216;404.36110944707366;14312.0697154685;14450.421983120676;15063.62340941477;18767.812321876776;19456.610867782634;18102.637947241048;18300.908495457537;19047.238110475566;19264.614707705874;19302.131404086816;5017.949820501803;5295.205719142121;5295.761690723926;5297.629165958385;5311.282976798121;4639.953600464003;4853.296272040569;5101.923471147936;5420.972895135519;5455.263539422167;5277.815276465324;5296.364436267634;5336.386315573701;5393.703346315951;5459.508644222019;5163.173892177252;5272;5321.787128514855;5330.147176451788;5388.649737767052;5160.638755287131;5174.896502069938;5182.170852663593;5182.740862956837;5186.611004174702;12511.186772859735;12579.308138052404;12741.490340386374;12917.354132293442;12952.48190072396;2126.669043939939;2133.98933005334;2172.8587641803233;2417.7944874685672;2504.474060447308;9826.16477599405;9841;9842.852357214648;10308.896911030944;10347.689569312932;1793.7668103146639;1823.480308112195;1859.665260253154;1877.662020836249;1879.4643526594991;90961.54519227428;117007.99999999914;120365.39817300858;124795;127647.36176319151;11195.440227988567;11305.095592352593;11338.263012904135;11399.48702308398;11550.249233799776;14504;14531.982761206826;14536.982411231324;14540.49108281205;14548.272586370638;7948.483348582324;8179.263866252066;8187.0994190638985;8273.8345233095;8295.543745093957;7934;7946.40401969843;7953.204679532117;8058.637361318754;8115.594220288962;4407.757573333497;4415.712978656377;4418.734875907456;4433.290673492225;4500.594946454769;1689.729643257073;1848.741176235328;1888.9622207556047;1895.772507299133;1923.1057558235489;735.7498450526847;754.8226166850785;798.9680412783424;850.2475309351269;881.9514926679094;19589.804101959082;20800.167993280404;21161.68257476139;21180.25869094605;21314.36056918295;851.1488511488511;872.7250915961451;943.235978857128;971.8347880860271;1005.7234260578399;8914.242289405343;11181.161412894066;11194.888051119384;11197.104231661448;11198.552057917756;489116;585138;586015;600786;603955.9604403899;104776;119962.80037199517;130567;134533;134891;903.3315346643436;925.9212966897754;952.6998995316453;954.2175416158683;954.4321128928276;18801.247950081844;19175.32886348971;19482.902585486983;19494.61010779748;19533.12100955432;44214.67355979326;55053.72473137609;55970.481328341135;56364.71817640885;56732.14901776394;58455.70772146112;62608.37391626026;78809.48333616591;81017.59491202506;81808.18191818005;7866.466039122316;7878.842423151619;8019.6391162398995;8135.430519863553;8171.42800003994;11950.423617610157;12663.303518306582;12967.935160324138;13030.348482576022;13203.943684505408;10906.836397454203;11007.724806879572;11064;11207;11369.147313951653;9121;10747.570097196025;10813.243072984815;10849.240553161519;11392.202545821712;10983.121350291798;11023.559057637605;11069.667909962714;11523.423828808358;11592.710182245512;7860.882086768817;7898.486598371088;7915.564643944638;8136.593170341577;8304.169583041888;8174.468659537111;10362.06741393248;10980.505877235384;11110.16673749456;11279.887201128213 08:28:47 INFO - PROCESS | 1631 | |3;mootools.html;1454.9563513094608;1501.0543357684658;1656.5941344370624;1793;1848.039019709751;7971.760847174587;8255.422120451563;8262.876056859144;8275.21385468381;8421.663133474654;6052.576319657625;7603.315701586856;7610.200928902468;7977.0826354969195;8200.876986845202;2287.5653625811124;2332.1021406758437;2336.4509340305017;2337.7662233776655;2338.251759436981;2318.9420264493356;2348.8003519700846;2351.870647114408;2367.242482405631;2372.5492156490213;2041.061111888533;2122.1617461102855;2132.2537112010828;2260.1863329201474;2281.4866655002656;2253.954920901581;2259.4690247791755;2263.1173842201556;2272.749997500274;2273.1362082408746;2030.8172264496118;2286.6798648189265;2310.330004298751;2322.7096612923383;2351.0713268259115;8133.593320334019;8225.588720563948;8254;8291.419600627958;8300.377471689648;5029.949700502984;5192.740362981874;5193.766280517386;5194.480551944813;5209.869753256161;3361.4957756336503;3439.8280085995602;3468.6704763047724;3529.4000019996406;3530.082178633548;1002.2783595811004;1054.7152268887357;1065.7495488560182;1080.9189310801721;1084.2518662123189;10737;10749.785004299949;10783.406912619803;10786.244962852681;10792.46037698112;4511.70673906195;4567.223571992703;4657.604103651228;4716.221823399169;4726.621870250372;9372.625094996123;9408.435493870253;9413.670521531714;9417.670381536613;9435.198008169245;5140.742962851842;5149.098907691139;5153.948460515347;5154.072266991902;5159.948400516022;4614.538546145426;4661.277501987197;4662.393888794435;4669.766511674403;4749.715017098985;3736.7944763038295;3756;3845.538535375773;3862.1696335288048;3877.185790983925;1238.4241327782624;1243.8320826688462;1246.7880460321767;1256.2901960392348;1263.8862502374645;726.3753172271842;816.9339012588629;868.5049521772655;882.8410886040574;892.4466830565091;5165.509276618715;5341.839744807662;5564.332280126329;5613.915791263134;5689.9146512802345;446.6783915580777;449.6200710399707;450.263818656499;450.43695380774034;453.5283305362386;3746.7189960753053;3754.1928485375774;3790.9431358529646;3794.9810250948567;3876.7673939563715;9392.67125650599;9780.413175209367;10006.949965250127;10143.898561014443;10160.796784064278;9744.658936937172;10064.597416103421;10086.394816311045;10107.039831216023;10314.329568578169;6716.597004179765;7694.269044440769;7795.883061754078;7804.882926756103;7813;5360.973195133999;5401.135818269058;5525.11598144295;5593.07714227148;5632.127020311858;8063.032436107705;9289.442633442015;9328.207102396373;9336.11306925841;9484.383515071499;8704.738857834274;9334.159925606456;9384.859227111463;9525.809483810424;9526;7019.087518622547;7132.643367831692;7139.464540159613;7152.284771523015;7162.247963963917;7614.771556853303;7717.768466946;7807.843843123219;7825.530468171928;7833.843323133619;7090.822729431806;7101.786946391617;7119.8932016020835;7137.429005679637;7279.636018199175;6910.205326387329;6933;6941.965290173517;6942.479314051366;6950.443964482728;6428.4214420702365;6548.050532672641;6633.767818126343;6657.101291325609;6659.567128136656;4616.769161541976;4744.098621261949;4781.521847815191;4800.0639875224;4820.542048505386;4636.513166117644;4647.814087436464;4795.376601041982;4805.927911081267;4867.269909513459 08:28:47 INFO - PROCESS | 1631 | |4;prototype.html;268.62526775148683;297.95083811171156;307.08794879208756;307.919940815388;312.67426113279674;4407.338899165127;4870.537298956593;5277.340332458442;5386.434424385442;5391.784328626855;4594.37975873257;5268.604854635899;5273.762680679369;5296.629235953485;5338;3767.8304476298563;3770.359038963383;3905.882823515299;3917.5103112110987;3976.403539469088;3654.963450365502;3872.625218047595;4002.979985100085;4021.77880216588;4078.9184216315803;3412.4198886189406;3565.3599344301524;3891.268385568423;3996.960030399702;4095.795210239491;3152.36952609479;3771.924561508768;3835.6356146166067;3997.900052498682;4014.417909403149;3052.3590046090126;3492.4761285807076;3497.8600855965733;3498.2303893143467;3533.6643018913164;5028.522290382444;5102.209157580581;5129.333186685744;5137.974310128426;5152.716600586966;4199.265128602513;4388.539203383662;4415.911681766379;4592.678512504126;4602.838900638495;4552.68131230814;4614.077184563101;4809.663323567352;5098.5411312982005;5290.650884024588;3848.1918797052654;3864.7294689371756;3867.7292589518743;3880.165764360676;3912.726109172359;5325.934813037408;5544.63959842609;5759.164921086419;5771.422857714195;5783.884322313572;3552.2717842842158;3832.3101841668768;3843.9231215375535;3844.5386553613744;3851.171998020383;3895.5714871364125;3896.7077469189926;3897.902552436212;3910.980445097813;3947.684185265172;3847.5382954045695;4472.888177795581;4522;4539;4559.589636932692;4259.488861336659;5374.355077390738;5579.553635709133;5589.441055894459;5681.943180568224;1176.8819621359742;1250.2436026204016;1256.434604428011;1258.7985922252396;1265.936703164838;696.8710688684364;792.7899106736719;821.8986558012292;857.2070834478083;862.4351050062219;4953.777080031407;5179.559737422362;5202.895942081137;5223;5254.159334506537;5578.135389014709;5597.720113994284;5609.663420194802;5627.803026894039;5646.378898321182;4273.9358909616385;5096.184610462308;5260.422393027389;5270.973645131827;5277.920831187535;2643;2647.5234457797396;2835.3631123055757;2843.8482414622113;2887.4946884295164;4375.212461756851;5121.846344609593;5158.781723858701;5543.307086614173;5573.098830818362;4899.779509922062;5351.705656188947;5535.363433205152;5548.861278468071;5591.3569939456665;4906.822362632943;4949.980511887818;5641.097424412074;5964.612300200473;5997;4760.452547956891;5286.524212820865;5762.723724128862;5783.595148339576;5786.383510306058;5320.5477534408465;5667.659940403589;5687.037971899688;5705.258316418839;5732.197492350991;4825.758712064453;5045.520675535819;5617.8033768817895;5910.054391297372;5974.9402505974385;3123.7813353065976;3271.607407111162;3857.6883859487907;3866.342721737255;3886.1839013806284;5617.522510586646;5727.513161381163;5763.423657634203;5815.011448053926;5817.214676018683;5569.805056822992;5576.86057848557;5582.720863956704;5597.244372009726;5605.915911261416;4457.7771111444945;5589.329280486368;5593.916091258553;5599.440055994531;5653.208550802973;5065.2018533420105;5098.796048158032;5157.7679004444135;5509.862253443696;5517.096601672417;3992.3811809169624;5078.949210507848;5376.005438993642;5547.75596668557;5650.85872853182;5085.211792172219;5205.687658740488;5233.927044955699;5459.945400546103;5483.616146869681 08:28:47 INFO - PROCESS | 1631 | |5;yui.html;695.6834640238691;746.2462912458419;760.182803486252;786.0959896119465;788.3535500889271;15369.385224591017;15989.84010159898;16047;16164.676706465878;16391.4263000795;14758.778618320734;14840.035397699168;14869;14896.329665165067;14958.7008259835;684.4968947823346;709.0427922304899;832.9916700832974;865.6537385046;866.9653213871437;702.5222848463043;704.0425021970142;704.9788506344818;710.7441321124392;825.1130035212141;891.9732408027737;892.7098692924793;903.3405613901856;907.9773005674845;908.1236606674557;887.3211992825461;891.2958762577556;891.6477991193442;894.065701342099;895.8656201569752;520.4327283261264;540.4514417865869;666.4768156996735;669.5212922760201;669.6584741781678;13569.45722171112;14016.859831401658;14119;14284.57146285613;14327.283635818167;13529.255890925995;13707;13756.713839843573;13892.791608125886;13895.444182232699;10505.842412363821;10878.836817447745;11094.278871873383;11231.775364492745;11440.084793216523;3635.348985530369;3666.651668091527;3676.2096149327754;3770.7360484766077;3794.7343685941996;78765;78889.21110788934;84501.99999999939;84646;84713.57643211806;10482.947585262178;10492.73768155787;10553.894461055444;10577.84133238002;10660.520276587573;17251.65496690059;20961.266355677784;21046.263380781904;21223.68164477534;21262.46843828917;6596.340365963365;7134.607596582237;7536.962315188389;7659.808504787314;7695.576743279062;4087.4686290782006;4159.9792001039805;4169.10364271677;4206.053637931441;4233.703640745119;3338.7662863599317;3454.29187016661;3455.1016735648914;3457.567804024497;3471.2710330830305;618.4773866083152;621.1459243540132;632.4750457120579;655.1875674164003;656.3502132888406;587.8060240120835;636.6625688385163;657.6911945229056;659.7690808217086;668.4218151299091;9343.766405839908;9560.139587437165;9595.13643772064;9648.565814538362;9858;646.360103497534;647.6729251727847;649.9935000649933;662.2946561911644;671.8118926700531;3912.68698504119;4207.726497777635;4223.429836972031;4328.675349348811;4464.598186163261;67761;69372.95940560896;97956.57151999646;102412.97587024183;102992;62022.379776202564;74011.88982165272;88722.11277887267;91783;94020;79442.01394965172;82700.17299827046;83104.58447707722;84212;84633;8565.957170214358;8609.440386374865;8674.479531228146;8822.117788221127;8864.9507309687;38698.41952370773;50852.982940341724;50895.72760680864;51650.48349516457;52734.68163295948;69047.65476172788;69330.31800430518;71345;73215.53568928697;73361.6331918337;10365.585376584851;10927.508262128064;11019.614313498989;11125.83311250348;11185.105191584817;9973.50132493387;11732.248439382063;11814;11825.467853946766;12237.75524489523;10871.565137394417;11763.647090587296;12268.263904165779;12381.071419643742;12382.62852114438;9803.656872009446;10215.131713804112;11393;11439.42802859837;12029.338386388832;11806.881931180578;11903;12744.68138296513;13176.47294108225;13203.207807531579;10123.493825308853;10395.792084158124;10431.321964072311;10503.684889453329;10522.461903819452;9204.401713888576;9990.450525221182;10269.845952310572;10590.841137382791;10797.244192906735 08:28:47 INFO - PROCESS | 1631 | __end_tp_report 08:28:47 INFO - PROCESS | 1631 | __start_cc_report 08:28:47 INFO - PROCESS | 1631 | _x_x_mozilla_cycle_collect,1411 08:28:47 INFO - PROCESS | 1631 | __end_cc_report 08:28:47 INFO - PROCESS | 1631 | __startTimestamp1481732927133__endTimestamp 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - PROCESS | 1631 | ------- Summary: start ------- 08:28:47 INFO - PROCESS | 1631 | Number of tests: 6 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - PROCESS | 1631 | [#0] dojo.html Cycles:165 Average:5681.90 Median:5849.53 stddev:663.59 (11.3%) stddev-sans-first:627.37 08:28:47 INFO - PROCESS | 1631 | Values: 2851.2 3303.4 3328.3 3333.6 3394.5 5621.5 5629.6 5651.1 5705.0 5728.6 5575.9 5580.2 5623.1 5667.3 5673.7 5111.9 5137.6 5148.9 5179.6 5190.9 5143.5 5153.5 5164.5 5239.1 5295.0 4349.8 5213.9 5215.8 5233.5 5240.4 5163.1 5166.0 5187.2 5187.3 5196.8 5109.6 5167.9 5168.4 5197.7 5200.2 4753.4 5513.1 5560.2 5687.1 5699.7 6085.4 6099.9 6120.5 6178.9 6179.2 6222.2 6280.6 6288.5 6290.3 6293.6 5650.0 5710.1 5750.0 5791.9 5807.9 5196.3 6172.4 6262.3 6271.7 6302.3 6071.6 6093.8 6095.7 6097.1 6116.9 5371.5 5730.1 6216.5 6222.2 6241.1 5672.9 5686.2 6128.4 6160.5 6176.4 6221.1 6225.5 6226.8 6234.0 6240.8 4884.1 4919.1 4924.5 4927.4 4937.6 4357.4 4383.4 4408.0 4427.5 4463.9 5775.0 5794.1 5800.0 5840.0 5843.4 6212.7 6218.9 6227.2 6228.5 6231.8 5609.4 5731.1 5777.1 5791.8 5799.3 5342.7 5349.3 5353.5 5358.1 5358.5 6230.5 6242.7 6270.1 6274.8 6287.0 6230.7 6259.4 6262.0 6263.3 6265.7 6298.1 6317.6 6319.1 6327.1 6329.4 4791.4 6069.9 6124.0 6129.0 6191.5 6059.5 6156.8 6236.7 6238.8 6249.8 6113.3 6118.8 6125.2 6128.4 6141.6 5915.0 5986.9 6103.6 6110.2 6129.5 5047.8 5849.5 5885.8 5899.5 5927.1 6137.6 6141.8 6147.5 6148.9 6157.7 5906.8 5949.0 5963.3 5964.0 5989.8 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - PROCESS | 1631 | [#1] ext.html Cycles:180 Average:68689.98 Median:36927.85 stddev:156425.13 (423.6%) stddev-sans-first:156780.04 08:28:47 INFO - PROCESS | 1631 | Values: 452.3 506.2 519.6 523.6 545.6 30090.8 38600.0 39775.2 39815.0 40030.0 40547.0 40882.6 40919.0 41155.8 41194.8 9140.1 9313.0 9319.0 9334.6 9346.5 9250.6 9357.2 9361.3 9365.3 9369.0 9320.1 9376.2 9392.7 9402.5 9404.3 9309.5 9346.7 9364.2 9371.9 9386.8 9261.7 9271.0 9321.7 9352.9 9395.4 36886.0 37235.0 37255.1 37287.0 37351.6 3160.7 3636.6 3780.1 3804.3 3827.6 27939.6 28379.6 29412.3 29521.3 30069.7 3090.3 3175.8 3201.5 3204.5 3243.0 514359.0 519185.0 519292.0 519953.0 520183.4 32518.7 33435.8 60119.7 60645.1 60832.0 29310.0 34096.0 34610.1 34647.7 34678.7 12392.8 14073.5 14104.2 14105.0 14139.9 11869.7 11975.0 11977.3 11982.6 11987.8 7601.2 7608.4 7638.1 7764.8 7772.0 3162.6 3216.2 3216.6 3220.8 3263.8 793.2 857.6 1003.4 1007.6 1036.6 27979.9 28882.7 29441.0 29512.9 29543.1 843.9 1009.7 1017.0 1039.2 1077.0 14982.5 15017.5 15277.7 15379.6 15410.8 639068.0 641967.0 643463.0 645457.0 646614.0 54275.7 54944.5 54966.0 54966.9 55013.0 1351.4 1365.7 1390.5 1391.0 1395.2 28287.4 28980.6 29031.9 29174.6 29533.1 47797.5 47983.8 48108.5 48121.5 48191.5 576594.0 580304.0 582401.0 583190.0 583927.0 25324.6 25685.9 25738.6 25749.2 25836.9 43470.0 43585.0 44241.0 44670.8 44913.8 36296.1 36743.0 36823.1 36842.6 37269.6 30063.4 31935.2 36321.6 36532.6 36969.7 28176.3 31188.5 32008.0 35944.1 36509.6 31751.5 32731.3 33374.5 33384.5 33419.8 24651.8 24864.1 24916.8 25187.0 25315.9 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - PROCESS | 1631 | [#2] jquery.html Cycles:180 Average:33415.03 Median:373.08 stddev:96132.01 (25767.2%) stddev-sans-first:96369.64 08:28:47 INFO - PROCESS | 1631 | Values: 354.2 370.8 375.4 383.5 404.4 14312.1 14450.4 15063.6 18767.8 19456.6 18102.6 18300.9 19047.2 19264.6 19302.1 5017.9 5295.2 5295.8 5297.6 5311.3 4640.0 4853.3 5101.9 5421.0 5455.3 5277.8 5296.4 5336.4 5393.7 5459.5 5163.2 5272.0 5321.8 5330.1 5388.6 5160.6 5174.9 5182.2 5182.7 5186.6 12511.2 12579.3 12741.5 12917.4 12952.5 2126.7 2134.0 2172.9 2417.8 2504.5 9826.2 9841.0 9842.9 10308.9 10347.7 1793.8 1823.5 1859.7 1877.7 1879.5 90961.5 117008.0 120365.4 124795.0 127647.4 11195.4 11305.1 11338.3 11399.5 11550.2 14504.0 14532.0 14537.0 14540.5 14548.3 7948.5 8179.3 8187.1 8273.8 8295.5 7934.0 7946.4 7953.2 8058.6 8115.6 4407.8 4415.7 4418.7 4433.3 4500.6 1689.7 1848.7 1889.0 1895.8 1923.1 735.7 754.8 799.0 850.2 882.0 19589.8 20800.2 21161.7 21180.3 21314.4 851.1 872.7 943.2 971.8 1005.7 8914.2 11181.2 11194.9 11197.1 11198.6 489116.0 585138.0 586015.0 600786.0 603956.0 104776.0 119962.8 130567.0 134533.0 134891.0 903.3 925.9 952.7 954.2 954.4 18801.2 19175.3 19482.9 19494.6 19533.1 44214.7 55053.7 55970.5 56364.7 56732.1 58455.7 62608.4 78809.5 81017.6 81808.2 7866.5 7878.8 8019.6 8135.4 8171.4 11950.4 12663.3 12967.9 13030.3 13203.9 10906.8 11007.7 11064.0 11207.0 11369.1 9121.0 10747.6 10813.2 10849.2 11392.2 10983.1 11023.6 11069.7 11523.4 11592.7 7860.9 7898.5 7915.6 8136.6 8304.2 8174.5 10362.1 10980.5 11110.2 11279.9 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - PROCESS | 1631 | [#3] mootools.html Cycles:180 Average:5372.48 Median:4843.91 stddev:2956.40 (61.0%) stddev-sans-first:2950.04 08:28:47 INFO - PROCESS | 1631 | Values: 1455.0 1501.1 1656.6 1793.0 1848.0 7971.8 8255.4 8262.9 8275.2 8421.7 6052.6 7603.3 7610.2 7977.1 8200.9 2287.6 2332.1 2336.5 2337.8 2338.3 2318.9 2348.8 2351.9 2367.2 2372.5 2041.1 2122.2 2132.3 2260.2 2281.5 2254.0 2259.5 2263.1 2272.7 2273.1 2030.8 2286.7 2310.3 2322.7 2351.1 8133.6 8225.6 8254.0 8291.4 8300.4 5029.9 5192.7 5193.8 5194.5 5209.9 3361.5 3439.8 3468.7 3529.4 3530.1 1002.3 1054.7 1065.7 1080.9 1084.3 10737.0 10749.8 10783.4 10786.2 10792.5 4511.7 4567.2 4657.6 4716.2 4726.6 9372.6 9408.4 9413.7 9417.7 9435.2 5140.7 5149.1 5153.9 5154.1 5159.9 4614.5 4661.3 4662.4 4669.8 4749.7 3736.8 3756.0 3845.5 3862.2 3877.2 1238.4 1243.8 1246.8 1256.3 1263.9 726.4 816.9 868.5 882.8 892.4 5165.5 5341.8 5564.3 5613.9 5689.9 446.7 449.6 450.3 450.4 453.5 3746.7 3754.2 3790.9 3795.0 3876.8 9392.7 9780.4 10006.9 10143.9 10160.8 9744.7 10064.6 10086.4 10107.0 10314.3 6716.6 7694.3 7795.9 7804.9 7813.0 5361.0 5401.1 5525.1 5593.1 5632.1 8063.0 9289.4 9328.2 9336.1 9484.4 8704.7 9334.2 9384.9 9525.8 9526.0 7019.1 7132.6 7139.5 7152.3 7162.2 7614.8 7717.8 7807.8 7825.5 7833.8 7090.8 7101.8 7119.9 7137.4 7279.6 6910.2 6933.0 6942.0 6942.5 6950.4 6428.4 6548.1 6633.8 6657.1 6659.6 4616.8 4744.1 4781.5 4800.1 4820.5 4636.5 4647.8 4795.4 4805.9 4867.3 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - PROCESS | 1631 | [#4] prototype.html Cycles:180 Average:4439.78 Median:5082.08 stddev:1380.37 (27.2%) stddev-sans-first:1348.28 08:28:47 INFO - PROCESS | 1631 | Values: 268.6 298.0 307.1 307.9 312.7 4407.3 4870.5 5277.3 5386.4 5391.8 4594.4 5268.6 5273.8 5296.6 5338.0 3767.8 3770.4 3905.9 3917.5 3976.4 3655.0 3872.6 4003.0 4021.8 4078.9 3412.4 3565.4 3891.3 3997.0 4095.8 3152.4 3771.9 3835.6 3997.9 4014.4 3052.4 3492.5 3497.9 3498.2 3533.7 5028.5 5102.2 5129.3 5138.0 5152.7 4199.3 4388.5 4415.9 4592.7 4602.8 4552.7 4614.1 4809.7 5098.5 5290.7 3848.2 3864.7 3867.7 3880.2 3912.7 5325.9 5544.6 5759.2 5771.4 5783.9 3552.3 3832.3 3843.9 3844.5 3851.2 3895.6 3896.7 3897.9 3911.0 3947.7 3847.5 4472.9 4522.0 4539.0 4559.6 4259.5 5374.4 5579.6 5589.4 5681.9 1176.9 1250.2 1256.4 1258.8 1265.9 696.9 792.8 821.9 857.2 862.4 4953.8 5179.6 5202.9 5223.0 5254.2 5578.1 5597.7 5609.7 5627.8 5646.4 4273.9 5096.2 5260.4 5271.0 5277.9 2643.0 2647.5 2835.4 2843.8 2887.5 4375.2 5121.8 5158.8 5543.3 5573.1 4899.8 5351.7 5535.4 5548.9 5591.4 4906.8 4950.0 5641.1 5964.6 5997.0 4760.5 5286.5 5762.7 5783.6 5786.4 5320.5 5667.7 5687.0 5705.3 5732.2 4825.8 5045.5 5617.8 5910.1 5974.9 3123.8 3271.6 3857.7 3866.3 3886.2 5617.5 5727.5 5763.4 5815.0 5817.2 5569.8 5576.9 5582.7 5597.2 5605.9 4457.8 5589.3 5593.9 5599.4 5653.2 5065.2 5098.8 5157.8 5509.9 5517.1 3992.4 5078.9 5376.0 5547.8 5650.9 5085.2 5205.7 5233.9 5459.9 5483.6 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - PROCESS | 1631 | [#5] yui.html Cycles:180 Average:18956.82 Median:51273.11 stddev:26851.61 (52.4%) stddev-sans-first:26891.92 08:28:47 INFO - PROCESS | 1631 | Values: 695.7 746.2 760.2 786.1 788.4 15369.4 15989.8 16047.0 16164.7 16391.4 14758.8 14840.0 14869.0 14896.3 14958.7 684.5 709.0 833.0 865.7 867.0 702.5 704.0 705.0 710.7 825.1 892.0 892.7 903.3 908.0 908.1 887.3 891.3 891.6 894.1 895.9 520.4 540.5 666.5 669.5 669.7 13569.5 14016.9 14119.0 14284.6 14327.3 13529.3 13707.0 13756.7 13892.8 13895.4 10505.8 10878.8 11094.3 11231.8 11440.1 3635.3 3666.7 3676.2 3770.7 3794.7 78765.0 78889.2 84502.0 84646.0 84713.6 10482.9 10492.7 10553.9 10577.8 10660.5 17251.7 20961.3 21046.3 21223.7 21262.5 6596.3 7134.6 7537.0 7659.8 7695.6 4087.5 4160.0 4169.1 4206.1 4233.7 3338.8 3454.3 3455.1 3457.6 3471.3 618.5 621.1 632.5 655.2 656.4 587.8 636.7 657.7 659.8 668.4 9343.8 9560.1 9595.1 9648.6 9858.0 646.4 647.7 650.0 662.3 671.8 3912.7 4207.7 4223.4 4328.7 4464.6 67761.0 69373.0 97956.6 102413.0 102992.0 62022.4 74011.9 88722.1 91783.0 94020.0 79442.0 82700.2 83104.6 84212.0 84633.0 8566.0 8609.4 8674.5 8822.1 8865.0 38698.4 50853.0 50895.7 51650.5 52734.7 69047.7 69330.3 71345.0 73215.5 73361.6 10365.6 10927.5 11019.6 11125.8 11185.1 9973.5 11732.2 11814.0 11825.5 12237.8 10871.6 11763.6 12268.3 12381.1 12382.6 9803.7 10215.1 11393.0 11439.4 12029.3 11806.9 11903.0 12744.7 13176.5 13203.2 10123.5 10395.8 10431.3 10503.7 10522.5 9204.4 9990.5 10269.8 10590.8 10797.2 08:28:47 INFO - PROCESS | 1631 | -------- Summary: end -------- 08:28:47 INFO - PROCESS | 1631 | 08:28:47 INFO - TEST-INFO | 1631: exit 0 08:28:47 INFO - TEST-OK | dromaeo_css | took 1120258ms 08:28:47 INFO - TEST-START | kraken 08:28:47 INFO - operating with platform_type : mac_ 08:28:47 INFO - Initialising browser for kraken test... 08:28:47 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmp3NJI3x/profile http://localhost:49242/getInfo.html) 08:28:50 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 08:28:50 INFO - PROCESS | 1640 | colorDepth:24 08:28:50 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 08:28:50 INFO - PROCESS | 1640 | __metrics 08:28:50 INFO - PROCESS | 1640 | 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" 08:28:50 INFO - PROCESS | 1640 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:28:51 INFO - TEST-INFO | 1640: exit 0 08:28:51 INFO - Browser initialized. 08:28:51 INFO - Running cycle 1/1 for kraken test... 08:28:51 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmp3NJI3x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:03 INFO - PROCESS | 1641 | RSS: Main: 222621696 08:29:03 INFO - PROCESS | 1641 | 08:29:06 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 08:29:07 INFO - PROCESS | 1641 | RSS: Main: 271941632 08:29:07 INFO - PROCESS | 1641 | 08:29:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 08:29:10 INFO - PROCESS | 1641 | RSS: Main: 282533888 08:29:10 INFO - PROCESS | 1641 | 08:29:13 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 08:29:14 INFO - PROCESS | 1641 | RSS: Main: 380633088 08:29:14 INFO - PROCESS | 1641 | 08:29:16 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 08:29:16 INFO - PROCESS | 1641 | RSS: Main: 282525696 08:29:16 INFO - PROCESS | 1641 | 08:29:18 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:29:19 INFO - PROCESS | 1641 | RSS: Main: 283791360 08:29:19 INFO - PROCESS | 1641 | 08:29:22 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 08:29:22 INFO - PROCESS | 1641 | RSS: Main: 324059136 08:29:22 INFO - PROCESS | 1641 | 08:29:25 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 08:29:26 INFO - PROCESS | 1641 | RSS: Main: 324440064 08:29:26 INFO - PROCESS | 1641 | 08:29:29 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 08:29:29 INFO - PROCESS | 1641 | RSS: Main: 323620864 08:29:29 INFO - PROCESS | 1641 | 08:29:31 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:29:32 INFO - PROCESS | 1641 | RSS: Main: 298774528 08:29:32 INFO - PROCESS | 1641 | 08:29:33 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:29:34 INFO - PROCESS | 1641 | RSS: Main: 299343872 08:29:34 INFO - PROCESS | 1641 | 08:29:36 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:29:37 INFO - PROCESS | 1641 | RSS: Main: 301957120 08:29:37 INFO - PROCESS | 1641 | 08:29:40 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:29:41 INFO - PROCESS | 1641 | RSS: Main: 309948416 08:29:41 INFO - PROCESS | 1641 | 08:29:44 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:29:44 INFO - PROCESS | 1641 | RSS: Main: 310104064 08:29:44 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 08:29:46 INFO - PROCESS | 1641 | RSS: Main: 353808384 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | __start_tp_report 08:29:46 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 08:29:46 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 08:29:46 INFO - PROCESS | 1641 | |i|pagename|runs| 08:29:46 INFO - PROCESS | 1641 | |0;ai-astar;119;167;169;115;114;115;114;114;114;115 08:29:46 INFO - PROCESS | 1641 | |1;audio-beat-detection;147;110;112;144;117;118;141;143;153;110 08:29:46 INFO - PROCESS | 1641 | |2;audio-dft;183;185;182;183;185;182;182;187;180;186 08:29:46 INFO - PROCESS | 1641 | |3;audio-fft;82;59;59;88;63;61;63;74;110;82 08:29:46 INFO - PROCESS | 1641 | |4;audio-oscillator;83;70;97;69;68;71;89;69;70;94 08:29:46 INFO - PROCESS | 1641 | |5;imaging-gaussian-blur;88;88;89;85;87;84;93;132;86;84 08:29:46 INFO - PROCESS | 1641 | |6;imaging-darkroom;92;93;93;93;93;93;93;92;94;93 08:29:46 INFO - PROCESS | 1641 | |7;imaging-desaturate;112;125;134;81;84;84;92;85;83;82 08:29:46 INFO - PROCESS | 1641 | |8;json-parse-financial;54;55;53;55;57;58;57;57;57;57 08:29:46 INFO - PROCESS | 1641 | |9;json-stringify-tinderbox;67;70;76;76;74;70;72;71;72;73 08:29:46 INFO - PROCESS | 1641 | |10;stanford-crypto-aes;85;82;84;84;85;85;83;83;84;84 08:29:46 INFO - PROCESS | 1641 | |11;stanford-crypto-ccm;142;147;129;147;150;159;144;144;225;168 08:29:46 INFO - PROCESS | 1641 | |12;stanford-crypto-pbkdf2;168;170;165;164;164;166;165;166;223;183 08:29:46 INFO - PROCESS | 1641 | |13;stanford-crypto-sha256-iterative;70;67;68;68;69;68;73;61;76;86 08:29:46 INFO - PROCESS | 1641 | __end_tp_report 08:29:46 INFO - PROCESS | 1641 | __start_cc_report 08:29:46 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,2077 08:29:46 INFO - PROCESS | 1641 | __end_cc_report 08:29:46 INFO - PROCESS | 1641 | __startTimestamp1481732986824__endTimestamp 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | ------- Summary: start ------- 08:29:46 INFO - PROCESS | 1641 | Number of tests: 14 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#0] ai-astar Cycles:10 Average:125.60 Median:115.00 stddev:22.40 (19.5%) stddev-sans-first:23.63 08:29:46 INFO - PROCESS | 1641 | Values: 119.0 167.0 169.0 115.0 114.0 115.0 114.0 114.0 114.0 115.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:142.00 stddev:17.44 (12.3%) stddev-sans-first:17.31 08:29:46 INFO - PROCESS | 1641 | Values: 147.0 110.0 112.0 144.0 117.0 118.0 141.0 143.0 153.0 110.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:2.17 (1.2%) stddev-sans-first:2.30 08:29:46 INFO - PROCESS | 1641 | Values: 183.0 185.0 182.0 183.0 185.0 182.0 182.0 187.0 180.0 186.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#3] audio-fft Cycles:10 Average:74.10 Median:68.50 stddev:16.60 (24.2%) stddev-sans-first:17.36 08:29:46 INFO - PROCESS | 1641 | Values: 82.0 59.0 59.0 88.0 63.0 61.0 63.0 74.0 110.0 82.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:77.00 stddev:11.56 (15.0%) stddev-sans-first:12.12 08:29:46 INFO - PROCESS | 1641 | Values: 83.0 70.0 97.0 69.0 68.0 71.0 89.0 69.0 70.0 94.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:87.50 stddev:14.45 (16.5%) stddev-sans-first:15.26 08:29:46 INFO - PROCESS | 1641 | Values: 88.0 88.0 89.0 85.0 87.0 84.0 93.0 132.0 86.0 84.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 08:29:46 INFO - PROCESS | 1641 | Values: 92.0 93.0 93.0 93.0 93.0 93.0 93.0 92.0 94.0 93.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:83.50 stddev:19.88 (23.8%) stddev-sans-first:20.24 08:29:46 INFO - PROCESS | 1641 | Values: 112.0 125.0 134.0 81.0 84.0 84.0 92.0 85.0 83.0 82.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:1.63 (2.9%) stddev-sans-first:1.56 08:29:46 INFO - PROCESS | 1641 | Values: 54.0 55.0 53.0 55.0 57.0 58.0 57.0 57.0 57.0 57.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.50 stddev:2.81 (3.9%) stddev-sans-first:2.29 08:29:46 INFO - PROCESS | 1641 | Values: 67.0 70.0 76.0 76.0 74.0 70.0 72.0 71.0 72.0 73.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:0.99 (1.2%) stddev-sans-first:0.97 08:29:46 INFO - PROCESS | 1641 | Values: 85.0 82.0 84.0 84.0 85.0 85.0 83.0 83.0 84.0 84.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:148.50 stddev:26.50 (17.8%) stddev-sans-first:27.66 08:29:46 INFO - PROCESS | 1641 | Values: 142.0 147.0 129.0 147.0 150.0 159.0 144.0 144.0 225.0 168.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:167.00 stddev:18.32 (11.0%) stddev-sans-first:19.33 08:29:46 INFO - PROCESS | 1641 | Values: 168.0 170.0 165.0 164.0 164.0 166.0 165.0 166.0 223.0 183.0 08:29:46 INFO - PROCESS | 1641 | 08:29:46 INFO - PROCESS | 1641 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:69.50 stddev:6.67 (9.6%) stddev-sans-first:7.07 08:29:46 INFO - PROCESS | 1641 | Values: 70.0 67.0 68.0 68.0 69.0 68.0 73.0 61.0 76.0 86.0 08:29:46 INFO - PROCESS | 1641 | -------- Summary: end -------- 08:29:46 INFO - PROCESS | 1641 | 08:29:47 INFO - TEST-INFO | 1641: exit 0 08:29:47 INFO - TEST-OK | kraken | took 60204ms 08:29:47 INFO - SUITE-END | took 1180s 08:29:48 INFO - Completed test suite (00:19:40) 08:29:48 INFO - javascript benchmark 08:29:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2851.2016635342106, 3303.388873058486, 3328.2844188499453, 3333.633300336963, 3394.456886898097, 5621.4940655341015, 5629.577781666371, 5651.095824668054, 5704.973104841127, 5728.627639203454, 5575.8606034849145, 5580.190872323511, 5623.100303951369, 5667.263255776754, 5673.659580425167, 5111.897762044757, 5137.5633071188995, 5148.897022059557, 5179.61152913533, 5190.92213616796, 5143.5113664201845, 5153.458886816887, 5164.509371609691, 5239.1093514102695, 5295.0, 4349.782510874444, 5213.921791173136, 5215.843524694265, 5233.528982391584, 5240.37115546135, 5163.096458119833, 5165.99263143689, 5187.195984622352, 5187.299714538527, 5196.818111366084, 5109.642325037249, 5167.870803229912, 5168.3539557555305, 5197.66215196011, 5200.167973124282, 4753.405824271966, 5513.117901135839, 5560.193771903092, 5687.061634830248, 5699.68651724155, 6085.3914608539235, 6099.939000609981, 6120.538559592075, 6178.876422471525, 6179.196704428396, 6222.222222222223, 6280.591761535531, 6288.496920246369, 6290.308066112724, 6293.622382657055, 5649.971750141305, 5710.086386178275, 5750.022496175702, 5791.936286709806, 5807.88384232313, 5196.298499702568, 6172.382761723792, 6262.279837818618, 6271.749130034838, 6302.275238347557, 6071.635701857815, 6093.75624974996, 6095.664738439428, 6097.054956481664, 6116.877662446726, 5371.543418809367, 5730.08318669011, 6216.533759968021, 6222.160008398899, 6241.126242326078, 5672.874774306876, 5686.2039314496005, 6128.3503509891225, 6160.537959653044, 6176.413240742122, 6221.129041934133, 6225.533085018642, 6226.813195604049, 6234.0337247726675, 6240.843978900564, 4884.072026314989, 4919.1391506486225, 4924.532169443897, 4927.384076990376, 4937.580305673986, 4357.368181613649, 4383.408239887638, 4408.0302333487225, 4427.512973572904, 4463.910721785611, 5774.971125144348, 5794.1308803679785, 5799.971000145057, 5840.036393994943, 5843.444872737083, 6212.689365531705, 6218.9378106218355, 6227.190465239581, 6228.5017198624, 6231.813045608639, 5609.411011843779, 5731.083026715705, 5777.075667893201, 5791.768329266866, 5799.2750906136735, 5342.679439233659, 5349.277847490617, 5353.4646535346155, 5358.089124848785, 5358.54452371545, 6230.532710046765, 6242.65665388408, 6270.0908368287255, 6274.843128921813, 6286.968565157145, 6230.6884655766125, 6259.405356491126, 6261.96869015652, 6263.311035786151, 6265.686715664289, 6298.08677741734, 6317.557770956173, 6319.146915166578, 6327.114204011534, 6329.367063293818, 4791.396992930316, 6069.939300607114, 6124.0, 6129.019356902874, 6191.504679625709, 6059.545534085049, 6156.784512542219, 6236.656983865931, 6238.812835614939, 6249.781257656142, 6113.266408031065, 6118.755249790137, 6125.203723515914, 6128.448439640454, 6141.570090093651, 5915.0, 5986.850328741816, 6103.6032657877095, 6110.236220472441, 6129.478994285358, 5047.798088076436, 5849.53203743708, 5885.823425297248, 5899.4985426239255, 5927.051671732502, 6137.560853825989, 6141.823559979257, 6147.508199343953, 6148.907766383597, 6157.692115394302, 5906.763729450774, 5948.970255148696, 5963.28440587115, 5964.045752679724, 5989.790357337472], "value": 5638.969959301109, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.3328091065678, 506.1976766824583, 519.5557798082639, 523.552362729866, 545.6453305351519, 30090.84954575227, 38600.0, 39775.20449591007, 39815.0, 40029.999250018765, 40547.0, 40882.59117408825, 40919.0, 41155.794221028926, 41194.79402602983, 9140.131687489678, 9313.0, 9318.953405232964, 9334.626614935394, 9346.485943273117, 9250.629974801, 9357.157855792977, 9361.251099912024, 9365.297602679791, 9368.953155234214, 9320.114589114057, 9376.203022743075, 9392.718218453458, 9402.529873506332, 9404.29467789915, 9309.487978161198, 9346.67286644971, 9364.204042656382, 9371.85942210864, 9386.812263754686, 9261.675841345588, 9271.0, 9321.673741419017, 9352.859707104399, 9395.436273823592, 36886.0, 37235.0, 37255.068623284365, 37287.0, 37351.62648373509, 3160.668129846356, 3636.5999740028574, 3780.111673756665, 3804.2771873343977, 3827.617238276178, 27939.58090628642, 28379.574306385213, 29412.26469338262, 29521.261968450744, 30069.69930300691, 3090.2892334762905, 3175.7935734177204, 3201.471757160066, 3204.5033019881957, 3242.983785081083, 514359.0, 519185.0, 519292.0, 519953.0, 520183.39908300596, 32518.674813251564, 33435.83282083574, 60119.69940150359, 60645.09032364518, 60832.0, 29310.0, 34096.0, 34610.134746631535, 34647.65352346495, 34678.653213468046, 12392.75214495705, 14073.507427239998, 14104.224267665171, 14105.0, 14139.85860141406, 11869.703257418634, 11975.0, 11977.281363118242, 11982.64052078439, 11987.820182697267, 7601.239876012354, 7608.429367797326, 7638.083429988437, 7764.767056988299, 7772.028496437945, 3162.6204855417495, 3216.2281052547687, 3216.5818443602184, 3220.838958052088, 3263.7878537894962, 793.2305663506389, 857.5969294431607, 1003.372891942536, 1007.6120693532896, 1036.5905467340508, 27979.860100699774, 28882.711172888, 29441.0, 29512.852435737685, 29543.113706588836, 843.8945131858518, 1009.7475631092227, 1016.9694909152737, 1039.1998161415668, 1076.9892301076889, 14982.475613353481, 15017.549473515812, 15277.694446111016, 15379.61550961235, 15410.845891541165, 639068.0, 641967.0, 643463.0, 645457.0, 646614.0, 54275.72862135664, 54944.45055549473, 54966.0, 54966.90066198654, 55013.0, 1351.3716122003316, 1365.7268546290782, 1390.5202705066736, 1390.986090139106, 1395.232622057864, 28287.434251314447, 28980.565291521067, 29031.854840726504, 29174.56238156472, 29533.114006579835, 47797.522024779304, 47983.840727166666, 48108.518914810404, 48121.518784811706, 48191.5180848187, 576594.0, 580304.0, 582401.0, 583190.0, 583927.0, 25324.620130698422, 25685.871570642026, 25738.613920791577, 25749.227523174333, 25836.8708156458, 43470.0, 43585.0, 44241.0, 44670.77664611656, 44913.77543112264, 36296.09259768527, 36743.0, 36823.07942301464, 36842.631573684994, 37269.6273037277, 30063.398732025675, 31935.201619959687, 36321.636783631824, 36532.634673653985, 36969.70606028776, 28176.29559261035, 31188.532172016985, 32008.0, 35944.101397465274, 36509.634903651684, 31751.523727144573, 32731.34537309288, 33374.499382508795, 33384.49923251104, 33419.83290083534, 24651.753482465665, 24864.12975545847, 24916.75083249144, 25187.0, 25315.87342063278], "value": 17999.915005183142, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.2383874669462, 370.80532720321827, 375.352516908333, 383.48038407957216, 404.36110944707366, 14312.0697154685, 14450.421983120676, 15063.62340941477, 18767.812321876776, 19456.610867782634, 18102.637947241048, 18300.908495457537, 19047.238110475566, 19264.614707705874, 19302.131404086816, 5017.949820501803, 5295.205719142121, 5295.761690723926, 5297.629165958385, 5311.282976798121, 4639.953600464003, 4853.296272040569, 5101.923471147936, 5420.972895135519, 5455.263539422167, 5277.815276465324, 5296.364436267634, 5336.386315573701, 5393.703346315951, 5459.508644222019, 5163.173892177252, 5272.0, 5321.787128514855, 5330.147176451788, 5388.649737767052, 5160.638755287131, 5174.896502069938, 5182.170852663593, 5182.740862956837, 5186.611004174702, 12511.186772859735, 12579.308138052404, 12741.490340386374, 12917.354132293442, 12952.48190072396, 2126.669043939939, 2133.98933005334, 2172.8587641803233, 2417.7944874685672, 2504.474060447308, 9826.16477599405, 9841.0, 9842.852357214648, 10308.896911030944, 10347.689569312932, 1793.7668103146639, 1823.480308112195, 1859.665260253154, 1877.662020836249, 1879.4643526594991, 90961.54519227428, 117007.99999999914, 120365.39817300858, 124795.0, 127647.36176319151, 11195.440227988567, 11305.095592352593, 11338.263012904135, 11399.48702308398, 11550.249233799776, 14504.0, 14531.982761206826, 14536.982411231324, 14540.49108281205, 14548.272586370638, 7948.483348582324, 8179.263866252066, 8187.0994190638985, 8273.8345233095, 8295.543745093957, 7934.0, 7946.40401969843, 7953.204679532117, 8058.637361318754, 8115.594220288962, 4407.757573333497, 4415.712978656377, 4418.734875907456, 4433.290673492225, 4500.594946454769, 1689.729643257073, 1848.741176235328, 1888.9622207556047, 1895.772507299133, 1923.1057558235489, 735.7498450526847, 754.8226166850785, 798.9680412783424, 850.2475309351269, 881.9514926679094, 19589.804101959082, 20800.167993280404, 21161.68257476139, 21180.25869094605, 21314.36056918295, 851.1488511488511, 872.7250915961451, 943.235978857128, 971.8347880860271, 1005.7234260578399, 8914.242289405343, 11181.161412894066, 11194.888051119384, 11197.104231661448, 11198.552057917756, 489116.0, 585138.0, 586015.0, 600786.0, 603955.9604403899, 104776.0, 119962.80037199517, 130567.0, 134533.0, 134891.0, 903.3315346643436, 925.9212966897754, 952.6998995316453, 954.2175416158683, 954.4321128928276, 18801.247950081844, 19175.32886348971, 19482.902585486983, 19494.61010779748, 19533.12100955432, 44214.67355979326, 55053.72473137609, 55970.481328341135, 56364.71817640885, 56732.14901776394, 58455.70772146112, 62608.37391626026, 78809.48333616591, 81017.59491202506, 81808.18191818005, 7866.466039122316, 7878.842423151619, 8019.6391162398995, 8135.430519863553, 8171.42800003994, 11950.423617610157, 12663.303518306582, 12967.935160324138, 13030.348482576022, 13203.943684505408, 10906.836397454203, 11007.724806879572, 11064.0, 11207.0, 11369.147313951653, 9121.0, 10747.570097196025, 10813.243072984815, 10849.240553161519, 11392.202545821712, 10983.121350291798, 11023.559057637605, 11069.667909962714, 11523.423828808358, 11592.710182245512, 7860.882086768817, 7898.486598371088, 7915.564643944638, 8136.593170341577, 8304.169583041888, 8174.468659537111, 10362.06741393248, 10980.505877235384, 11110.16673749456, 11279.887201128213], "value": 9016.393647911755, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.9563513094608, 1501.0543357684658, 1656.5941344370624, 1793.0, 1848.039019709751, 7971.760847174587, 8255.422120451563, 8262.876056859144, 8275.21385468381, 8421.663133474654, 6052.576319657625, 7603.315701586856, 7610.200928902468, 7977.0826354969195, 8200.876986845202, 2287.5653625811124, 2332.1021406758437, 2336.4509340305017, 2337.7662233776655, 2338.251759436981, 2318.9420264493356, 2348.8003519700846, 2351.870647114408, 2367.242482405631, 2372.5492156490213, 2041.061111888533, 2122.1617461102855, 2132.2537112010828, 2260.1863329201474, 2281.4866655002656, 2253.954920901581, 2259.4690247791755, 2263.1173842201556, 2272.749997500274, 2273.1362082408746, 2030.8172264496118, 2286.6798648189265, 2310.330004298751, 2322.7096612923383, 2351.0713268259115, 8133.593320334019, 8225.588720563948, 8254.0, 8291.419600627958, 8300.377471689648, 5029.949700502984, 5192.740362981874, 5193.766280517386, 5194.480551944813, 5209.869753256161, 3361.4957756336503, 3439.8280085995602, 3468.6704763047724, 3529.4000019996406, 3530.082178633548, 1002.2783595811004, 1054.7152268887357, 1065.7495488560182, 1080.9189310801721, 1084.2518662123189, 10737.0, 10749.785004299949, 10783.406912619803, 10786.244962852681, 10792.46037698112, 4511.70673906195, 4567.223571992703, 4657.604103651228, 4716.221823399169, 4726.621870250372, 9372.625094996123, 9408.435493870253, 9413.670521531714, 9417.670381536613, 9435.198008169245, 5140.742962851842, 5149.098907691139, 5153.948460515347, 5154.072266991902, 5159.948400516022, 4614.538546145426, 4661.277501987197, 4662.393888794435, 4669.766511674403, 4749.715017098985, 3736.7944763038295, 3756.0, 3845.538535375773, 3862.1696335288048, 3877.185790983925, 1238.4241327782624, 1243.8320826688462, 1246.7880460321767, 1256.2901960392348, 1263.8862502374645, 726.3753172271842, 816.9339012588629, 868.5049521772655, 882.8410886040574, 892.4466830565091, 5165.509276618715, 5341.839744807662, 5564.332280126329, 5613.915791263134, 5689.9146512802345, 446.6783915580777, 449.6200710399707, 450.263818656499, 450.43695380774034, 453.5283305362386, 3746.7189960753053, 3754.1928485375774, 3790.9431358529646, 3794.9810250948567, 3876.7673939563715, 9392.67125650599, 9780.413175209367, 10006.949965250127, 10143.898561014443, 10160.796784064278, 9744.658936937172, 10064.597416103421, 10086.394816311045, 10107.039831216023, 10314.329568578169, 6716.597004179765, 7694.269044440769, 7795.883061754078, 7804.882926756103, 7813.0, 5360.973195133999, 5401.135818269058, 5525.11598144295, 5593.07714227148, 5632.127020311858, 8063.032436107705, 9289.442633442015, 9328.207102396373, 9336.11306925841, 9484.383515071499, 8704.738857834274, 9334.159925606456, 9384.859227111463, 9525.809483810424, 9526.0, 7019.087518622547, 7132.643367831692, 7139.464540159613, 7152.284771523015, 7162.247963963917, 7614.771556853303, 7717.768466946, 7807.843843123219, 7825.530468171928, 7833.843323133619, 7090.822729431806, 7101.786946391617, 7119.8932016020835, 7137.429005679637, 7279.636018199175, 6910.205326387329, 6933.0, 6941.965290173517, 6942.479314051366, 6950.443964482728, 6428.4214420702365, 6548.050532672641, 6633.767818126343, 6657.101291325609, 6659.567128136656, 4616.769161541976, 4744.098621261949, 4781.521847815191, 4800.0639875224, 4820.542048505386, 4636.513166117644, 4647.814087436464, 4795.376601041982, 4805.927911081267, 4867.269909513459], "value": 4289.291604529884, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.62526775148683, 297.95083811171156, 307.08794879208756, 307.919940815388, 312.67426113279674, 4407.338899165127, 4870.537298956593, 5277.340332458442, 5386.434424385442, 5391.784328626855, 4594.37975873257, 5268.604854635899, 5273.762680679369, 5296.629235953485, 5338.0, 3767.8304476298563, 3770.359038963383, 3905.882823515299, 3917.5103112110987, 3976.403539469088, 3654.963450365502, 3872.625218047595, 4002.979985100085, 4021.77880216588, 4078.9184216315803, 3412.4198886189406, 3565.3599344301524, 3891.268385568423, 3996.960030399702, 4095.795210239491, 3152.36952609479, 3771.924561508768, 3835.6356146166067, 3997.900052498682, 4014.417909403149, 3052.3590046090126, 3492.4761285807076, 3497.8600855965733, 3498.2303893143467, 3533.6643018913164, 5028.522290382444, 5102.209157580581, 5129.333186685744, 5137.974310128426, 5152.716600586966, 4199.265128602513, 4388.539203383662, 4415.911681766379, 4592.678512504126, 4602.838900638495, 4552.68131230814, 4614.077184563101, 4809.663323567352, 5098.5411312982005, 5290.650884024588, 3848.1918797052654, 3864.7294689371756, 3867.7292589518743, 3880.165764360676, 3912.726109172359, 5325.934813037408, 5544.63959842609, 5759.164921086419, 5771.422857714195, 5783.884322313572, 3552.2717842842158, 3832.3101841668768, 3843.9231215375535, 3844.5386553613744, 3851.171998020383, 3895.5714871364125, 3896.7077469189926, 3897.902552436212, 3910.980445097813, 3947.684185265172, 3847.5382954045695, 4472.888177795581, 4522.0, 4539.0, 4559.589636932692, 4259.488861336659, 5374.355077390738, 5579.553635709133, 5589.441055894459, 5681.943180568224, 1176.8819621359742, 1250.2436026204016, 1256.434604428011, 1258.7985922252396, 1265.936703164838, 696.8710688684364, 792.7899106736719, 821.8986558012292, 857.2070834478083, 862.4351050062219, 4953.777080031407, 5179.559737422362, 5202.895942081137, 5223.0, 5254.159334506537, 5578.135389014709, 5597.720113994284, 5609.663420194802, 5627.803026894039, 5646.378898321182, 4273.9358909616385, 5096.184610462308, 5260.422393027389, 5270.973645131827, 5277.920831187535, 2643.0, 2647.5234457797396, 2835.3631123055757, 2843.8482414622113, 2887.4946884295164, 4375.212461756851, 5121.846344609593, 5158.781723858701, 5543.307086614173, 5573.098830818362, 4899.779509922062, 5351.705656188947, 5535.363433205152, 5548.861278468071, 5591.3569939456665, 4906.822362632943, 4949.980511887818, 5641.097424412074, 5964.612300200473, 5997.0, 4760.452547956891, 5286.524212820865, 5762.723724128862, 5783.595148339576, 5786.383510306058, 5320.5477534408465, 5667.659940403589, 5687.037971899688, 5705.258316418839, 5732.197492350991, 4825.758712064453, 5045.520675535819, 5617.8033768817895, 5910.054391297372, 5974.9402505974385, 3123.7813353065976, 3271.607407111162, 3857.6883859487907, 3866.342721737255, 3886.1839013806284, 5617.522510586646, 5727.513161381163, 5763.423657634203, 5815.011448053926, 5817.214676018683, 5569.805056822992, 5576.86057848557, 5582.720863956704, 5597.244372009726, 5605.915911261416, 4457.7771111444945, 5589.329280486368, 5593.916091258553, 5599.440055994531, 5653.208550802973, 5065.2018533420105, 5098.796048158032, 5157.7679004444135, 5509.862253443696, 5517.096601672417, 3992.3811809169624, 5078.949210507848, 5376.005438993642, 5547.75596668557, 5650.85872853182, 5085.211792172219, 5205.687658740488, 5233.927044955699, 5459.945400546103, 5483.616146869681], "value": 3993.3243770062745, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.6834640238691, 746.2462912458419, 760.182803486252, 786.0959896119465, 788.3535500889271, 15369.385224591017, 15989.84010159898, 16047.0, 16164.676706465878, 16391.4263000795, 14758.778618320734, 14840.035397699168, 14869.0, 14896.329665165067, 14958.7008259835, 684.4968947823346, 709.0427922304899, 832.9916700832974, 865.6537385046, 866.9653213871437, 702.5222848463043, 704.0425021970142, 704.9788506344818, 710.7441321124392, 825.1130035212141, 891.9732408027737, 892.7098692924793, 903.3405613901856, 907.9773005674845, 908.1236606674557, 887.3211992825461, 891.2958762577556, 891.6477991193442, 894.065701342099, 895.8656201569752, 520.4327283261264, 540.4514417865869, 666.4768156996735, 669.5212922760201, 669.6584741781678, 13569.45722171112, 14016.859831401658, 14119.0, 14284.57146285613, 14327.283635818167, 13529.255890925995, 13707.0, 13756.713839843573, 13892.791608125886, 13895.444182232699, 10505.842412363821, 10878.836817447745, 11094.278871873383, 11231.775364492745, 11440.084793216523, 3635.348985530369, 3666.651668091527, 3676.2096149327754, 3770.7360484766077, 3794.7343685941996, 78765.0, 78889.21110788934, 84501.99999999939, 84646.0, 84713.57643211806, 10482.947585262178, 10492.73768155787, 10553.894461055444, 10577.84133238002, 10660.520276587573, 17251.65496690059, 20961.266355677784, 21046.263380781904, 21223.68164477534, 21262.46843828917, 6596.340365963365, 7134.607596582237, 7536.962315188389, 7659.808504787314, 7695.576743279062, 4087.4686290782006, 4159.9792001039805, 4169.10364271677, 4206.053637931441, 4233.703640745119, 3338.7662863599317, 3454.29187016661, 3455.1016735648914, 3457.567804024497, 3471.2710330830305, 618.4773866083152, 621.1459243540132, 632.4750457120579, 655.1875674164003, 656.3502132888406, 587.8060240120835, 636.6625688385163, 657.6911945229056, 659.7690808217086, 668.4218151299091, 9343.766405839908, 9560.139587437165, 9595.13643772064, 9648.565814538362, 9858.0, 646.360103497534, 647.6729251727847, 649.9935000649933, 662.2946561911644, 671.8118926700531, 3912.68698504119, 4207.726497777635, 4223.429836972031, 4328.675349348811, 4464.598186163261, 67761.0, 69372.95940560896, 97956.57151999646, 102412.97587024183, 102992.0, 62022.379776202564, 74011.88982165272, 88722.11277887267, 91783.0, 94020.0, 79442.01394965172, 82700.17299827046, 83104.58447707722, 84212.0, 84633.0, 8565.957170214358, 8609.440386374865, 8674.479531228146, 8822.117788221127, 8864.9507309687, 38698.41952370773, 50852.982940341724, 50895.72760680864, 51650.48349516457, 52734.68163295948, 69047.65476172788, 69330.31800430518, 71345.0, 73215.53568928697, 73361.6331918337, 10365.585376584851, 10927.508262128064, 11019.614313498989, 11125.83311250348, 11185.105191584817, 9973.50132493387, 11732.248439382063, 11814.0, 11825.467853946766, 12237.75524489523, 10871.565137394417, 11763.647090587296, 12268.263904165779, 12381.071419643742, 12382.62852114438, 9803.656872009446, 10215.131713804112, 11393.0, 11439.42802859837, 12029.338386388832, 11806.881931180578, 11903.0, 12744.68138296513, 13176.47294108225, 13203.207807531579, 10123.493825308853, 10395.792084158124, 10431.321964072311, 10503.684889453329, 10522.461903819452, 9204.401713888576, 9990.450525221182, 10269.845952310572, 10590.841137382791, 10797.244192906735], "value": 7041.860746677692, "unit": "score"}], "name": "dromaeo_css", "value": 6926.160881501507, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 167.0, 169.0, 115.0, 114.0, 115.0, 114.0, 114.0, 114.0, 115.0], "value": 125.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 112.0, 144.0, 117.0, 118.0, 141.0, 143.0, 153.0, 110.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 182.0, 183.0, 185.0, 182.0, 182.0, 187.0, 180.0, 186.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 88.0, 63.0, 61.0, 63.0, 74.0, 110.0, 82.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 97.0, 69.0, 68.0, 71.0, 89.0, 69.0, 70.0, 94.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 85.0, 87.0, 84.0, 93.0, 132.0, 86.0, 84.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0, 94.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 125.0, 134.0, 81.0, 84.0, 84.0, 92.0, 85.0, 83.0, 82.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 53.0, 55.0, 57.0, 58.0, 57.0, 57.0, 57.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 76.0, 76.0, 74.0, 70.0, 72.0, 71.0, 72.0, 73.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 84.0, 84.0, 85.0, 85.0, 83.0, 83.0, 84.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 129.0, 147.0, 150.0, 159.0, 144.0, 144.0, 225.0, 168.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 165.0, 164.0, 164.0, 166.0, 165.0, 166.0, 223.0, 183.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 68.0, 69.0, 68.0, 73.0, 61.0, 76.0, 86.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1482.9, "alertThreshold": 2.0}]} 08:29:48 INFO - Return code: 0 08:29:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:29:48 INFO - # TBPL SUCCESS # 08:29:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:29:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:29:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:29:48 INFO - 2016-12-14 08:29:48.625 screenresolution[1645:10510] starting screenresolution argv=screenresolution get 08:29:48 INFO - 2016-12-14 08:29:48.628 screenresolution[1645:10510] Display 0: 1600x1200x32@60 08:29:48 INFO - 2016-12-14 08:29:48.634 screenresolution[1646:10513] starting screenresolution argv=screenresolution list 08:29:48 INFO - Available Modes on Display 0 08:29:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:29:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:29:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:29:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:29:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:29:48 INFO - Intel Iris: 08:29:48 INFO - Chipset Model: Intel Iris 08:29:48 INFO - Type: GPU 08:29:48 INFO - Bus: Built-In 08:29:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:29:48 INFO - Vendor: Intel (0x8086) 08:29:48 INFO - Device ID: 0x0a2e 08:29:48 INFO - Revision ID: 0x0009 08:29:48 INFO - Displays: 08:29:48 INFO - Display: 08:29:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:29:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:29:48 INFO - Display Serial Number: 1600x1200 60 08:29:48 INFO - Main Display: Yes 08:29:48 INFO - Mirror: Off 08:29:48 INFO - Online: Yes 08:29:48 INFO - Rotation: Supported 08:29:48 INFO - Return code: 0 08:29:48 INFO - Running post-action listener: _resource_record_post_action 08:29:48 INFO - [mozharness: 2016-12-14 16:29:48.868358Z] Finished run-tests step (success) 08:29:48 INFO - Running post-run listener: _resource_record_post_run 08:29:48 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37495808; Write bytes: 658991104; Read time: 235; Write time: 1900 08:29:48 INFO - TinderboxPrint: CPU usage
53.3% 08:29:48 INFO - TinderboxPrint: I/O read bytes / time
37,495,808 / 235 08:29:48 INFO - TinderboxPrint: I/O write bytes / time
658,991,104 / 1,900 08:29:48 INFO - TinderboxPrint: CPU idle
2,265.8 (46.7%) 08:29:48 INFO - TinderboxPrint: CPU system
968.5 (20.0%) 08:29:48 INFO - TinderboxPrint: CPU user
1,615.5 (33.3%) 08:29:48 INFO - TinderboxPrint: Swap in / out
386,007,040 / 0 08:29:48 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349688320; Write bytes: 351750144; Read time: 26800; Write time: 754 08:29:48 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 34333696; Write bytes: 305901568; Read time: 212; Write time: 1139 08:29:49 INFO - Running post-run listener: _upload_blobber_files 08:29:49 INFO - Blob upload gear active. 08:29:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:29:49 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 08:29:49 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'] 08:29:49 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 08:29:49 INFO - (blobuploader) - INFO - Open directory for files ... 08:29:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:29:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:29:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:50 INFO - (blobuploader) - INFO - Done attempting. 08:29:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:29:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:29:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:51 INFO - (blobuploader) - INFO - Done attempting. 08:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:29:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:53 INFO - (blobuploader) - INFO - Done attempting. 08:29:53 INFO - (blobuploader) - INFO - Iteration through files over. 08:29:53 INFO - Return code: 0 08:29:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:29:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:29:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02322029b961c8eee671b1551453878e838c5a68fe1f562438bc78bb63861773cfc54ec834a833f77eea66899489923752b69ae78d71a065e1541a74eb16f8fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/faaf70eea59414bbdb94759cc9fb55393fc0bd759d19e6f20b4644b56d51ae659d5eb79822ef3477be1c1f23151836ef8d546afac46bc70a70a63a1f44a91494", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3ec35d4f5397ea3fe8f1947c24c6d0a697426b5dd7a269cbbd7736da0b24d99801fea9c6ab841e8927252eba0ead6b6e8c9a83f99e7a52b604bb0e82ccf145f"} 08:29:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:29:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:29:53 INFO - Contents: 08:29:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02322029b961c8eee671b1551453878e838c5a68fe1f562438bc78bb63861773cfc54ec834a833f77eea66899489923752b69ae78d71a065e1541a74eb16f8fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/faaf70eea59414bbdb94759cc9fb55393fc0bd759d19e6f20b4644b56d51ae659d5eb79822ef3477be1c1f23151836ef8d546afac46bc70a70a63a1f44a91494", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3ec35d4f5397ea3fe8f1947c24c6d0a697426b5dd7a269cbbd7736da0b24d99801fea9c6ab841e8927252eba0ead6b6e8c9a83f99e7a52b604bb0e82ccf145f"} 08:29:53 INFO - Running post-run listener: copy_logs_to_upload_dir 08:29:53 INFO - Copying logs to upload dir... 08:29:53 INFO - mkdir: /builds/slave/test/build/upload/logs 08:29:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.878769 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-14 08:29:53.652078) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 08:29:53.655218) ========= 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.QuExDRTZRU/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.wcs7LgVfzd/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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/02322029b961c8eee671b1551453878e838c5a68fe1f562438bc78bb63861773cfc54ec834a833f77eea66899489923752b69ae78d71a065e1541a74eb16f8fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/faaf70eea59414bbdb94759cc9fb55393fc0bd759d19e6f20b4644b56d51ae659d5eb79822ef3477be1c1f23151836ef8d546afac46bc70a70a63a1f44a91494", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3ec35d4f5397ea3fe8f1947c24c6d0a697426b5dd7a269cbbd7736da0b24d99801fea9c6ab841e8927252eba0ead6b6e8c9a83f99e7a52b604bb0e82ccf145f"} build_url:https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010933 build_url: 'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/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/02322029b961c8eee671b1551453878e838c5a68fe1f562438bc78bb63861773cfc54ec834a833f77eea66899489923752b69ae78d71a065e1541a74eb16f8fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/faaf70eea59414bbdb94759cc9fb55393fc0bd759d19e6f20b4644b56d51ae659d5eb79822ef3477be1c1f23151836ef8d546afac46bc70a70a63a1f44a91494", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3ec35d4f5397ea3fe8f1947c24c6d0a697426b5dd7a269cbbd7736da0b24d99801fea9c6ab841e8927252eba0ead6b6e8c9a83f99e7a52b604bb0e82ccf145f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 08:29:53.690045) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:29:53.690454) ========= 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.QuExDRTZRU/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.wcs7LgVfzd/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.005049 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:29:53.739947) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 08:29:53.740275) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 08:29:53.740603) ========= ========= Total master_lag: 0.19 =========