builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0257 starttime: 1479149968.63 results: success (0) buildid: 20161114081542 builduid: c0b4dd1356ae41cf97e49288305ed2d3 revision: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:28.626963) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:28.627638) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:28.627946) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:28.653569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:28.654063) ========= 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.YU6q0FtUkb/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.oed07GvU7R/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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-11-14 10:59:28-- 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% 155M=0s 2016-11-14 10:59:28 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128551 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:28.805172) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:28.805484) ========= 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.YU6q0FtUkb/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.oed07GvU7R/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.027811 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:28.854021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 10:59:28.854394) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d --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 f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YU6q0FtUkb/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.oed07GvU7R/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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-11-14 10:59:28,944 truncating revision to first 12 chars 2016-11-14 10:59:28,944 Setting DEBUG logging. 2016-11-14 10:59:28,945 attempt 1/10 2016-11-14 10:59:28,945 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f11ca24b2382?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 10:59:29,918 unpacking tar archive at: mozilla-aurora-f11ca24b2382/testing/mozharness/ program finished with exit code 0 elapsedTime=1.256924 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 10:59:30.133150) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:30.133473) ========= script_repo_revision: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:30.133865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:30.134149) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:59:30.152745) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 10:59:30.153162) ========= /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.YU6q0FtUkb/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.oed07GvU7R/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 10:59:30 INFO - MultiFileLogger online at 20161114 10:59:30 in /builds/slave/test 10:59:30 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 10:59:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:59:30 INFO - {'append_to_log': False, 10:59:30 INFO - 'base_work_dir': '/builds/slave/test', 10:59:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:59:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:59:30 INFO - 'branch': 'Mozilla-Aurora', 10:59:30 INFO - 'buildbot_json_path': 'buildprops.json', 10:59:30 INFO - 'config_files': ('talos/mac_config.py',), 10:59:30 INFO - 'default_actions': ('clobber', 10:59:30 INFO - 'read-buildbot-config', 10:59:30 INFO - 'download-and-extract', 10:59:30 INFO - 'populate-webroot', 10:59:30 INFO - 'create-virtualenv', 10:59:30 INFO - 'install', 10:59:30 INFO - 'run-tests'), 10:59:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:59:30 INFO - 'download_minidump_stackwalk': True, 10:59:30 INFO - 'download_symbols': 'ondemand', 10:59:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:59:30 INFO - 'tooltool.py': '/tools/tooltool.py', 10:59:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:59:30 INFO - '/tools/misc-python/virtualenv.py')}, 10:59:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:59:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:59:30 INFO - 'installer_path': 'installer.exe', 10:59:30 INFO - 'log_level': 'info', 10:59:30 INFO - 'log_name': 'talos', 10:59:30 INFO - 'log_to_console': True, 10:59:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:59:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:59:30 INFO - 'opt_config_files': (), 10:59:30 INFO - 'pip_index': False, 10:59:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:59:30 INFO - 'cmd': ('bash', 10:59:30 INFO - '-c', 10:59:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:59:30 INFO - 'enabled': True, 10:59:30 INFO - 'halt_on_failure': False, 10:59:30 INFO - 'name': 'check_screen_resolution'},), 10:59:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:59:30 INFO - 'cmd': ('bash', 10:59:30 INFO - '-c', 10:59:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:59:30 INFO - 'enabled': True, 10:59:30 INFO - 'halt_on_failure': False, 10:59:30 INFO - 'name': 'check_screen_resolution'},), 10:59:30 INFO - 'run_cmd_checks_enabled': True, 10:59:30 INFO - 'sps_profile': False, 10:59:30 INFO - 'sps_profile_interval': 0, 10:59:30 INFO - 'suite': 'dromaeojs', 10:59:30 INFO - 'system_bits': '32', 10:59:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:59:30 INFO - 'title': 't-yosemite-r7-0257', 10:59:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:59:30 INFO - 'use_talos_json': True, 10:59:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:59:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:59:30 INFO - 'work_dir': 'build'} 10:59:30 INFO - [mozharness: 2016-11-14 18:59:30.365240Z] Running clobber step. 10:59:30 INFO - Running pre-action listener: _resource_record_pre_action 10:59:30 INFO - Running main action method: clobber 10:59:30 INFO - rmtree: /builds/slave/test/build 10:59:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:59:32 INFO - Running post-action listener: _resource_record_post_action 10:59:32 INFO - [mozharness: 2016-11-14 18:59:32.321702Z] Finished clobber step (success) 10:59:32 INFO - [mozharness: 2016-11-14 18:59:32.321849Z] Running read-buildbot-config step. 10:59:32 INFO - Running pre-action listener: _resource_record_pre_action 10:59:32 INFO - Running main action method: read_buildbot_config 10:59:32 INFO - Using buildbot properties: 10:59:32 INFO - { 10:59:32 INFO - "project": "", 10:59:32 INFO - "product": "firefox", 10:59:32 INFO - "script_repo_revision": "production", 10:59:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:59:32 INFO - "repository": "", 10:59:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:59:32 INFO - "buildid": "20161114081542", 10:59:32 INFO - "pgo_build": "False", 10:59:32 INFO - "basedir": "/builds/slave/test", 10:59:32 INFO - "buildnumber": 28, 10:59:32 INFO - "slavename": "t-yosemite-r7-0257", 10:59:32 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:59:32 INFO - "platform": "yosemite_r7", 10:59:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:59:32 INFO - "branch": "mozilla-aurora", 10:59:32 INFO - "repo_path": "releases/mozilla-aurora", 10:59:32 INFO - "revision": "f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d", 10:59:32 INFO - "stage_platform": "macosx64", 10:59:32 INFO - "builduid": "c0b4dd1356ae41cf97e49288305ed2d3", 10:59:32 INFO - "slavebuilddir": "test" 10:59:32 INFO - } 10:59:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:59:32 INFO - Running post-action listener: _resource_record_post_action 10:59:32 INFO - [mozharness: 2016-11-14 18:59:32.325117Z] Finished read-buildbot-config step (success) 10:59:32 INFO - [mozharness: 2016-11-14 18:59:32.325232Z] Running download-and-extract step. 10:59:32 INFO - Running pre-action listener: _resource_record_pre_action 10:59:32 INFO - Running main action method: download_and_extract 10:59:32 INFO - mkdir: /builds/slave/test/build/tests 10:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:59:32 INFO - trying https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:59:32 INFO - Downloading https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 10:59:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 10:59:33 INFO - Downloaded 1199 bytes. 10:59:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:59:33 INFO - Using the following test package requirements: 10:59:33 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:59:33 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:59:33 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:59:33 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:59:33 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:59:33 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:59:33 INFO - u'jsshell-mac.zip'], 10:59:33 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:59:33 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:59:33 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:59:33 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:59:33 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:59:33 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:59:33 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:59:33 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:59:33 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:59:33 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:59:33 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:59:33 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:59:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:59:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:59:33 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:59:34 INFO - Http code: 200 10:59:34 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 10:59:34 INFO - x-amz-cf-id: v5RFDaqZsAfVjLlE5g4f-iWfj5B9w1pbyStImDCNfsil_GYWcw3u5g== 10:59:34 INFO - x-amz-version-id: beIhMZs6B_1lhCUU5tXTJZFEtB99JGu9 10:59:34 INFO - Expected file size: 16327504 10:59:34 INFO - Obtained file size: 16327504 10:59:41 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 10:59:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:59:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:59:41 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:59:41 INFO - Http code: 200 10:59:41 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 10:59:41 INFO - x-amz-cf-id: iRTDPnu7p5dRD6zawWaKbY0KOWT2NGgLlDHqYm2KiotbGlBcjFXvOg== 10:59:41 INFO - x-amz-version-id: beIhMZs6B_1lhCUU5tXTJZFEtB99JGu9 10:59:42 INFO - Expected file size: 16327504 10:59:42 INFO - Obtained file size: 16327504 10:59:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:59:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:59:48 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:59:49 INFO - Http code: 200 10:59:49 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 10:59:49 INFO - x-amz-cf-id: Rj7gaIKqOotmaNwk8beCwYrRcGkqaHbit37-cJXorPonwyhUdcdSxg== 10:59:49 INFO - x-amz-version-id: fL.n5SYgNX8ZLi3RUYlMNYmEt036hZfV 10:59:50 INFO - Expected file size: 14409038 10:59:50 INFO - Obtained file size: 14409038 10:59:50 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')]} 10:59:50 INFO - trying https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:59:50 INFO - Downloading https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 10:59:53 INFO - Downloaded 114359577 bytes. 10:59:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:59:53 INFO - mkdir: /builds/slave/test/properties 10:59:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:59:53 INFO - Writing to file /builds/slave/test/properties/build_url 10:59:53 INFO - Contents: 10:59:53 INFO - build_url:https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:59:54 INFO - Running post-action listener: _resource_record_post_action 10:59:54 INFO - Running post-action listener: set_extra_try_arguments 10:59:54 INFO - [mozharness: 2016-11-14 18:59:54.180570Z] Finished download-and-extract step (success) 10:59:54 INFO - [mozharness: 2016-11-14 18:59:54.180721Z] Running populate-webroot step. 10:59:54 INFO - Running pre-action listener: _resource_record_pre_action 10:59:54 INFO - Running main action method: populate_webroot 10:59:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:59:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:59:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:59:54 INFO - '--tppagecycles', 10:59:54 INFO - '7'], 10:59:54 INFO - 'tests': ['tsvgm']}}, 10:59:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:59:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:59:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:59:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:59:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:59:54 INFO - 'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:59:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:59:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:59:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:59:54 INFO - 'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['damp', 'tps']}, 10:59:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:59:54 INFO - 'tests': ['damp', 'tps']}, 10:59:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['dromaeo_dom']}, 10:59:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:59:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['basic_compositor_video']}, 10:59:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:59:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['a11yr', 10:59:54 INFO - 'ts_paint', 10:59:54 INFO - 'tpaint', 10:59:54 INFO - 'sessionrestore', 10:59:54 INFO - 'sessionrestore_no_auto_restore', 10:59:54 INFO - 'tabpaint']}, 10:59:54 INFO - 'other-e10s': {'tests': ['a11yr', 10:59:54 INFO - 'ts_paint', 10:59:54 INFO - 'tpaint', 10:59:54 INFO - 'sessionrestore', 10:59:54 INFO - 'sessionrestore_no_auto_restore', 10:59:54 INFO - 'tabpaint']}, 10:59:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:59:54 INFO - 'ts_paint', 10:59:54 INFO - 'tpaint', 10:59:54 INFO - 'sessionrestore', 10:59:54 INFO - 'sessionrestore_no_auto_restore', 10:59:54 INFO - 'tabpaint']}, 10:59:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:59:54 INFO - 'ts_paint', 10:59:54 INFO - 'tpaint', 10:59:54 INFO - 'sessionrestore', 10:59:54 INFO - 'sessionrestore_no_auto_restore', 10:59:54 INFO - 'tabpaint']}, 10:59:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['a11yr', 10:59:54 INFO - 'ts_paint', 10:59:54 INFO - 'tpaint', 10:59:54 INFO - 'sessionrestore', 10:59:54 INFO - 'sessionrestore_no_auto_restore', 10:59:54 INFO - 'tabpaint']}, 10:59:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['a11yr', 10:59:54 INFO - 'ts_paint', 10:59:54 INFO - 'tpaint', 10:59:54 INFO - 'sessionrestore', 10:59:54 INFO - 'sessionrestore_no_auto_restore', 10:59:54 INFO - 'tabpaint']}, 10:59:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['tsvgx', 10:59:54 INFO - 'tsvgr_opacity', 10:59:54 INFO - 'tart', 10:59:54 INFO - 'tscrollx', 10:59:54 INFO - 'cart']}, 10:59:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:59:54 INFO - 'tsvgr_opacity', 10:59:54 INFO - 'tart', 10:59:54 INFO - 'tscrollx', 10:59:54 INFO - 'cart']}, 10:59:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:59:54 INFO - 'talos_options': ['--disable-e10s'], 10:59:54 INFO - 'tests': ['tp5o']}, 10:59:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:59:54 INFO - 'tests': ['tp5o']}, 10:59:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:59:54 INFO - 'talos_options': ['--disable-e10s', 10:59:54 INFO - '--xperf_path', 10:59:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:59:54 INFO - 'tests': ['tp5n']}, 10:59:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:59:54 INFO - 'talos_options': ['--xperf_path', 10:59:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:59:54 INFO - 'tests': ['tp5n']}}, 10:59:54 INFO - 'talos.zip': {'path': '', 10:59:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:59:54 INFO - Running post-action listener: _resource_record_post_action 10:59:54 INFO - [mozharness: 2016-11-14 18:59:54.199060Z] Finished populate-webroot step (success) 10:59:54 INFO - [mozharness: 2016-11-14 18:59:54.199192Z] Running create-virtualenv step. 10:59:54 INFO - Running pre-action listener: _resource_record_pre_action 10:59:54 INFO - Running main action method: create_virtualenv 10:59:54 INFO - Creating virtualenv /builds/slave/test/build/venv 10:59:54 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 10:59:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:59:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:59:54 INFO - Using real prefix '/tools/python27' 10:59:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:59:55 INFO - Installing distribute.............................................................................................................................................................................................done. 10:59:57 INFO - Installing pip.................done. 10:59:57 INFO - Return code: 0 10:59:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:59:58 INFO - Reading from file tmpfile_stdout 10:59:58 INFO - Output received: 10:59:58 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) 10:59:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:59:58 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')]} 10:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:59:58 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')]} 10:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:59:58 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 10:59:58 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 10:59:58 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 10:59:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 10:59:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:59:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:59:58 INFO - 'HOME': '/Users/cltbld', 10:59:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:59:58 INFO - 'LOGNAME': 'cltbld', 10:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:59:58 INFO - 'MOZ_NO_REMOTE': '1', 10:59:58 INFO - 'NO_EM_RESTART': '1', 10:59:58 INFO - 'PAGER': '/bin/cat', 10:59:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:59:58 INFO - 'PWD': '/builds/slave/test', 10:59:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:59:58 INFO - 'SHELL': '/bin/bash', 10:59:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 10:59:58 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 10:59:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:59:58 INFO - 'USER': 'cltbld', 10:59:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:59:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:59:58 INFO - 'XPC_FLAGS': '0x0', 10:59:58 INFO - 'XPC_SERVICE_NAME': '0', 10:59:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:59:58 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 10:59:58 INFO - Cleaning up... 10:59:58 INFO - Return code: 0 10:59:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:59:58 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')]} 10:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:59:58 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')]} 10:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:59:58 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 10:59:58 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 10:59:58 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 10:59:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 10:59:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:59:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:59:58 INFO - 'HOME': '/Users/cltbld', 10:59:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:59:58 INFO - 'LOGNAME': 'cltbld', 10:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:59:58 INFO - 'MOZ_NO_REMOTE': '1', 10:59:58 INFO - 'NO_EM_RESTART': '1', 10:59:58 INFO - 'PAGER': '/bin/cat', 10:59:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:59:58 INFO - 'PWD': '/builds/slave/test', 10:59:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:59:58 INFO - 'SHELL': '/bin/bash', 10:59:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 10:59:58 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 10:59:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:59:58 INFO - 'USER': 'cltbld', 10:59:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:59:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:59:58 INFO - 'XPC_FLAGS': '0x0', 10:59:58 INFO - 'XPC_SERVICE_NAME': '0', 10:59:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:59:58 INFO - Downloading/unpacking psutil>=3.1.1 10:59:58 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 10:59:58 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 10:59:58 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 10:59:58 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 10:59:58 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 10:59:58 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 11:00:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:00:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:00:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:00:02 INFO - Installing collected packages: psutil 11:00:02 INFO - Running setup.py install for psutil 11:00:02 INFO - building 'psutil._psutil_osx' extension 11:00:02 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 11:00:02 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 11:00:02 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 11:00:02 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 11:00:02 INFO - building 'psutil._psutil_posix' extension 11:00:02 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 11:00:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:00:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:00:02 INFO - ^ 11:00:03 INFO - 1 warning generated. 11:00:03 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 11:00:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:00:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:00:03 INFO - Successfully installed psutil 11:00:03 INFO - Cleaning up... 11:00:03 INFO - Return code: 0 11:00:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:00:03 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')]} 11:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:03 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')]} 11:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:03 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 11:00:03 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 11:00:03 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 11:00:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:03 INFO - 'HOME': '/Users/cltbld', 11:00:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:03 INFO - 'LOGNAME': 'cltbld', 11:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:03 INFO - 'MOZ_NO_REMOTE': '1', 11:00:03 INFO - 'NO_EM_RESTART': '1', 11:00:03 INFO - 'PAGER': '/bin/cat', 11:00:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:03 INFO - 'PWD': '/builds/slave/test', 11:00:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:03 INFO - 'SHELL': '/bin/bash', 11:00:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:03 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:03 INFO - 'USER': 'cltbld', 11:00:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:03 INFO - 'XPC_FLAGS': '0x0', 11:00:03 INFO - 'XPC_SERVICE_NAME': '0', 11:00:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:00:03 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 11:00:03 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 11:00:03 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 11:00:03 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 11:00:03 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 11:00:03 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 11:00:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:00:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:00:06 INFO - Installing collected packages: mozsystemmonitor 11:00:06 INFO - Running setup.py install for mozsystemmonitor 11:00:06 INFO - Successfully installed mozsystemmonitor 11:00:06 INFO - Cleaning up... 11:00:06 INFO - Return code: 0 11:00:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:06 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 11:00:06 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 11:00:06 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 11:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:06 INFO - 'HOME': '/Users/cltbld', 11:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:06 INFO - 'LOGNAME': 'cltbld', 11:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:06 INFO - 'MOZ_NO_REMOTE': '1', 11:00:06 INFO - 'NO_EM_RESTART': '1', 11:00:06 INFO - 'PAGER': '/bin/cat', 11:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:06 INFO - 'PWD': '/builds/slave/test', 11:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:06 INFO - 'SHELL': '/bin/bash', 11:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:06 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:06 INFO - 'USER': 'cltbld', 11:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:06 INFO - 'XPC_FLAGS': '0x0', 11:00:06 INFO - 'XPC_SERVICE_NAME': '0', 11:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:06 INFO - Downloading/unpacking jsonschema==2.5.1 11:00:06 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 11:00:06 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 11:00:06 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 11:00:06 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 11:00:06 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 11:00:06 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 11:00:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:00:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:00:09 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 11:00:09 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 11:00:09 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 11:00:09 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 11:00:09 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 11:00:09 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 11:00:09 INFO - Downloading functools32-3.2.3-2.tar.gz 11:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:00:09 INFO - warning: no files found matching '*.txt' 11:00:09 INFO - no previously-included directories found matching 'build' 11:00:09 INFO - no previously-included directories found matching 'dist' 11:00:09 INFO - no previously-included directories found matching '.git*' 11:00:09 INFO - Installing collected packages: jsonschema, functools32 11:00:09 INFO - Running setup.py install for functools32 11:00:09 INFO - warning: no files found matching '*.txt' 11:00:09 INFO - no previously-included directories found matching 'build' 11:00:09 INFO - no previously-included directories found matching 'dist' 11:00:09 INFO - no previously-included directories found matching '.git*' 11:00:09 INFO - Successfully installed jsonschema functools32 11:00:09 INFO - Cleaning up... 11:00:10 INFO - Return code: 0 11:00:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:00:10 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')]} 11:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:10 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')]} 11:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:10 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 11:00:10 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 11:00:10 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 11:00:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:10 INFO - 'HOME': '/Users/cltbld', 11:00:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:10 INFO - 'LOGNAME': 'cltbld', 11:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:10 INFO - 'MOZ_NO_REMOTE': '1', 11:00:10 INFO - 'NO_EM_RESTART': '1', 11:00:10 INFO - 'PAGER': '/bin/cat', 11:00:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:10 INFO - 'PWD': '/builds/slave/test', 11:00:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:10 INFO - 'SHELL': '/bin/bash', 11:00:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:10 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:10 INFO - 'USER': 'cltbld', 11:00:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:10 INFO - 'XPC_FLAGS': '0x0', 11:00:10 INFO - 'XPC_SERVICE_NAME': '0', 11:00:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:00:10 INFO - Cleaning up... 11:00:10 INFO - Return code: 0 11:00:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:00:10 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')]} 11:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:10 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')]} 11:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:10 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 11:00:10 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 11:00:10 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 11:00:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:10 INFO - 'HOME': '/Users/cltbld', 11:00:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:10 INFO - 'LOGNAME': 'cltbld', 11:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:10 INFO - 'MOZ_NO_REMOTE': '1', 11:00:10 INFO - 'NO_EM_RESTART': '1', 11:00:10 INFO - 'PAGER': '/bin/cat', 11:00:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:10 INFO - 'PWD': '/builds/slave/test', 11:00:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:10 INFO - 'SHELL': '/bin/bash', 11:00:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:10 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:10 INFO - 'USER': 'cltbld', 11:00:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:10 INFO - 'XPC_FLAGS': '0x0', 11:00:10 INFO - 'XPC_SERVICE_NAME': '0', 11:00:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:10 INFO - Downloading/unpacking blobuploader==1.2.4 11:00:10 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 11:00:10 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 11:00:10 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 11:00:10 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 11:00:10 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 11:00:10 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 11:00:13 INFO - Downloading blobuploader-1.2.4.tar.gz 11:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:00:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:00:13 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 11:00:13 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 11:00:13 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 11:00:13 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 11:00:13 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 11:00:13 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 11:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:00:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:00:13 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 11:00:13 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 11:00:13 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 11:00:13 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 11:00:13 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 11:00:13 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 11:00:14 INFO - Downloading docopt-0.6.1.tar.gz 11:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:00:14 INFO - Installing collected packages: blobuploader, requests, docopt 11:00:14 INFO - Running setup.py install for blobuploader 11:00:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:00:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:00:14 INFO - Running setup.py install for requests 11:00:15 INFO - Running setup.py install for docopt 11:00:15 INFO - Successfully installed blobuploader requests docopt 11:00:15 INFO - Cleaning up... 11:00:15 INFO - Return code: 0 11:00:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:00: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')]} 11:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00: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')]} 11:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:15 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 11:00:15 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 11:00:15 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 11:00:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:15 INFO - 'HOME': '/Users/cltbld', 11:00:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:15 INFO - 'LOGNAME': 'cltbld', 11:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:15 INFO - 'MOZ_NO_REMOTE': '1', 11:00:15 INFO - 'NO_EM_RESTART': '1', 11:00:15 INFO - 'PAGER': '/bin/cat', 11:00:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:15 INFO - 'PWD': '/builds/slave/test', 11:00:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:15 INFO - 'SHELL': '/bin/bash', 11:00:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:15 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:15 INFO - 'USER': 'cltbld', 11:00:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:15 INFO - 'XPC_FLAGS': '0x0', 11:00:15 INFO - 'XPC_SERVICE_NAME': '0', 11:00:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:00:15 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-B1ghet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:00:15 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-aPfKLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:00:15 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-nS4Nsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:00:15 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ZBudWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:00:15 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-r65tRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Rb0oNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-pWZNhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-fkeH9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-W8CKZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-YNCFQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-8hxgrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-b0fbD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-a0ue2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:00:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-mG4uEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:00:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-fZ2kGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:00:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-DviFz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:00:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-HuXEKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:00:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:00:17 INFO - Running setup.py install for manifestparser 11:00:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:00:17 INFO - Running setup.py install for mozcrash 11:00:17 INFO - Running setup.py install for mozdebug 11:00:17 INFO - Running setup.py install for mozdevice 11:00:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:00:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:00:17 INFO - Running setup.py install for mozfile 11:00:18 INFO - Running setup.py install for mozhttpd 11:00:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:00:18 INFO - Running setup.py install for mozinfo 11:00:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:00:18 INFO - Running setup.py install for mozInstall 11:00:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:00:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:00:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:00:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:00:18 INFO - Running setup.py install for mozleak 11:00:18 INFO - Running setup.py install for mozlog 11:00:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:00:18 INFO - Running setup.py install for moznetwork 11:00:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:00:18 INFO - Running setup.py install for mozprocess 11:00:19 INFO - Running setup.py install for mozprofile 11:00:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:00:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:00:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:00:19 INFO - Running setup.py install for mozrunner 11:00:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:00:19 INFO - Running setup.py install for mozscreenshot 11:00:19 INFO - Running setup.py install for moztest 11:00:19 INFO - Running setup.py install for mozversion 11:00:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:00:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:00:19 INFO - Cleaning up... 11:00:19 INFO - Return code: 0 11:00:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:00: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')]} 11:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00: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')]} 11:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:19 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 11:00:19 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 11:00:19 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 11:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:19 INFO - 'HOME': '/Users/cltbld', 11:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:19 INFO - 'LOGNAME': 'cltbld', 11:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:19 INFO - 'MOZ_NO_REMOTE': '1', 11:00:19 INFO - 'NO_EM_RESTART': '1', 11:00:19 INFO - 'PAGER': '/bin/cat', 11:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:19 INFO - 'PWD': '/builds/slave/test', 11:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:19 INFO - 'SHELL': '/bin/bash', 11:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:19 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:19 INFO - 'USER': 'cltbld', 11:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:19 INFO - 'XPC_FLAGS': '0x0', 11:00:19 INFO - 'XPC_SERVICE_NAME': '0', 11:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:00:20 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-m1mSfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:00:20 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)) 11:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:00:20 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-MH9Nsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:00:20 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)) 11:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:00:20 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-rpPbsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:00:20 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)) 11:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:00:20 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-xVL9BJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:00:20 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)) 11:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:00:20 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-lpMmYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:00:20 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)) 11:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:00:20 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-0QFXbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:00:20 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)) 11:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:00:20 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ZDMz8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:00:20 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)) 11:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:00:20 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Wlmcpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Pr9sA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-YjTYUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Y1PGJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-iYxVYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ZEQ2Ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-3CQmtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-dSOzQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-RfY94u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:00:21 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)) 11:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:00:21 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-svgBzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:00:22 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)) 11:00:22 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)) 11:00:22 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)) 11:00:22 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)) 11:00:22 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)) 11:00:22 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)) 11:00:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:00:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:00:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:00:24 INFO - Downloading blessings-1.6.tar.gz 11:00:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:00:24 INFO - Installing collected packages: blessings 11:00:24 INFO - Running setup.py install for blessings 11:00:24 INFO - Successfully installed blessings 11:00:24 INFO - Cleaning up... 11:00:24 INFO - Return code: 0 11:00:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:00:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:00:25 INFO - Reading from file tmpfile_stdout 11:00:25 INFO - Current package versions: 11:00:25 INFO - blessings == 1.6 11:00:25 INFO - blobuploader == 1.2.4 11:00:25 INFO - docopt == 0.6.1 11:00:25 INFO - functools32 == 3.2.3-2 11:00:25 INFO - jsonschema == 2.5.1 11:00:25 INFO - manifestparser == 1.1 11:00:25 INFO - mozInstall == 1.12 11:00:25 INFO - mozcrash == 1.0 11:00:25 INFO - mozdebug == 0.1 11:00:25 INFO - mozdevice == 0.48 11:00:25 INFO - mozfile == 1.2 11:00:25 INFO - mozhttpd == 0.7 11:00:25 INFO - mozinfo == 0.9 11:00:25 INFO - mozleak == 0.1 11:00:25 INFO - mozlog == 3.4 11:00:25 INFO - moznetwork == 0.27 11:00:25 INFO - mozprocess == 0.23 11:00:25 INFO - mozprofile == 0.28 11:00:25 INFO - mozrunner == 6.13 11:00:25 INFO - mozscreenshot == 0.1 11:00:25 INFO - mozsystemmonitor == 0.3 11:00:25 INFO - moztest == 0.7 11:00:25 INFO - mozversion == 1.4 11:00:25 INFO - psutil == 3.1.1 11:00:25 INFO - requests == 1.2.3 11:00:25 INFO - wsgiref == 0.1.2 11:00:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:26 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 11:00:26 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 11:00:26 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 11:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:26 INFO - 'HOME': '/Users/cltbld', 11:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:26 INFO - 'LOGNAME': 'cltbld', 11:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:26 INFO - 'MOZ_NO_REMOTE': '1', 11:00:26 INFO - 'NO_EM_RESTART': '1', 11:00:26 INFO - 'PAGER': '/bin/cat', 11:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:26 INFO - 'PWD': '/builds/slave/test', 11:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:26 INFO - 'SHELL': '/bin/bash', 11:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:26 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:26 INFO - 'USER': 'cltbld', 11:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:26 INFO - 'XPC_FLAGS': '0x0', 11:00:26 INFO - 'XPC_SERVICE_NAME': '0', 11:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 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)) 11:00:26 INFO - Cleaning up... 11:00:26 INFO - Return code: 0 11:00:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:00:26 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')]} 11:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:26 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')]} 11:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:26 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 0x107ac6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba094b6b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, '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.YU6q0FtUkb/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.oed07GvU7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 11:00:26 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 11:00:26 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 11:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:26 INFO - 'HOME': '/Users/cltbld', 11:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:26 INFO - 'LOGNAME': 'cltbld', 11:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:26 INFO - 'MOZ_NO_REMOTE': '1', 11:00:26 INFO - 'NO_EM_RESTART': '1', 11:00:26 INFO - 'PAGER': '/bin/cat', 11:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:26 INFO - 'PWD': '/builds/slave/test', 11:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:26 INFO - 'SHELL': '/bin/bash', 11:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:26 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:26 INFO - 'USER': 'cltbld', 11:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:26 INFO - 'XPC_FLAGS': '0x0', 11:00:26 INFO - 'XPC_SERVICE_NAME': '0', 11:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:00:27 INFO - Cleaning up... 11:00:27 INFO - Return code: 0 11:00:27 INFO - Running post-action listener: _resource_record_post_action 11:00:27 INFO - Running post-action listener: _start_resource_monitoring 11:00:27 INFO - Starting resource monitoring. 11:00:27 INFO - [mozharness: 2016-11-14 19:00:27.272264Z] Finished create-virtualenv step (success) 11:00:27 INFO - [mozharness: 2016-11-14 19:00:27.273226Z] Running install step. 11:00:27 INFO - Running pre-action listener: _resource_record_pre_action 11:00:27 INFO - Running main action method: install 11:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:00:27 INFO - Reading from file tmpfile_stdout 11:00:27 INFO - Detecting whether we're running mozinstall >=1.0... 11:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:00:27 INFO - Reading from file tmpfile_stdout 11:00:27 INFO - Output received: 11:00:27 INFO - Usage: mozinstall [options] installer 11:00:27 INFO - Options: 11:00:27 INFO - -h, --help show this help message and exit 11:00:27 INFO - -d DEST, --destination=DEST 11:00:27 INFO - Directory to install application into. [default: 11:00:27 INFO - "/builds/slave/test"] 11:00:27 INFO - --app=APP Application being installed. [default: firefox] 11:00:27 INFO - mkdir: /builds/slave/test/build/application 11:00:27 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'] 11:00:27 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 11:00:59 INFO - Reading from file tmpfile_stdout 11:00:59 INFO - Output received: 11:00:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:00:59 INFO - Running post-action listener: _resource_record_post_action 11:00:59 INFO - [mozharness: 2016-11-14 19:00:59.428198Z] Finished install step (success) 11:00:59 INFO - [mozharness: 2016-11-14 19:00:59.428345Z] Running run-tests step. 11:00:59 INFO - Running pre-action listener: _resource_record_pre_action 11:00:59 INFO - Running main action method: run_tests 11:00:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:00:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:00:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:00:59 INFO - 2016-11-14 11:00:59.442 screenresolution[1651:7520] starting screenresolution argv=screenresolution get 11:00:59 INFO - 2016-11-14 11:00:59.445 screenresolution[1651:7520] Display 0: 1600x1200x32@60 11:00:59 INFO - 2016-11-14 11:00:59.451 screenresolution[1652:7523] starting screenresolution argv=screenresolution list 11:00:59 INFO - Available Modes on Display 0 11:00:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:00:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:00:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:00:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:00:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:00:59 INFO - Intel Iris: 11:00:59 INFO - Chipset Model: Intel Iris 11:00:59 INFO - Type: GPU 11:00:59 INFO - Bus: Built-In 11:00:59 INFO - VRAM (Dynamic, Max): 1536 MB 11:00:59 INFO - Vendor: Intel (0x8086) 11:00:59 INFO - Device ID: 0x0a2e 11:00:59 INFO - Revision ID: 0x0009 11:00:59 INFO - Displays: 11:00:59 INFO - Display: 11:00:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:00:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:00:59 INFO - Display Serial Number: 1600x1200 60 11:00:59 INFO - Main Display: Yes 11:00:59 INFO - Mirror: Off 11:00:59 INFO - Online: Yes 11:00:59 INFO - Rotation: Supported 11:00:59 INFO - Return code: 0 11:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:00:59 INFO - Python 2.7.3 11:00:59 INFO - Return code: 0 11:00:59 INFO - grabbing minidump binary from tooltool 11:00: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')]} 11:00:59 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 0x1078dad30>, '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 0x107740db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:00:59 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 11:00:59 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 11:00:59 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 11:00:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:00:59 INFO - Return code: 0 11:00:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:00:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:00:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:00:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:00:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:00:59 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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 11:00:59 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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0257 --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 11:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU6q0FtUkb/Render', 11:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:59 INFO - 'HOME': '/Users/cltbld', 11:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:59 INFO - 'LOGNAME': 'cltbld', 11:00:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:00:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:59 INFO - 'MOZ_NO_REMOTE': '1', 11:00:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:00:59 INFO - 'NO_EM_RESTART': '1', 11:00:59 INFO - 'PAGER': '/bin/cat', 11:00:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:59 INFO - 'PWD': '/builds/slave/test', 11:00:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:59 INFO - 'SHELL': '/bin/bash', 11:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oed07GvU7R/Listeners', 11:00:59 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 11:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:59 INFO - 'USER': 'cltbld', 11:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:59 INFO - 'XPC_FLAGS': '0x0', 11:00:59 INFO - 'XPC_SERVICE_NAME': '0', 11:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:59 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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 11:01:00 INFO - mozversion application_buildid: 20161114081542 11:01:00 INFO - mozversion application_changeset: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d 11:01:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:01:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:01:00 INFO - mozversion application_name: Firefox 11:01:00 INFO - mozversion application_remotingname: firefox-dev 11:01:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:01:00 INFO - mozversion application_vendor: Mozilla 11:01:00 INFO - mozversion application_version: 52.0a2 11:01:00 INFO - mozversion platform_buildid: 20161114081542 11:01:00 INFO - mozversion platform_changeset: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d 11:01:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:01:00 INFO - mozversion platform_version: 52.0a2 11:01:00 INFO - using testdate: 1479150060 11:01:00 INFO - actual date: 1479150060 11:01:00 INFO - starting webserver on 'localhost:49267' 11:01:00 INFO - SUITE-START | Running 2 tests 11:01:00 INFO - TEST-START | dromaeo_css 11:01:00 INFO - operating with platform_type : mac_ 11:01:00 INFO - Initialising browser for dromaeo_css test... 11:01:00 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmp7gEdbR/profile http://localhost:49267/getInfo.html) 11:01:01 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 11:01:02 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 11:01:02 INFO - PROCESS | 1662 | colorDepth:24 11:01:02 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 11:01:02 INFO - PROCESS | 1662 | __metrics 11:01:02 INFO - PROCESS | 1662 | 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" 11:01:02 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:01:03 INFO - TEST-INFO | 1662: exit 0 11:01:03 INFO - Browser initialized. 11:01:03 INFO - Running cycle 1/1 for dromaeo_css test... 11:01:03 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmp7gEdbR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:01:15 INFO - PROCESS | 1664 | RSS: Main: 217161728 11:01:15 INFO - PROCESS | 1664 | 11:02:06 INFO - PROCESS | 1664 | 1479150126318 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] 11:04:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 11:04:22 INFO - PROCESS | 1664 | RSS: Main: 365232128 11:04:22 INFO - PROCESS | 1664 | 11:07:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 11:07:26 INFO - PROCESS | 1664 | RSS: Main: 357740544 11:07:26 INFO - PROCESS | 1664 | 11:10:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 11:10:29 INFO - PROCESS | 1664 | RSS: Main: 319561728 11:10:29 INFO - PROCESS | 1664 | 11:13:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 11:13:32 INFO - PROCESS | 1664 | RSS: Main: 317276160 11:13:32 INFO - PROCESS | 1664 | 11:16:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 11:16:36 INFO - PROCESS | 1664 | RSS: Main: 313241600 11:16:36 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 11:19:39 INFO - PROCESS | 1664 | RSS: Main: 428171264 11:19:39 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | __start_tp_report 11:19:39 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 11:19:39 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 11:19:39 INFO - PROCESS | 1664 | |i|pagename|runs| 11:19:39 INFO - PROCESS | 1664 | |0;dojo.html;3321.817300048497;3329.833508324584;3333.649871801921;3337.115664348948;3383.3064221834525;5848.532117430606;5906.940930590693;5908.881822363561;5938.792142275021;5952.43451872072;5798.6230894991795;5909.409059094089;5936.079907614316;5936.910946335797;5958.225430694015;5258.842234732954;5289.709066001368;5359.7320133993335;5364.544013758835;5376.327959005124;5257.605679574028;5258;5270.130428479296;5393.81121660742;5413;5332.733363331838;5363.463653634644;5366.168243922179;5370.167624018283;5371.462853714617;5355.3305836770405;5355.357357117171;5360.892782144355;5364.249005139283;5365.570754339644;5172.250023746573;5281.603879708999;5285.947140528622;5292.841214763564;5308.70802105884;5940.79207227745;5942.346341902387;5956.970215148896;5967.8209653710455;5971.313298970631;6106.187656479728;6112.655464441461;6116.1836049259855;6122.438812974205;6133.325334213233;6139.737467782881;6149.538784591174;6166.229221347332;6181.598196117283;6265.185525881606;5871.618344807616;5954.46409823118;5955.352259732503;5979.43195396441;5999.100134979744;6175.289841668266;6383.62599931175;6390.265119511223;6400.9359906400805;6406.199225096863;5816.47651711348;5867.735951882176;6127.84680382994;6169.84575385619;6182.845428864224;6218.9378106218355;6231.376862313823;6240.906386404207;6252.343503932113;6253.218347706536;6232.220972378453;6238.906416403757;6240.095186197976;6241.96879015602;6252.781152659635;6163.537734669918;6218.502519798405;6222.62664240138;6238.500919926395;6254.312025677172;4980.028894365636;4980.05378977993;4981.178105612606;4990.426100998432;5028.74856257193;4442.111577684476;4454.242778727624;4464.531224221475;4465.843695470643;4471.1728330259;4983.626228032912;5728;5789.797357092481;5812.273465816773;5895.675737834375;6170.598911070766;6220.844478888064;6225.750969961241;6233.2208473940755;6242.1261023456755;5920.901471484378;5975.641461512323;5993.340732519419;6011;6017.976943919544;5372.623916325898;5412.7293635318865;5438.374586922555;5483.75323110461;5487.6158668893595;6301.4643755279485;6317.526185536011;6367.3314301997625;6442.677866106582;6464.870702586016;6203.100550420254;6212;6212.689365531795;6231.875362492634;6233.719482623384;6210.627362358273;6374.681265936777;6432.163818703539;6444.387783160592;6455.4512866406885;6117.066276512034;6168.383161683796;6180.103884936749;6207.937920620917;6226.844328891814;6212.502999759918;6221.564490485623;6223.222097237845;6227.532935029982;6247.281562620356;5982.670953097621;6170.10533472653;6171.84570385744;6230.221222347207;6234.314225435289;6076.90884636722;6110.892920173631;6130.122981477994;6132.233470816148;6192.783252586318;6112.442689633789;6124.157728958232;6134.687268874758;6135.32453530862;6140.9030504681905;6020;6087.330393656783;6103.298120716175;6205.193324867739;6215.96892015537;6041.214642096499;6109.816705498843;6123.938760612336;6127.708789264132;6141.117811340085 11:19:39 INFO - PROCESS | 1664 | |1;ext.html;490.17650347416327;505.30520534265384;514.3570536828964;515.4124298299939;526.5471694342865;25815.87092064542;30948.84525577372;37576.68481603141;38446.038849028715;38512.03719907004;39838.800805996005;40228.79885600575;40250;40332.59667403325;40375.39436908442;9171.128742769442;9323.860142097874;9339.906600933971;9343.813123737522;9357.064293570656;9283.721488355326;9379.859302110473;9398.295127865404;9399.24806015517;9403.200727938134;9365.34442589019;9386.10831970965;9393.248540116774;9400.62397504099;9483.193928516084;9327.6735314264;9365.531723413802;9384.39001464906;9389.953050234773;9390.5304734763;9252.537373131316;9316.114969077928;9350.953245233799;9359.29805264608;9369.953150234272;38232.80883595592;38676.41985370193;38716.61283387186;38755.41866871999;38820.11798820124;3353.564036675241;3420.521127042216;3450.430678937972;3490.162361033359;3642.653947874948;27183;28196.577051344248;28973.275668108254;30315.545266821016;30510.694893051008;3106.332138590214;3169.5879535660447;3222.758293128025;3324.8005119692652;3330.2673411849355;468596;469071;473055;473851.63074184407;476938;32600.347993040006;55098.44901551013;55540.444595554334;55552;55565.44434555683;32752.836235819144;34164;34241.315173696385;34263.314733705185;34277.828610856785;13839.169649821042;13960.511382101578;13974.510892118727;13984.86015139856;14018.929905350407;11808.527658893549;11890.643280701594;11890.940545297219;11892.108091893142;11902.761944761056;7501.28737769911;7605.809854753676;7609.239076092346;7689.346405555479;7711.730089446843;2830.235836324181;3172.127664892127;3219.4687876500584;3229.128135403428;3276.5904261967253;910.4628269321132;928.1321963963716;935.8081593273363;955.961761529531;1018.3787889387468;27718.168454946383;28653.713462865522;28858.391015539422;29072.273193169916;29267.560986585217;1034.4827586206898;1042.421456091861;1093.2456604942565;1103.862017247844;1173.3429279603279;14331.426742930165;14949.009120482177;15025.849741502445;15049.766159011977;15210.46763363277;170024;171514;171676;596474;598885;55148.17277740837;55856.72071639697;55919;55928;55994;1331.494032267732;1353.8849197818295;1360.0139898573455;1364.126958746403;1366.3168415792104;27971.16086517408;28227.57658635163;28618.141455756362;29000.854995724887;29109.272268192617;46397;46673.766631166625;46757;46827.53172468232;46855.53144468648;542781;544850;547014.5298546964;547196;548380;24486.510269794147;25596;25607.74392256128;25625.871870640527;25633.359166021;43870;44218;44262.336064959694;44442.77778611086;44892.87767805831;33449;36334.97419729068;36483;38304;38546.0363490915;34161.48757768586;37083.814580926926;37127;37469.625303746616;37985.81007094947;27736.445271094868;35836;36208.63791362053;36493.45259821052;36845.26309473742;27876;31316;33135.171620709676;33202;33232.33535329328;24340.262347143063;24561;24621.876890615433;24831.62752558677;24865.62701559442 11:19:39 INFO - PROCESS | 1664 | |2;jquery.html;326.3147390479992;355.63902638821617;375.36937944253646;377.4131225943658;407.22423782693977;14376.640583985405;14673.413063477461;15455.536333909986;19237.94191319477;19878.60242795143;18648;19141.425757227305;19375.031248437557;19811.603767924633;20048.49878753032;5533.446655334465;5726.341470730857;5732.426757324276;5796.623219490741;5880.176775251468;5085.3389059422225;5730.3123625165035;6525.967370163143;6607.966960165192;6670.866582668344;6178.443940045395;6569;6588.538802283842;6597.175353080865;6598.241202261754;6349.111124442558;6384.170057892491;6401.8399540011405;6418.03729440583;6445.806625801233;5595.020871347498;5607.018771714975;6967.79096627102;7319.194888562254;7401.555906645564;27322.5901611476;28480.857595712096;28694.426111477864;28746.281342966384;28772.136835894955;2333.626619740833;2367.1596583213054;2416.359664688859;2506.938759210073;2620.3056190109637;15458.5362439127;15720.764188537181;16621.916890415472;16637.33450661972;17120.31518739249;1637.71340015498;1840.1719226348064;2034.7253120828648;2062.0100106294367;2070.554830711389;96467;128776.35611821974;132033;139837.90243146362;148198.25900870428;16967.83032169687;21225;21471.248506302516;21642.891785541287;21757.23849665286;35142.824285878916;35206;35839.10402239913;36083.91748247557;36099.27801444009;10436.90412506691;10478.109360704273;10553.788924221473;10766;10973.341599504056;7553.660085296175;8412.705555305534;8587.613557389932;8794.560271986375;8991.100889910957;7286.744963926237;9758.414495130315;9781.853272200922;9797.755056123511;9876.456794876207;3073.5082386818003;3661.2494438640015;3685.631436856293;3696;3708.7589306694977;825.4510750351069;838.6268110690806;916.8303863785221;925.6251218256617;939.8449255872705;19820.306289279804;20701.68947465789;20878.37364879056;20934.895325523274;21219.257325993825;902.6750369866974;938.0431959401373;971.0573182500963;1027.1166796554958;1034.1416624201895;6269.905951410732;10740.355578665305;11013.559457621606;11023.61417350389;11067.169962252863;518185;539342;594738;599545;615260;75191.62404188054;129475;137464;139038;149563;956.6460409648336;999.415342024909;1013.4679293370892;1017.5980487707462;1020.4591566469783;17624.471265862045;17783.555411114306;18312.26750929948;18330.633387332444;18679.813201867808;53044;53200;54141.45858541364;57297.56756080964;57412;77717.61141194258;81235.18764812277;81900.59049704713;83010.58494707488;83726.58136709277;7757.961210194138;7964.2433968772875;8054.677812887418;8122.1065682776025;8147.714829980922;11619.535218591162;12056.216345937486;12266.448009839758;12271.754564908473;12303.138780285652;10489.895101048893;10623;10646.946765266124;10749.946250269011;10856.45717714127;10573.154147668014;10635.361878287327;10749.086327662237;10793.29843560166;10795.946020269848;9600.567974441026;10229.335093218917;10756.838647420138;10905.618303359344;11061;7719.266669666373;7769.223077692412;8024.438289319692;8179.918200817916;8184.263416292562;9442.48066356357;9479.526023698925;10464.890486599023;10489.370637761758;10894.182936279652 11:19:39 INFO - PROCESS | 1664 | |3;mootools.html;1250.806125050617;1301.0567338679452;1470.1399681186506;1549.3957356630915;1797.3799039331427;7502.137254215767;7822.569758663271;8196.30331421829;8469.491830490175;8493.405461617675;8280.089190189074;8311.21043500868;8375.120612335708;8401.41190116692;8406.453580517262;2326.3718795925092;2357.1985524921524;2357.929262122139;2361.622140457527;2363.9763602364014;2326.523062772136;2360.7875291223786;2380.7262164851004;2393.85636861788;2402.2192787344184;2033.9491512712152;2321.210788331967;2354.3407845803204;2363.657269695893;2369.30105618842;2272.9204477843277;2303.470201853583;2342.156823543523;2344.2967109866972;2347.3427440316736;2346.718393792739;2375.418022584478;2376.263358358914;2376.988115059431;2382.7617238276207;8221.876671849926;8268;8287.12985136558;8294;8301.211384918422;5092.643514953955;5239.502247286502;5244.711540865251;5253.264542963988;5262.0791361511965;3333.4166520858753;3431.8627254909775;3519.1202199450136;3524.8942531724088;3532.205253817896;1058.687687132298;1073.5544748929185;1074.7259448840573;1082.8667524975785;1107.385401102388;10763.408012559306;10806.945965270203;10929.234953553254;10960;10972.341659500455;4566.8173273068705;4573.794179261941;4732.929006064912;4745.691530050604;4756.334113224151;9378.953105234566;9411.964683884768;9435.528223588793;9448.811023779623;9454.480003599869;5174.223866420067;5188.870278242998;5246.449122842123;5247.501487358695;5248.291480650139;3952.1700442906736;4299.054208074219;4404.163208990281;4463.642908567307;4803.991161856049;3789.2800367930004;3802.7718336899875;3875.903102422462;3916.4908561886773;3921.470601468822;1245.5080243303844;1257.8679238679806;1258.8363512743467;1275.2284867655121;1279.820825084489;608.863005823695;769.7756554733863;850.6172222499777;887.5917078144126;937.4609599480326;5190.636655434083;5680.630759000652;5843.2695913010875;5868.207791948118;5881.029630111069;899.1413200393608;912.4388501071926;920.5351297594806;929.772205809581;933.3466573398648;3621.040424287566;3647.379945409287;3693;3699.9260014800093;3872.5352957645264;9618.422894626345;9844.556994935245;10039.34744241622;10158.847617285746;10183.643572475075;10103.54534045955;10104.09063184317;10106.747331316776;10110.848337274947;10112.59549618007;7219.458540609476;7663.812109122983;7729.07251129868;7739.806504837424;7744.225577442211;5281.234221038005;5342.358916929993;5351.812686555952;5524.1989911463415;5618.157276408408;8853.645854165761;9137.543122843965;9146.085391460801;9174.724758256996;9241.584128714358;8906.421082629608;9194.770130746785;9278.350515463853;9389.29580281468;9442.763930901509;6557.245916719447;6786.355296246848;6792.320767923168;6803.625800581016;6817.761378351734;7562.773116806505;7787.143414224544;7886.566238856918;7904.249096335839;7917.960410197912;7093.971374150616;7151.713931442892;7174.856502870018;7176.497645164997;7250.456215783943;6909.723611055501;6936.167659880846;6973.825654358681;7054.118235220598;7086.007958885657;6612.900806488003;6721.428678562377;6742.797716068526;6750.088738020305;6755.3244675534015;4645.163870503342;4718.834840780556;4724.858254252378;4826.444958829779;4901.681390709592;4137;4594.379758732662;4630.536946305342;4767.141914455431;4785.042991401664 11:19:39 INFO - PROCESS | 1664 | |4;prototype.html;301.874721990374;306.30774449743586;307.60780005492995;315.30632608261817;317.54590934962994;4330.812939465573;4807.759612019398;5190.71451070191;5196.844094677161;5304.9496539489755;4434.979954610441;4533.682642215046;4868.172410690182;4989.550940415362;5101.617378696594;3537.8938631840956;3781.621837816224;3941.8029098545103;3951;3962.7027972902006;3320.186554294189;3912.9804350978206;3996.3605823068315;4014.297497937849;4034.031832360242;3595.6044835068124;3640.9635903640888;3661.971830985921;3941.1376850686343;4017.758934463927;3643.505820383987;3799.5440547134244;4009.939850902239;4031.1333063391367;4034.6973976951845;3453.9827300863585;3458.4812278158224;3490.778227620338;3501.984424516887;3532.7880327180196;4361.476622805252;4414.653530673143;4811.591014763714;4997.85006449807;5056;4441.646383478653;4525.796339164745;4565.269556870885;4566.931496027562;4589.495155532889;5411.161270003156;5468.808591699271;5507.807226747044;5538.120177507673;5556;3849.538055433338;3861.4400911867624;3868.574456809749;3887.086534664352;3887.3760211856884;4305.6986010978935;4923.556879880828;5741.282339707494;5751.798687045975;5756.035641644557;3105.6117985251844;3753.12343828086;3770.490983717218;3773.2076263984322;3778.7165962552917;3795.905102372463;3806.5241844769403;3808.124131449782;3809.3524100902914;3836.5779764225913;3778.471014058034;3853.9421908671393;4453.0648563801315;4460.598546130864;4492.663050271178;5134.689389774617;5391.3799913009725;5470.617056806065;5473.17902314656;5480.081741554728;1295.4494339905502;1304.2500562176785;1311.8354714720788;1315.6251716955803;1318.813068238593;793.3534169651715;834.9707760228484;849.2356878809121;866.3026263857655;896.6727144592177;4850.9494572032045;4902.926456103161;4909.901801963941;4998.225275082368;5037.6323128129725;5586.162075688598;5588.524975377139;5611.594898005128;5624.353199382042;5630.944705182579;4562.266338791238;5126.487351264918;5154.484551544815;5159.458256883048;5169.612279079084;2439.8654625599183;2448.2579510947853;2628.8948442062488;2654.389490417215;2814.9718502815117;5084.13569693153;5093.1087059764395;5506.531944784658;5534.706849424586;5623.915641265385;4944.826931057396;5379.892402152013;5390.622656414013;5497.202905578669;5502.248875562219;4732;5429.538489228381;5454.754536045967;5490.995786537965;5603.579731520153;4765.523447655207;5069.746512674278;5185.611079169002;5303.337082864642;5356.384015838073;4522;4565.269556870885;4822.807087716452;4973.502649734997;5335.413104558573;5432.049391356576;5454.90881838621;5457.443035065765;5525.309336332958;5595.664260144242;3263.755218358585;3530.91172720684;3532.682058614737;3561.7506774525536;3605.152789094613;4683.601893838925;5297.801412413805;5315.335583052119;5347.304850369427;5689.459501347365;5484.725763711719;5498.477644623755;5526.613137080366;5536.972315138399;5591.10542313228;5040.042391945519;5381.76219469532;5451.454854514517;5513.035218836768;5613.691246981455;4717.57541821224;4967.428745694291;5413.621046526705;5480.1231802912935;5515.117581186991;4478.126765280869;5311.565877213208;5497.615166938276;5621.5221706155435;5639.013172694844;4521.773911304356;5253.290805741176;5519.061759500814;5531.585131115102;5564.221009058654 11:19:39 INFO - PROCESS | 1664 | |5;yui.html;670.2292363781651;684.5276759036265;716.6094478509212;768.5427170833352;772.1930582541244;13571.660708482294;15167.393304267829;15806.525804225863;16001.359945602191;16396.09821459819;14076.366563504642;14370.353334099964;14656.633584160374;14681.77977330341;14841.925790371033;717.4726575966687;814.4054839966827;836.017679226909;837.0415873824468;843.3000609494136;656.4289068510402;675.5946432140726;676.9864602707943;797.8643630582786;829.2205326992607;866.9479831210116;871.8648609465542;873.3319010956624;876.6055275126187;877.2850127146355;880.0847118996236;886.5522910929939;886.6763631274571;887.4187407248295;888.5512816027929;514.8970205958823;529.214117036201;636.7134789344767;661.1669296686161;666.0043235363163;13809.171449712949;14281.642958926006;14297.213653249068;14327.212003339811;14333.78499322511;13308.667283317898;13410.48838952051;13583.52457663977;13598.11612245201;13630.182189068688;12069.57756478519;12141.757164856741;12247.326397048158;12441;12548.309842958724;4390.495093064306;4414.955850441486;4442.844500442501;4486.416765820455;4563.406757121553;90119.09880901173;93072.53463732706;99516.50241748817;99711;100526.49736751342;10338.689839304832;10674.89325106739;10769.56921723138;10793.67618971432;10866.565337386573;20113.39659810208;20374;20496.897515512326;20658.79341206599;20713.58572828535;7559.735409260649;7587.734429294948;7604;7642.8089297768;7747.728829491053;4166.33338665812;4166.479190101238;4204.62158405745;4224.936625950613;4281.37920001598;3305.93388132236;3400.6769356911054;3401.8639254429545;3401.914952126167;3406.471996840494;603.1706403694919;604.2778879239355;615.4737699267063;618.4897459595761;656.3305428462941;528.8228443471403;547.5482726750447;617.7127635649445;621.1490258346095;641.4964253061324;7192.7122915083855;7613.961930190314;8423.284020858173;8623.137686231452;8629.69796057135;577.7140315543833;603.6559161278117;612.8406614280319;617.4936552027384;630.0391902348955;3712.832922518493;4159.10579225468;4170.874873753793;4254.680898932531;4309.698321117492;72261;103513.48243258735;119367.80632193567;121833;125794;104884;105383;106190.46904765582;110016.4499177499;110893;90157.64763528421;92825.60761588582;94597.58103628451;96876.51561742288;97094;7253.455990800606;7999.480098781213;8168.632411541376;8281.792955176168;8494.83010339802;41773.58226417697;50699.239511406624;51613.70965725887;60420.69789651024;61108.083378748466;75297;78473;78814.60592697;79663;83369.58315208385;11959.461824217757;12091.736569694709;12157.148999569945;12441.93779031099;12574.30841303737;13682.45270189221;13971.650708732363;14242.643933901736;14279.21464319431;14344.56966291013;12298.262104273772;12719.74560508766;12865.228086314852;13125.671858203621;13257.734845303234;10705.732356691145;10721.785564288826;12511.4369853355;12807.423665934868;12953.481860725466;12067.215630983957;12835.550755723505;12858.485660573473;13413.731725365244;13424.530141445002;10633.096186824207;10660.25378223517;10678.466076695979;10921.071708904832;11210.55157793679;10965.177611678997;11183.608573699881;11359.375234362189;11459.656210313704;11484.253523520945 11:19:39 INFO - PROCESS | 1664 | __end_tp_report 11:19:39 INFO - PROCESS | 1664 | __start_cc_report 11:19:39 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1493 11:19:39 INFO - PROCESS | 1664 | __end_cc_report 11:19:39 INFO - PROCESS | 1664 | __startTimestamp1479151179425__endTimestamp 11:19:39 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | ------- Summary: start ------- 11:19:39 INFO - PROCESS | 1664 | Number of tests: 6 11:19:39 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5813.99 Median:6103.30 stddev:632.85 (10.4%) stddev-sans-first:603.84 11:19:39 INFO - PROCESS | 1664 | Values: 3321.8 3329.8 3333.6 3337.1 3383.3 5848.5 5906.9 5908.9 5938.8 5952.4 5798.6 5909.4 5936.1 5936.9 5958.2 5258.8 5289.7 5359.7 5364.5 5376.3 5257.6 5258.0 5270.1 5393.8 5413.0 5332.7 5363.5 5366.2 5370.2 5371.5 5355.3 5355.4 5360.9 5364.2 5365.6 5172.3 5281.6 5285.9 5292.8 5308.7 5940.8 5942.3 5957.0 5967.8 5971.3 6106.2 6112.7 6116.2 6122.4 6133.3 6139.7 6149.5 6166.2 6181.6 6265.2 5871.6 5954.5 5955.4 5979.4 5999.1 6175.3 6383.6 6390.3 6400.9 6406.2 5816.5 5867.7 6127.8 6169.8 6182.8 6218.9 6231.4 6240.9 6252.3 6253.2 6232.2 6238.9 6240.1 6242.0 6252.8 6163.5 6218.5 6222.6 6238.5 6254.3 4980.0 4980.1 4981.2 4990.4 5028.7 4442.1 4454.2 4464.5 4465.8 4471.2 4983.6 5728.0 5789.8 5812.3 5895.7 6170.6 6220.8 6225.8 6233.2 6242.1 5920.9 5975.6 5993.3 6011.0 6018.0 5372.6 5412.7 5438.4 5483.8 5487.6 6301.5 6317.5 6367.3 6442.7 6464.9 6203.1 6212.0 6212.7 6231.9 6233.7 6210.6 6374.7 6432.2 6444.4 6455.5 6117.1 6168.4 6180.1 6207.9 6226.8 6212.5 6221.6 6223.2 6227.5 6247.3 5982.7 6170.1 6171.8 6230.2 6234.3 6076.9 6110.9 6130.1 6132.2 6192.8 6112.4 6124.2 6134.7 6135.3 6140.9 6020.0 6087.3 6103.3 6205.2 6216.0 6041.2 6109.8 6123.9 6127.7 6141.1 11:19:39 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:58050.82 Median:36964.54 stddev:127887.98 (346.0%) stddev-sans-first:128173.72 11:19:39 INFO - PROCESS | 1664 | Values: 490.2 505.3 514.4 515.4 526.5 25815.9 30948.8 37576.7 38446.0 38512.0 39838.8 40228.8 40250.0 40332.6 40375.4 9171.1 9323.9 9339.9 9343.8 9357.1 9283.7 9379.9 9398.3 9399.2 9403.2 9365.3 9386.1 9393.2 9400.6 9483.2 9327.7 9365.5 9384.4 9390.0 9390.5 9252.5 9316.1 9351.0 9359.3 9370.0 38232.8 38676.4 38716.6 38755.4 38820.1 3353.6 3420.5 3450.4 3490.2 3642.7 27183.0 28196.6 28973.3 30315.5 30510.7 3106.3 3169.6 3222.8 3324.8 3330.3 468596.0 469071.0 473055.0 473851.6 476938.0 32600.3 55098.4 55540.4 55552.0 55565.4 32752.8 34164.0 34241.3 34263.3 34277.8 13839.2 13960.5 13974.5 13984.9 14018.9 11808.5 11890.6 11890.9 11892.1 11902.8 7501.3 7605.8 7609.2 7689.3 7711.7 2830.2 3172.1 3219.5 3229.1 3276.6 910.5 928.1 935.8 956.0 1018.4 27718.2 28653.7 28858.4 29072.3 29267.6 1034.5 1042.4 1093.2 1103.9 1173.3 14331.4 14949.0 15025.8 15049.8 15210.5 170024.0 171514.0 171676.0 596474.0 598885.0 55148.2 55856.7 55919.0 55928.0 55994.0 1331.5 1353.9 1360.0 1364.1 1366.3 27971.2 28227.6 28618.1 29000.9 29109.3 46397.0 46673.8 46757.0 46827.5 46855.5 542781.0 544850.0 547014.5 547196.0 548380.0 24486.5 25596.0 25607.7 25625.9 25633.4 43870.0 44218.0 44262.3 44442.8 44892.9 33449.0 36335.0 36483.0 38304.0 38546.0 34161.5 37083.8 37127.0 37469.6 37985.8 27736.4 35836.0 36208.6 36493.5 36845.3 27876.0 31316.0 33135.2 33202.0 33232.3 24340.3 24561.0 24621.9 24831.6 24865.6 11:19:39 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:35895.43 Median:17734.57 stddev:96387.55 (543.5%) stddev-sans-first:96620.94 11:19:39 INFO - PROCESS | 1664 | Values: 326.3 355.6 375.4 377.4 407.2 14376.6 14673.4 15455.5 19237.9 19878.6 18648.0 19141.4 19375.0 19811.6 20048.5 5533.4 5726.3 5732.4 5796.6 5880.2 5085.3 5730.3 6526.0 6608.0 6670.9 6178.4 6569.0 6588.5 6597.2 6598.2 6349.1 6384.2 6401.8 6418.0 6445.8 5595.0 5607.0 6967.8 7319.2 7401.6 27322.6 28480.9 28694.4 28746.3 28772.1 2333.6 2367.2 2416.4 2506.9 2620.3 15458.5 15720.8 16621.9 16637.3 17120.3 1637.7 1840.2 2034.7 2062.0 2070.6 96467.0 128776.4 132033.0 139837.9 148198.3 16967.8 21225.0 21471.2 21642.9 21757.2 35142.8 35206.0 35839.1 36083.9 36099.3 10436.9 10478.1 10553.8 10766.0 10973.3 7553.7 8412.7 8587.6 8794.6 8991.1 7286.7 9758.4 9781.9 9797.8 9876.5 3073.5 3661.2 3685.6 3696.0 3708.8 825.5 838.6 916.8 925.6 939.8 19820.3 20701.7 20878.4 20934.9 21219.3 902.7 938.0 971.1 1027.1 1034.1 6269.9 10740.4 11013.6 11023.6 11067.2 518185.0 539342.0 594738.0 599545.0 615260.0 75191.6 129475.0 137464.0 139038.0 149563.0 956.6 999.4 1013.5 1017.6 1020.5 17624.5 17783.6 18312.3 18330.6 18679.8 53044.0 53200.0 54141.5 57297.6 57412.0 77717.6 81235.2 81900.6 83010.6 83726.6 7758.0 7964.2 8054.7 8122.1 8147.7 11619.5 12056.2 12266.4 12271.8 12303.1 10489.9 10623.0 10646.9 10749.9 10856.5 10573.2 10635.4 10749.1 10793.3 10795.9 9600.6 10229.3 10756.8 10905.6 11061.0 7719.3 7769.2 8024.4 8179.9 8184.3 9442.5 9479.5 10464.9 10489.4 10894.2 11:19:39 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5410.59 Median:5189.75 stddev:2959.77 (57.0%) stddev-sans-first:2951.56 11:19:39 INFO - PROCESS | 1664 | Values: 1250.8 1301.1 1470.1 1549.4 1797.4 7502.1 7822.6 8196.3 8469.5 8493.4 8280.1 8311.2 8375.1 8401.4 8406.5 2326.4 2357.2 2357.9 2361.6 2364.0 2326.5 2360.8 2380.7 2393.9 2402.2 2033.9 2321.2 2354.3 2363.7 2369.3 2272.9 2303.5 2342.2 2344.3 2347.3 2346.7 2375.4 2376.3 2377.0 2382.8 8221.9 8268.0 8287.1 8294.0 8301.2 5092.6 5239.5 5244.7 5253.3 5262.1 3333.4 3431.9 3519.1 3524.9 3532.2 1058.7 1073.6 1074.7 1082.9 1107.4 10763.4 10806.9 10929.2 10960.0 10972.3 4566.8 4573.8 4732.9 4745.7 4756.3 9379.0 9412.0 9435.5 9448.8 9454.5 5174.2 5188.9 5246.4 5247.5 5248.3 3952.2 4299.1 4404.2 4463.6 4804.0 3789.3 3802.8 3875.9 3916.5 3921.5 1245.5 1257.9 1258.8 1275.2 1279.8 608.9 769.8 850.6 887.6 937.5 5190.6 5680.6 5843.3 5868.2 5881.0 899.1 912.4 920.5 929.8 933.3 3621.0 3647.4 3693.0 3699.9 3872.5 9618.4 9844.6 10039.3 10158.8 10183.6 10103.5 10104.1 10106.7 10110.8 10112.6 7219.5 7663.8 7729.1 7739.8 7744.2 5281.2 5342.4 5351.8 5524.2 5618.2 8853.6 9137.5 9146.1 9174.7 9241.6 8906.4 9194.8 9278.4 9389.3 9442.8 6557.2 6786.4 6792.3 6803.6 6817.8 7562.8 7787.1 7886.6 7904.2 7918.0 7094.0 7151.7 7174.9 7176.5 7250.5 6909.7 6936.2 6973.8 7054.1 7086.0 6612.9 6721.4 6742.8 6750.1 6755.3 4645.2 4718.8 4724.9 4826.4 4901.7 4137.0 4594.4 4630.5 4767.1 4785.0 11:19:39 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4368.13 Median:4906.41 stddev:1336.30 (27.2%) stddev-sans-first:1304.73 11:19:39 INFO - PROCESS | 1664 | Values: 301.9 306.3 307.6 315.3 317.5 4330.8 4807.8 5190.7 5196.8 5304.9 4435.0 4533.7 4868.2 4989.6 5101.6 3537.9 3781.6 3941.8 3951.0 3962.7 3320.2 3913.0 3996.4 4014.3 4034.0 3595.6 3641.0 3662.0 3941.1 4017.8 3643.5 3799.5 4009.9 4031.1 4034.7 3454.0 3458.5 3490.8 3502.0 3532.8 4361.5 4414.7 4811.6 4997.9 5056.0 4441.6 4525.8 4565.3 4566.9 4589.5 5411.2 5468.8 5507.8 5538.1 5556.0 3849.5 3861.4 3868.6 3887.1 3887.4 4305.7 4923.6 5741.3 5751.8 5756.0 3105.6 3753.1 3770.5 3773.2 3778.7 3795.9 3806.5 3808.1 3809.4 3836.6 3778.5 3853.9 4453.1 4460.6 4492.7 5134.7 5391.4 5470.6 5473.2 5480.1 1295.4 1304.3 1311.8 1315.6 1318.8 793.4 835.0 849.2 866.3 896.7 4850.9 4902.9 4909.9 4998.2 5037.6 5586.2 5588.5 5611.6 5624.4 5630.9 4562.3 5126.5 5154.5 5159.5 5169.6 2439.9 2448.3 2628.9 2654.4 2815.0 5084.1 5093.1 5506.5 5534.7 5623.9 4944.8 5379.9 5390.6 5497.2 5502.2 4732.0 5429.5 5454.8 5491.0 5603.6 4765.5 5069.7 5185.6 5303.3 5356.4 4522.0 4565.3 4822.8 4973.5 5335.4 5432.0 5454.9 5457.4 5525.3 5595.7 3263.8 3530.9 3532.7 3561.8 3605.2 4683.6 5297.8 5315.3 5347.3 5689.5 5484.7 5498.5 5526.6 5537.0 5591.1 5040.0 5381.8 5451.5 5513.0 5613.7 4717.6 4967.4 5413.6 5480.1 5515.1 4478.1 5311.6 5497.6 5621.5 5639.0 4521.8 5253.3 5519.1 5531.6 5564.2 11:19:39 INFO - PROCESS | 1664 | 11:19:39 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:21466.75 Median:22989.10 stddev:32310.11 (140.5%) stddev-sans-first:32363.02 11:19:39 INFO - PROCESS | 1664 | Values: 670.2 684.5 716.6 768.5 772.2 13571.7 15167.4 15806.5 16001.4 16396.1 14076.4 14370.4 14656.6 14681.8 14841.9 717.5 814.4 836.0 837.0 843.3 656.4 675.6 677.0 797.9 829.2 866.9 871.9 873.3 876.6 877.3 880.1 886.6 886.7 887.4 888.6 514.9 529.2 636.7 661.2 666.0 13809.2 14281.6 14297.2 14327.2 14333.8 13308.7 13410.5 13583.5 13598.1 13630.2 12069.6 12141.8 12247.3 12441.0 12548.3 4390.5 4415.0 4442.8 4486.4 4563.4 90119.1 93072.5 99516.5 99711.0 100526.5 10338.7 10674.9 10769.6 10793.7 10866.6 20113.4 20374.0 20496.9 20658.8 20713.6 7559.7 7587.7 7604.0 7642.8 7747.7 4166.3 4166.5 4204.6 4224.9 4281.4 3305.9 3400.7 3401.9 3401.9 3406.5 603.2 604.3 615.5 618.5 656.3 528.8 547.5 617.7 621.1 641.5 7192.7 7614.0 8423.3 8623.1 8629.7 577.7 603.7 612.8 617.5 630.0 3712.8 4159.1 4170.9 4254.7 4309.7 72261.0 103513.5 119367.8 121833.0 125794.0 104884.0 105383.0 106190.5 110016.4 110893.0 90157.6 92825.6 94597.6 96876.5 97094.0 7253.5 7999.5 8168.6 8281.8 8494.8 41773.6 50699.2 51613.7 60420.7 61108.1 75297.0 78473.0 78814.6 79663.0 83369.6 11959.5 12091.7 12157.1 12441.9 12574.3 13682.5 13971.7 14242.6 14279.2 14344.6 12298.3 12719.7 12865.2 13125.7 13257.7 10705.7 10721.8 12511.4 12807.4 12953.5 12067.2 12835.6 12858.5 13413.7 13424.5 10633.1 10660.3 10678.5 10921.1 11210.6 10965.2 11183.6 11359.4 11459.7 11484.3 11:19:39 INFO - PROCESS | 1664 | -------- Summary: end -------- 11:19:39 INFO - PROCESS | 1664 | 11:19:40 INFO - TEST-INFO | 1664: exit 0 11:19:40 INFO - TEST-OK | dromaeo_css | took 1120052ms 11:19:40 INFO - TEST-START | kraken 11:19:40 INFO - operating with platform_type : mac_ 11:19:40 INFO - Initialising browser for kraken test... 11:19:40 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpeB8tCa/profile http://localhost:49267/getInfo.html) 11:19:41 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 11:19:42 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 11:19:42 INFO - PROCESS | 1672 | colorDepth:24 11:19:42 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 11:19:42 INFO - PROCESS | 1672 | __metrics 11:19:42 INFO - PROCESS | 1672 | 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" 11:19:42 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:19:43 INFO - TEST-INFO | 1672: exit 0 11:19:43 INFO - Browser initialized. 11:19:43 INFO - Running cycle 1/1 for kraken test... 11:19:43 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpeB8tCa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:19:55 INFO - PROCESS | 1673 | RSS: Main: 217202688 11:19:55 INFO - PROCESS | 1673 | 11:19:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 11:19:58 INFO - PROCESS | 1673 | RSS: Main: 267407360 11:19:58 INFO - PROCESS | 1673 | 11:20:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 11:20:02 INFO - PROCESS | 1673 | RSS: Main: 277204992 11:20:02 INFO - PROCESS | 1673 | 11:20:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 11:20:06 INFO - PROCESS | 1673 | RSS: Main: 375525376 11:20:06 INFO - PROCESS | 1673 | 11:20:08 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 11:20:08 INFO - PROCESS | 1673 | RSS: Main: 278310912 11:20:08 INFO - PROCESS | 1673 | 11:20:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:20:11 INFO - PROCESS | 1673 | RSS: Main: 279572480 11:20:11 INFO - PROCESS | 1673 | 11:20:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 11:20:14 INFO - PROCESS | 1673 | RSS: Main: 319168512 11:20:14 INFO - PROCESS | 1673 | 11:20:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 11:20:18 INFO - PROCESS | 1673 | RSS: Main: 320774144 11:20:18 INFO - PROCESS | 1673 | 11:20:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 11:20:21 INFO - PROCESS | 1673 | RSS: Main: 320434176 11:20:21 INFO - PROCESS | 1673 | 11:20:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:20:24 INFO - PROCESS | 1673 | RSS: Main: 296730624 11:20:24 INFO - PROCESS | 1673 | 11:20:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:20:26 INFO - PROCESS | 1673 | RSS: Main: 298594304 11:20:26 INFO - PROCESS | 1673 | 11:20:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:20:29 INFO - PROCESS | 1673 | RSS: Main: 299753472 11:20:29 INFO - PROCESS | 1673 | 11:20:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:20:33 INFO - PROCESS | 1673 | RSS: Main: 317358080 11:20:33 INFO - PROCESS | 1673 | 11:20:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:20:36 INFO - PROCESS | 1673 | RSS: Main: 310341632 11:20:36 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 11:20:38 INFO - PROCESS | 1673 | RSS: Main: 354357248 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | __start_tp_report 11:20:38 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 11:20:38 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 11:20:38 INFO - PROCESS | 1673 | |i|pagename|runs| 11:20:38 INFO - PROCESS | 1673 | |0;ai-astar;117;115;115;114;113;113;114;115;113;113 11:20:38 INFO - PROCESS | 1673 | |1;audio-beat-detection;163;135;113;145;112;134;134;134;136;114 11:20:38 INFO - PROCESS | 1673 | |2;audio-dft;182;186;182;186;183;183;183;182;185;182 11:20:38 INFO - PROCESS | 1673 | |3;audio-fft;81;59;59;88;60;58;49;58;87;57 11:20:38 INFO - PROCESS | 1673 | |4;audio-oscillator;88;70;93;69;68;68;88;67;68;91 11:20:38 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;86;89;87;88;130;134;87;88;84 11:20:38 INFO - PROCESS | 1673 | |6;imaging-darkroom;94;94;95;94;94;106;126;138;149;94 11:20:38 INFO - PROCESS | 1673 | |7;imaging-desaturate;81;82;80;79;80;83;82;83;81;81 11:20:38 INFO - PROCESS | 1673 | |8;json-parse-financial;54;53;54;53;57;56;57;57;56;56 11:20:38 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;66;77;80;74;73;70;72;70;72;70 11:20:38 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;84;83;84;84;83;84;83;78;84;83 11:20:38 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;146;147;154;149;146;146;150;150;192;172 11:20:38 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;167;167;166;165;165;167;164;165;167;166 11:20:38 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;65;66;66;66;68;65;64;66;65 11:20:38 INFO - PROCESS | 1673 | __end_tp_report 11:20:38 INFO - PROCESS | 1673 | __start_cc_report 11:20:38 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2043 11:20:38 INFO - PROCESS | 1673 | __end_cc_report 11:20:38 INFO - PROCESS | 1673 | __startTimestamp1479151238529__endTimestamp 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | ------- Summary: start ------- 11:20:38 INFO - PROCESS | 1673 | Number of tests: 14 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.50 stddev:1.32 (1.1%) stddev-sans-first:0.93 11:20:38 INFO - PROCESS | 1673 | Values: 117.0 115.0 115.0 114.0 113.0 113.0 114.0 115.0 113.0 113.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:134.50 stddev:15.82 (11.8%) stddev-sans-first:12.17 11:20:38 INFO - PROCESS | 1673 | Values: 163.0 135.0 113.0 145.0 112.0 134.0 134.0 134.0 136.0 114.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.00 stddev:1.65 (0.9%) stddev-sans-first:1.67 11:20:38 INFO - PROCESS | 1673 | Values: 182.0 186.0 182.0 186.0 183.0 183.0 183.0 182.0 185.0 182.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:65.60 Median:59.50 stddev:14.06 (23.6%) stddev-sans-first:13.77 11:20:38 INFO - PROCESS | 1673 | Values: 81.0 59.0 59.0 88.0 60.0 58.0 49.0 58.0 87.0 57.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:79.00 stddev:11.30 (14.3%) stddev-sans-first:11.27 11:20:38 INFO - PROCESS | 1673 | Values: 88.0 70.0 93.0 69.0 68.0 68.0 88.0 67.0 68.0 91.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:96.10 Median:87.50 stddev:18.99 (21.7%) stddev-sans-first:19.92 11:20:38 INFO - PROCESS | 1673 | Values: 88.0 86.0 89.0 87.0 88.0 130.0 134.0 87.0 88.0 84.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:108.40 Median:94.00 stddev:21.23 (22.6%) stddev-sans-first:21.87 11:20:38 INFO - PROCESS | 1673 | Values: 94.0 94.0 95.0 94.0 94.0 106.0 126.0 138.0 149.0 94.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:81.20 Median:81.50 stddev:1.32 (1.6%) stddev-sans-first:1.39 11:20:38 INFO - PROCESS | 1673 | Values: 81.0 82.0 80.0 79.0 80.0 83.0 82.0 83.0 81.0 81.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.64 (2.9%) stddev-sans-first:1.67 11:20:38 INFO - PROCESS | 1673 | Values: 54.0 53.0 54.0 53.0 57.0 56.0 57.0 57.0 56.0 56.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.50 stddev:3.95 (5.4%) stddev-sans-first:3.44 11:20:38 INFO - PROCESS | 1673 | Values: 66.0 77.0 80.0 74.0 73.0 70.0 72.0 70.0 72.0 70.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:84.00 stddev:1.83 (2.2%) stddev-sans-first:1.90 11:20:38 INFO - PROCESS | 1673 | Values: 84.0 83.0 84.0 84.0 83.0 84.0 83.0 78.0 84.0 83.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:155.20 Median:150.00 stddev:15.10 (10.1%) stddev-sans-first:15.64 11:20:38 INFO - PROCESS | 1673 | Values: 146.0 147.0 154.0 149.0 146.0 146.0 150.0 150.0 192.0 172.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:166.50 stddev:1.10 (0.7%) stddev-sans-first:1.09 11:20:38 INFO - PROCESS | 1673 | Values: 167.0 167.0 166.0 165.0 165.0 167.0 164.0 165.0 167.0 166.0 11:20:38 INFO - PROCESS | 1673 | 11:20:38 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 11:20:38 INFO - PROCESS | 1673 | Values: 66.0 65.0 66.0 66.0 66.0 68.0 65.0 64.0 66.0 65.0 11:20:38 INFO - PROCESS | 1673 | -------- Summary: end -------- 11:20:38 INFO - PROCESS | 1673 | 11:20:39 INFO - TEST-INFO | 1673: exit 0 11:20:39 INFO - TEST-OK | kraken | took 59356ms 11:20:39 INFO - SUITE-END | took 1179s 11:20:39 INFO - Completed test suite (00:19:39) 11:20:39 INFO - javascript benchmark 11:20:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3321.817300048497, 3329.833508324584, 3333.649871801921, 3337.115664348948, 3383.3064221834525, 5848.532117430606, 5906.940930590693, 5908.881822363561, 5938.792142275021, 5952.43451872072, 5798.6230894991795, 5909.409059094089, 5936.079907614316, 5936.910946335797, 5958.225430694015, 5258.842234732954, 5289.709066001368, 5359.7320133993335, 5364.544013758835, 5376.327959005124, 5257.605679574028, 5258.0, 5270.130428479296, 5393.81121660742, 5413.0, 5332.733363331838, 5363.463653634644, 5366.168243922179, 5370.167624018283, 5371.462853714617, 5355.3305836770405, 5355.357357117171, 5360.892782144355, 5364.249005139283, 5365.570754339644, 5172.250023746573, 5281.603879708999, 5285.947140528622, 5292.841214763564, 5308.70802105884, 5940.79207227745, 5942.346341902387, 5956.970215148896, 5967.8209653710455, 5971.313298970631, 6106.187656479728, 6112.655464441461, 6116.1836049259855, 6122.438812974205, 6133.325334213233, 6139.737467782881, 6149.538784591174, 6166.229221347332, 6181.598196117283, 6265.185525881606, 5871.618344807616, 5954.46409823118, 5955.352259732503, 5979.43195396441, 5999.100134979744, 6175.289841668266, 6383.62599931175, 6390.265119511223, 6400.9359906400805, 6406.199225096863, 5816.47651711348, 5867.735951882176, 6127.84680382994, 6169.84575385619, 6182.845428864224, 6218.9378106218355, 6231.376862313823, 6240.906386404207, 6252.343503932113, 6253.218347706536, 6232.220972378453, 6238.906416403757, 6240.095186197976, 6241.96879015602, 6252.781152659635, 6163.537734669918, 6218.502519798405, 6222.62664240138, 6238.500919926395, 6254.312025677172, 4980.028894365636, 4980.05378977993, 4981.178105612606, 4990.426100998432, 5028.74856257193, 4442.111577684476, 4454.242778727624, 4464.531224221475, 4465.843695470643, 4471.1728330259, 4983.626228032912, 5728.0, 5789.797357092481, 5812.273465816773, 5895.675737834375, 6170.598911070766, 6220.844478888064, 6225.750969961241, 6233.2208473940755, 6242.1261023456755, 5920.901471484378, 5975.641461512323, 5993.340732519419, 6011.0, 6017.976943919544, 5372.623916325898, 5412.7293635318865, 5438.374586922555, 5483.75323110461, 5487.6158668893595, 6301.4643755279485, 6317.526185536011, 6367.3314301997625, 6442.677866106582, 6464.870702586016, 6203.100550420254, 6212.0, 6212.689365531795, 6231.875362492634, 6233.719482623384, 6210.627362358273, 6374.681265936777, 6432.163818703539, 6444.387783160592, 6455.4512866406885, 6117.066276512034, 6168.383161683796, 6180.103884936749, 6207.937920620917, 6226.844328891814, 6212.502999759918, 6221.564490485623, 6223.222097237845, 6227.532935029982, 6247.281562620356, 5982.670953097621, 6170.10533472653, 6171.84570385744, 6230.221222347207, 6234.314225435289, 6076.90884636722, 6110.892920173631, 6130.122981477994, 6132.233470816148, 6192.783252586318, 6112.442689633789, 6124.157728958232, 6134.687268874758, 6135.32453530862, 6140.9030504681905, 6020.0, 6087.330393656783, 6103.298120716175, 6205.193324867739, 6215.96892015537, 6041.214642096499, 6109.816705498843, 6123.938760612336, 6127.708789264132, 6141.117811340085], "value": 5772.769213753894, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.17650347416327, 505.30520534265384, 514.3570536828964, 515.4124298299939, 526.5471694342865, 25815.87092064542, 30948.84525577372, 37576.68481603141, 38446.038849028715, 38512.03719907004, 39838.800805996005, 40228.79885600575, 40250.0, 40332.59667403325, 40375.39436908442, 9171.128742769442, 9323.860142097874, 9339.906600933971, 9343.813123737522, 9357.064293570656, 9283.721488355326, 9379.859302110473, 9398.295127865404, 9399.24806015517, 9403.200727938134, 9365.34442589019, 9386.10831970965, 9393.248540116774, 9400.62397504099, 9483.193928516084, 9327.6735314264, 9365.531723413802, 9384.39001464906, 9389.953050234773, 9390.5304734763, 9252.537373131316, 9316.114969077928, 9350.953245233799, 9359.29805264608, 9369.953150234272, 38232.80883595592, 38676.41985370193, 38716.61283387186, 38755.41866871999, 38820.11798820124, 3353.564036675241, 3420.521127042216, 3450.430678937972, 3490.162361033359, 3642.653947874948, 27183.0, 28196.577051344248, 28973.275668108254, 30315.545266821016, 30510.694893051008, 3106.332138590214, 3169.5879535660447, 3222.758293128025, 3324.8005119692652, 3330.2673411849355, 468596.0, 469071.0, 473055.0, 473851.63074184407, 476938.0, 32600.347993040006, 55098.44901551013, 55540.444595554334, 55552.0, 55565.44434555683, 32752.836235819144, 34164.0, 34241.315173696385, 34263.314733705185, 34277.828610856785, 13839.169649821042, 13960.511382101578, 13974.510892118727, 13984.86015139856, 14018.929905350407, 11808.527658893549, 11890.643280701594, 11890.940545297219, 11892.108091893142, 11902.761944761056, 7501.28737769911, 7605.809854753676, 7609.239076092346, 7689.346405555479, 7711.730089446843, 2830.235836324181, 3172.127664892127, 3219.4687876500584, 3229.128135403428, 3276.5904261967253, 910.4628269321132, 928.1321963963716, 935.8081593273363, 955.961761529531, 1018.3787889387468, 27718.168454946383, 28653.713462865522, 28858.391015539422, 29072.273193169916, 29267.560986585217, 1034.4827586206898, 1042.421456091861, 1093.2456604942565, 1103.862017247844, 1173.3429279603279, 14331.426742930165, 14949.009120482177, 15025.849741502445, 15049.766159011977, 15210.46763363277, 170024.0, 171514.0, 171676.0, 596474.0, 598885.0, 55148.17277740837, 55856.72071639697, 55919.0, 55928.0, 55994.0, 1331.494032267732, 1353.8849197818295, 1360.0139898573455, 1364.126958746403, 1366.3168415792104, 27971.16086517408, 28227.57658635163, 28618.141455756362, 29000.854995724887, 29109.272268192617, 46397.0, 46673.766631166625, 46757.0, 46827.53172468232, 46855.53144468648, 542781.0, 544850.0, 547014.5298546964, 547196.0, 548380.0, 24486.510269794147, 25596.0, 25607.74392256128, 25625.871870640527, 25633.359166021, 43870.0, 44218.0, 44262.336064959694, 44442.77778611086, 44892.87767805831, 33449.0, 36334.97419729068, 36483.0, 38304.0, 38546.0363490915, 34161.48757768586, 37083.814580926926, 37127.0, 37469.625303746616, 37985.81007094947, 27736.445271094868, 35836.0, 36208.63791362053, 36493.45259821052, 36845.26309473742, 27876.0, 31316.0, 33135.171620709676, 33202.0, 33232.33535329328, 24340.262347143063, 24561.0, 24621.876890615433, 24831.62752558677, 24865.62701559442], "value": 17613.097038143227, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.3147390479992, 355.63902638821617, 375.36937944253646, 377.4131225943658, 407.22423782693977, 14376.640583985405, 14673.413063477461, 15455.536333909986, 19237.94191319477, 19878.60242795143, 18648.0, 19141.425757227305, 19375.031248437557, 19811.603767924633, 20048.49878753032, 5533.446655334465, 5726.341470730857, 5732.426757324276, 5796.623219490741, 5880.176775251468, 5085.3389059422225, 5730.3123625165035, 6525.967370163143, 6607.966960165192, 6670.866582668344, 6178.443940045395, 6569.0, 6588.538802283842, 6597.175353080865, 6598.241202261754, 6349.111124442558, 6384.170057892491, 6401.8399540011405, 6418.03729440583, 6445.806625801233, 5595.020871347498, 5607.018771714975, 6967.79096627102, 7319.194888562254, 7401.555906645564, 27322.5901611476, 28480.857595712096, 28694.426111477864, 28746.281342966384, 28772.136835894955, 2333.626619740833, 2367.1596583213054, 2416.359664688859, 2506.938759210073, 2620.3056190109637, 15458.5362439127, 15720.764188537181, 16621.916890415472, 16637.33450661972, 17120.31518739249, 1637.71340015498, 1840.1719226348064, 2034.7253120828648, 2062.0100106294367, 2070.554830711389, 96467.0, 128776.35611821974, 132033.0, 139837.90243146362, 148198.25900870428, 16967.83032169687, 21225.0, 21471.248506302516, 21642.891785541287, 21757.23849665286, 35142.824285878916, 35206.0, 35839.10402239913, 36083.91748247557, 36099.27801444009, 10436.90412506691, 10478.109360704273, 10553.788924221473, 10766.0, 10973.341599504056, 7553.660085296175, 8412.705555305534, 8587.613557389932, 8794.560271986375, 8991.100889910957, 7286.744963926237, 9758.414495130315, 9781.853272200922, 9797.755056123511, 9876.456794876207, 3073.5082386818003, 3661.2494438640015, 3685.631436856293, 3696.0, 3708.7589306694977, 825.4510750351069, 838.6268110690806, 916.8303863785221, 925.6251218256617, 939.8449255872705, 19820.306289279804, 20701.68947465789, 20878.37364879056, 20934.895325523274, 21219.257325993825, 902.6750369866974, 938.0431959401373, 971.0573182500963, 1027.1166796554958, 1034.1416624201895, 6269.905951410732, 10740.355578665305, 11013.559457621606, 11023.61417350389, 11067.169962252863, 518185.0, 539342.0, 594738.0, 599545.0, 615260.0, 75191.62404188054, 129475.0, 137464.0, 139038.0, 149563.0, 956.6460409648336, 999.415342024909, 1013.4679293370892, 1017.5980487707462, 1020.4591566469783, 17624.471265862045, 17783.555411114306, 18312.26750929948, 18330.633387332444, 18679.813201867808, 53044.0, 53200.0, 54141.45858541364, 57297.56756080964, 57412.0, 77717.61141194258, 81235.18764812277, 81900.59049704713, 83010.58494707488, 83726.58136709277, 7757.961210194138, 7964.2433968772875, 8054.677812887418, 8122.1065682776025, 8147.714829980922, 11619.535218591162, 12056.216345937486, 12266.448009839758, 12271.754564908473, 12303.138780285652, 10489.895101048893, 10623.0, 10646.946765266124, 10749.946250269011, 10856.45717714127, 10573.154147668014, 10635.361878287327, 10749.086327662237, 10793.29843560166, 10795.946020269848, 9600.567974441026, 10229.335093218917, 10756.838647420138, 10905.618303359344, 11061.0, 7719.266669666373, 7769.223077692412, 8024.438289319692, 8179.918200817916, 8184.263416292562, 9442.48066356357, 9479.526023698925, 10464.890486599023, 10489.370637761758, 10894.182936279652], "value": 10562.964061619236, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.806125050617, 1301.0567338679452, 1470.1399681186506, 1549.3957356630915, 1797.3799039331427, 7502.137254215767, 7822.569758663271, 8196.30331421829, 8469.491830490175, 8493.405461617675, 8280.089190189074, 8311.21043500868, 8375.120612335708, 8401.41190116692, 8406.453580517262, 2326.3718795925092, 2357.1985524921524, 2357.929262122139, 2361.622140457527, 2363.9763602364014, 2326.523062772136, 2360.7875291223786, 2380.7262164851004, 2393.85636861788, 2402.2192787344184, 2033.9491512712152, 2321.210788331967, 2354.3407845803204, 2363.657269695893, 2369.30105618842, 2272.9204477843277, 2303.470201853583, 2342.156823543523, 2344.2967109866972, 2347.3427440316736, 2346.718393792739, 2375.418022584478, 2376.263358358914, 2376.988115059431, 2382.7617238276207, 8221.876671849926, 8268.0, 8287.12985136558, 8294.0, 8301.211384918422, 5092.643514953955, 5239.502247286502, 5244.711540865251, 5253.264542963988, 5262.0791361511965, 3333.4166520858753, 3431.8627254909775, 3519.1202199450136, 3524.8942531724088, 3532.205253817896, 1058.687687132298, 1073.5544748929185, 1074.7259448840573, 1082.8667524975785, 1107.385401102388, 10763.408012559306, 10806.945965270203, 10929.234953553254, 10960.0, 10972.341659500455, 4566.8173273068705, 4573.794179261941, 4732.929006064912, 4745.691530050604, 4756.334113224151, 9378.953105234566, 9411.964683884768, 9435.528223588793, 9448.811023779623, 9454.480003599869, 5174.223866420067, 5188.870278242998, 5246.449122842123, 5247.501487358695, 5248.291480650139, 3952.1700442906736, 4299.054208074219, 4404.163208990281, 4463.642908567307, 4803.991161856049, 3789.2800367930004, 3802.7718336899875, 3875.903102422462, 3916.4908561886773, 3921.470601468822, 1245.5080243303844, 1257.8679238679806, 1258.8363512743467, 1275.2284867655121, 1279.820825084489, 608.863005823695, 769.7756554733863, 850.6172222499777, 887.5917078144126, 937.4609599480326, 5190.636655434083, 5680.630759000652, 5843.2695913010875, 5868.207791948118, 5881.029630111069, 899.1413200393608, 912.4388501071926, 920.5351297594806, 929.772205809581, 933.3466573398648, 3621.040424287566, 3647.379945409287, 3693.0, 3699.9260014800093, 3872.5352957645264, 9618.422894626345, 9844.556994935245, 10039.34744241622, 10158.847617285746, 10183.643572475075, 10103.54534045955, 10104.09063184317, 10106.747331316776, 10110.848337274947, 10112.59549618007, 7219.458540609476, 7663.812109122983, 7729.07251129868, 7739.806504837424, 7744.225577442211, 5281.234221038005, 5342.358916929993, 5351.812686555952, 5524.1989911463415, 5618.157276408408, 8853.645854165761, 9137.543122843965, 9146.085391460801, 9174.724758256996, 9241.584128714358, 8906.421082629608, 9194.770130746785, 9278.350515463853, 9389.29580281468, 9442.763930901509, 6557.245916719447, 6786.355296246848, 6792.320767923168, 6803.625800581016, 6817.761378351734, 7562.773116806505, 7787.143414224544, 7886.566238856918, 7904.249096335839, 7917.960410197912, 7093.971374150616, 7151.713931442892, 7174.856502870018, 7176.497645164997, 7250.456215783943, 6909.723611055501, 6936.167659880846, 6973.825654358681, 7054.118235220598, 7086.007958885657, 6612.900806488003, 6721.428678562377, 6742.797716068526, 6750.088738020305, 6755.3244675534015, 4645.163870503342, 4718.834840780556, 4724.858254252378, 4826.444958829779, 4901.681390709592, 4137.0, 4594.379758732662, 4630.536946305342, 4767.141914455431, 4785.042991401664], "value": 4386.088740438083, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.874721990374, 306.30774449743586, 307.60780005492995, 315.30632608261817, 317.54590934962994, 4330.812939465573, 4807.759612019398, 5190.71451070191, 5196.844094677161, 5304.9496539489755, 4434.979954610441, 4533.682642215046, 4868.172410690182, 4989.550940415362, 5101.617378696594, 3537.8938631840956, 3781.621837816224, 3941.8029098545103, 3951.0, 3962.7027972902006, 3320.186554294189, 3912.9804350978206, 3996.3605823068315, 4014.297497937849, 4034.031832360242, 3595.6044835068124, 3640.9635903640888, 3661.971830985921, 3941.1376850686343, 4017.758934463927, 3643.505820383987, 3799.5440547134244, 4009.939850902239, 4031.1333063391367, 4034.6973976951845, 3453.9827300863585, 3458.4812278158224, 3490.778227620338, 3501.984424516887, 3532.7880327180196, 4361.476622805252, 4414.653530673143, 4811.591014763714, 4997.85006449807, 5056.0, 4441.646383478653, 4525.796339164745, 4565.269556870885, 4566.931496027562, 4589.495155532889, 5411.161270003156, 5468.808591699271, 5507.807226747044, 5538.120177507673, 5556.0, 3849.538055433338, 3861.4400911867624, 3868.574456809749, 3887.086534664352, 3887.3760211856884, 4305.6986010978935, 4923.556879880828, 5741.282339707494, 5751.798687045975, 5756.035641644557, 3105.6117985251844, 3753.12343828086, 3770.490983717218, 3773.2076263984322, 3778.7165962552917, 3795.905102372463, 3806.5241844769403, 3808.124131449782, 3809.3524100902914, 3836.5779764225913, 3778.471014058034, 3853.9421908671393, 4453.0648563801315, 4460.598546130864, 4492.663050271178, 5134.689389774617, 5391.3799913009725, 5470.617056806065, 5473.17902314656, 5480.081741554728, 1295.4494339905502, 1304.2500562176785, 1311.8354714720788, 1315.6251716955803, 1318.813068238593, 793.3534169651715, 834.9707760228484, 849.2356878809121, 866.3026263857655, 896.6727144592177, 4850.9494572032045, 4902.926456103161, 4909.901801963941, 4998.225275082368, 5037.6323128129725, 5586.162075688598, 5588.524975377139, 5611.594898005128, 5624.353199382042, 5630.944705182579, 4562.266338791238, 5126.487351264918, 5154.484551544815, 5159.458256883048, 5169.612279079084, 2439.8654625599183, 2448.2579510947853, 2628.8948442062488, 2654.389490417215, 2814.9718502815117, 5084.13569693153, 5093.1087059764395, 5506.531944784658, 5534.706849424586, 5623.915641265385, 4944.826931057396, 5379.892402152013, 5390.622656414013, 5497.202905578669, 5502.248875562219, 4732.0, 5429.538489228381, 5454.754536045967, 5490.995786537965, 5603.579731520153, 4765.523447655207, 5069.746512674278, 5185.611079169002, 5303.337082864642, 5356.384015838073, 4522.0, 4565.269556870885, 4822.807087716452, 4973.502649734997, 5335.413104558573, 5432.049391356576, 5454.90881838621, 5457.443035065765, 5525.309336332958, 5595.664260144242, 3263.755218358585, 3530.91172720684, 3532.682058614737, 3561.7506774525536, 3605.152789094613, 4683.601893838925, 5297.801412413805, 5315.335583052119, 5347.304850369427, 5689.459501347365, 5484.725763711719, 5498.477644623755, 5526.613137080366, 5536.972315138399, 5591.10542313228, 5040.042391945519, 5381.76219469532, 5451.454854514517, 5513.035218836768, 5613.691246981455, 4717.57541821224, 4967.428745694291, 5413.621046526705, 5480.1231802912935, 5515.117581186991, 4478.126765280869, 5311.565877213208, 5497.615166938276, 5621.5221706155435, 5639.013172694844, 4521.773911304356, 5253.290805741176, 5519.061759500814, 5531.585131115102, 5564.221009058654], "value": 3945.691882473028, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.2292363781651, 684.5276759036265, 716.6094478509212, 768.5427170833352, 772.1930582541244, 13571.660708482294, 15167.393304267829, 15806.525804225863, 16001.359945602191, 16396.09821459819, 14076.366563504642, 14370.353334099964, 14656.633584160374, 14681.77977330341, 14841.925790371033, 717.4726575966687, 814.4054839966827, 836.017679226909, 837.0415873824468, 843.3000609494136, 656.4289068510402, 675.5946432140726, 676.9864602707943, 797.8643630582786, 829.2205326992607, 866.9479831210116, 871.8648609465542, 873.3319010956624, 876.6055275126187, 877.2850127146355, 880.0847118996236, 886.5522910929939, 886.6763631274571, 887.4187407248295, 888.5512816027929, 514.8970205958823, 529.214117036201, 636.7134789344767, 661.1669296686161, 666.0043235363163, 13809.171449712949, 14281.642958926006, 14297.213653249068, 14327.212003339811, 14333.78499322511, 13308.667283317898, 13410.48838952051, 13583.52457663977, 13598.11612245201, 13630.182189068688, 12069.57756478519, 12141.757164856741, 12247.326397048158, 12441.0, 12548.309842958724, 4390.495093064306, 4414.955850441486, 4442.844500442501, 4486.416765820455, 4563.406757121553, 90119.09880901173, 93072.53463732706, 99516.50241748817, 99711.0, 100526.49736751342, 10338.689839304832, 10674.89325106739, 10769.56921723138, 10793.67618971432, 10866.565337386573, 20113.39659810208, 20374.0, 20496.897515512326, 20658.79341206599, 20713.58572828535, 7559.735409260649, 7587.734429294948, 7604.0, 7642.8089297768, 7747.728829491053, 4166.33338665812, 4166.479190101238, 4204.62158405745, 4224.936625950613, 4281.37920001598, 3305.93388132236, 3400.6769356911054, 3401.8639254429545, 3401.914952126167, 3406.471996840494, 603.1706403694919, 604.2778879239355, 615.4737699267063, 618.4897459595761, 656.3305428462941, 528.8228443471403, 547.5482726750447, 617.7127635649445, 621.1490258346095, 641.4964253061324, 7192.7122915083855, 7613.961930190314, 8423.284020858173, 8623.137686231452, 8629.69796057135, 577.7140315543833, 603.6559161278117, 612.8406614280319, 617.4936552027384, 630.0391902348955, 3712.832922518493, 4159.10579225468, 4170.874873753793, 4254.680898932531, 4309.698321117492, 72261.0, 103513.48243258735, 119367.80632193567, 121833.0, 125794.0, 104884.0, 105383.0, 106190.46904765582, 110016.4499177499, 110893.0, 90157.64763528421, 92825.60761588582, 94597.58103628451, 96876.51561742288, 97094.0, 7253.455990800606, 7999.480098781213, 8168.632411541376, 8281.792955176168, 8494.83010339802, 41773.58226417697, 50699.239511406624, 51613.70965725887, 60420.69789651024, 61108.083378748466, 75297.0, 78473.0, 78814.60592697, 79663.0, 83369.58315208385, 11959.461824217757, 12091.736569694709, 12157.148999569945, 12441.93779031099, 12574.30841303737, 13682.45270189221, 13971.650708732363, 14242.643933901736, 14279.21464319431, 14344.56966291013, 12298.262104273772, 12719.74560508766, 12865.228086314852, 13125.671858203621, 13257.734845303234, 10705.732356691145, 10721.785564288826, 12511.4369853355, 12807.423665934868, 12953.481860725466, 12067.215630983957, 12835.550755723505, 12858.485660573473, 13413.731725365244, 13424.530141445002, 10633.096186824207, 10660.25378223517, 10678.466076695979, 10921.071708904832, 11210.55157793679, 10965.177611678997, 11183.608573699881, 11359.375234362189, 11459.656210313704, 11484.253523520945], "value": 7307.930322012202, "unit": "score"}], "name": "dromaeo_css", "value": 7169.812451305461, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 115.0, 114.0, 113.0, 113.0, 114.0, 115.0, 113.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 135.0, 113.0, 145.0, 112.0, 134.0, 134.0, 134.0, 136.0, 114.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 182.0, 186.0, 183.0, 183.0, 183.0, 182.0, 185.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 88.0, 60.0, 58.0, 49.0, 58.0, 87.0, 57.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 93.0, 69.0, 68.0, 68.0, 88.0, 67.0, 68.0, 91.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 87.0, 88.0, 130.0, 134.0, 87.0, 88.0, 84.0], "value": 96.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 106.0, 126.0, 138.0, 149.0, 94.0], "value": 108.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 79.0, 80.0, 83.0, 82.0, 83.0, 81.0, 81.0], "value": 81.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 53.0, 57.0, 56.0, 57.0, 57.0, 56.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 80.0, 74.0, 73.0, 70.0, 72.0, 70.0, 72.0, 70.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 84.0, 83.0, 84.0, 83.0, 78.0, 84.0, 83.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 154.0, 149.0, 146.0, 146.0, 150.0, 150.0, 192.0, 172.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 166.0, 165.0, 165.0, 167.0, 164.0, 165.0, 167.0, 166.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 66.0, 68.0, 65.0, 64.0, 66.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1455.4, "alertThreshold": 2.0}]} 11:20:39 INFO - Return code: 0 11:20:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:20:39 ERROR - # TBPL SUCCESS # 11:20:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:20:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:20:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:20:39 INFO - 2016-11-14 11:20:39.843 screenresolution[1677:9922] starting screenresolution argv=screenresolution get 11:20:39 INFO - 2016-11-14 11:20:39.847 screenresolution[1677:9922] Display 0: 1600x1200x32@60 11:20:39 INFO - 2016-11-14 11:20:39.852 screenresolution[1678:9925] starting screenresolution argv=screenresolution list 11:20:39 INFO - Available Modes on Display 0 11:20:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:20:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:20:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:20:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:20:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:20:40 INFO - Intel Iris: 11:20:40 INFO - Chipset Model: Intel Iris 11:20:40 INFO - Type: GPU 11:20:40 INFO - Bus: Built-In 11:20:40 INFO - VRAM (Dynamic, Max): 1536 MB 11:20:40 INFO - Vendor: Intel (0x8086) 11:20:40 INFO - Device ID: 0x0a2e 11:20:40 INFO - Revision ID: 0x0009 11:20:40 INFO - Displays: 11:20:40 INFO - Display: 11:20:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:20:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:20:40 INFO - Display Serial Number: 1600x1200 60 11:20:40 INFO - Main Display: Yes 11:20:40 INFO - Mirror: Off 11:20:40 INFO - Online: Yes 11:20:40 INFO - Rotation: Supported 11:20:40 INFO - Return code: 0 11:20:40 INFO - Running post-action listener: _resource_record_post_action 11:20:40 INFO - [mozharness: 2016-11-14 19:20:40.089785Z] Finished run-tests step (success) 11:20:40 INFO - Running post-run listener: _resource_record_post_run 11:20:40 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37417984; Write bytes: 653517824; Read time: 241; Write time: 1889 11:20:40 INFO - TinderboxPrint: CPU usage
53.5% 11:20:40 INFO - TinderboxPrint: I/O read bytes / time
37,417,984 / 241 11:20:40 INFO - TinderboxPrint: I/O write bytes / time
653,517,824 / 1,889 11:20:40 INFO - TinderboxPrint: CPU idle
2,253.0 (46.5%) 11:20:40 INFO - TinderboxPrint: CPU system
972.8 (20.1%) 11:20:40 INFO - TinderboxPrint: CPU user
1,623.9 (33.5%) 11:20:40 INFO - TinderboxPrint: Swap in / out
389,992,448 / 0 11:20:40 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354120192; Write bytes: 347346432; Read time: 27723; Write time: 758 11:20:40 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 34247680; Write bytes: 295894528; Read time: 218; Write time: 1099 11:20:40 INFO - Running post-run listener: _upload_blobber_files 11:20:40 INFO - Blob upload gear active. 11:20:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:20:40 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 11:20:40 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'] 11:20:40 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 11:20:40 INFO - (blobuploader) - INFO - Open directory for files ... 11:20:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:20:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:20:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:20:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:20:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:20:41 INFO - (blobuploader) - INFO - Done attempting. 11:20:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:20:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:20:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:20:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:20:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:20:42 INFO - (blobuploader) - INFO - Done attempting. 11:20:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:20:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:20:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:20:44 INFO - (blobuploader) - INFO - Done attempting. 11:20:44 INFO - (blobuploader) - INFO - Iteration through files over. 11:20:44 INFO - Return code: 0 11:20:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:20:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:20:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1531303f7cd7bc621b4f99ca075343bf30a6b6ec47e3fddcc2fe49d743dc67289be267c0281cb84fa535a32becc1200a8afa13b1dc75199c2917e2a3ac073e05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ebb14352881242c1e9f07d03e7997c1ce92ac57cfe5979bef3d98fbc6eb97b5d164cdccda368456fdcbf11cb18be88ccc4d7dcade470c9568b2fc4c33854881", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac8536c2c54178d8c437af3e1b3413a57a5e4169eededa8a25de41ab8360b45d1791a9f16409d261e5d518fe0cadb7fddafe7cbb7cb926e47fd3368d12b20c22"} 11:20:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:20:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:20:44 INFO - Contents: 11:20:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1531303f7cd7bc621b4f99ca075343bf30a6b6ec47e3fddcc2fe49d743dc67289be267c0281cb84fa535a32becc1200a8afa13b1dc75199c2917e2a3ac073e05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ebb14352881242c1e9f07d03e7997c1ce92ac57cfe5979bef3d98fbc6eb97b5d164cdccda368456fdcbf11cb18be88ccc4d7dcade470c9568b2fc4c33854881", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac8536c2c54178d8c437af3e1b3413a57a5e4169eededa8a25de41ab8360b45d1791a9f16409d261e5d518fe0cadb7fddafe7cbb7cb926e47fd3368d12b20c22"} 11:20:44 INFO - Running post-run listener: copy_logs_to_upload_dir 11:20:44 INFO - Copying logs to upload dir... 11:20:44 INFO - mkdir: /builds/slave/test/build/upload/logs 11:20:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.585096 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 11:20:44.793643) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 11:20:44.801600) ========= 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.YU6q0FtUkb/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.oed07GvU7R/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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/1531303f7cd7bc621b4f99ca075343bf30a6b6ec47e3fddcc2fe49d743dc67289be267c0281cb84fa535a32becc1200a8afa13b1dc75199c2917e2a3ac073e05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ebb14352881242c1e9f07d03e7997c1ce92ac57cfe5979bef3d98fbc6eb97b5d164cdccda368456fdcbf11cb18be88ccc4d7dcade470c9568b2fc4c33854881", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac8536c2c54178d8c437af3e1b3413a57a5e4169eededa8a25de41ab8360b45d1791a9f16409d261e5d518fe0cadb7fddafe7cbb7cb926e47fd3368d12b20c22"} build_url:https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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/1531303f7cd7bc621b4f99ca075343bf30a6b6ec47e3fddcc2fe49d743dc67289be267c0281cb84fa535a32becc1200a8afa13b1dc75199c2917e2a3ac073e05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ebb14352881242c1e9f07d03e7997c1ce92ac57cfe5979bef3d98fbc6eb97b5d164cdccda368456fdcbf11cb18be88ccc4d7dcade470c9568b2fc4c33854881", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac8536c2c54178d8c437af3e1b3413a57a5e4169eededa8a25de41ab8360b45d1791a9f16409d261e5d518fe0cadb7fddafe7cbb7cb926e47fd3368d12b20c22"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 11:20:44.838557) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:20:44.838897) ========= 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.YU6q0FtUkb/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.oed07GvU7R/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.005191 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:20:44.888208) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 11:20:44.888518) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 11:20:44.888852) ========= ========= Total master_lag: 0.19 =========