Received: by 2002:ac0:a5a6:0:0:0:0:0 with SMTP id m35-v6csp4228363imm; Tue, 25 Sep 2018 13:40:03 -0700 (PDT) X-Google-Smtp-Source: ACcGV61VZyUM/GhSQWIimhQuUNRU9X/sfpeftlg/wignBozSuVn2C6Z2B6BSp7NszLgRiN8vN0ic X-Received: by 2002:a63:a112:: with SMTP id b18-v6mr2580116pgf.384.1537908002995; Tue, 25 Sep 2018 13:40:02 -0700 (PDT) ARC-Seal: i=1; a=rsa-sha256; t=1537908002; cv=none; d=google.com; s=arc-20160816; b=SJtglT3URnvHhQWsTeiisEmoybCcR9AZM22FLSNjl7frJuxfz1n2ooJHdmkUk2ttEN rh+B1ympXCi5GT994CsUfZkHe07acZZFTf6GDqIDt7YYiZWY4pyDQXi9lDFatLmyCtKq sw/IhqYyvdIjZA0c8SluuLP1zy5DD8E0GHL81tMiInLIuklobRiNqbuchPcTNy5AS7gs Ony7JSMUO30HhC+tz2s7+RHlIE/+druu7DijmNhmw9k5f6AjC+E/qXlIG1ssNAhOGAsw CCtYJXKG/e6ivsD8Zv6CqCc1MQTqWrIMTXw9t3r4DW107s/gcnYmDzxpNiVO1+EZ0n1Y vf3Q== ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=arc-20160816; h=list-id:precedence:sender:cc:to:subject:message-id:date:from :references:in-reply-to:mime-version:dkim-signature; bh=jdwEDaNGEcKie4P8pUE5aD6mgyefsr8E5wPGeo8cxUw=; b=V2/PRQ/mQitHTwNqbVNzjXXsjLp8Fb+sBkQE6wvaK4kFsnevczty7DUnvKt9uxS43J UtwPmYAQB+2iMEUmOM8cFRmjTqtwgHPYRqaRZUtz5b108KT68TVLznJMl/jzT8f4EOsQ Sf9uA/Nmj+RRPA/JhZhTR9QHrFLR3p5BTS5nhGSHfbliypJO+LboasuxDAONtvECW4+u bWCQKtKX2vYKy0htNnejmRjgW4q9747it3w6d7e1VXjDwf2fBVEaalUA/49Z2clPXtmX jKnFY8cRaW1Dyd8DWvyjXmwq0YlmUzca8jBVN2CH7bzRwxmNrEY+xrQu4HOFARJ3nfnQ BlUA== ARC-Authentication-Results: i=1; mx.google.com; dkim=pass header.i=@google.com header.s=20161025 header.b=jSUsMNGq; spf=pass (google.com: best guess record for domain of linux-kernel-owner@vger.kernel.org designates 209.132.180.67 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org; dmarc=pass (p=REJECT sp=REJECT dis=NONE) header.from=google.com Return-Path: Received: from vger.kernel.org (vger.kernel.org. [209.132.180.67]) by mx.google.com with ESMTP id 137-v6si3055770pfx.155.2018.09.25.13.39.47; Tue, 25 Sep 2018 13:40:02 -0700 (PDT) Received-SPF: pass (google.com: best guess record for domain of linux-kernel-owner@vger.kernel.org designates 209.132.180.67 as permitted sender) client-ip=209.132.180.67; Authentication-Results: mx.google.com; dkim=pass header.i=@google.com header.s=20161025 header.b=jSUsMNGq; spf=pass (google.com: best guess record for domain of linux-kernel-owner@vger.kernel.org designates 209.132.180.67 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org; dmarc=pass (p=REJECT sp=REJECT dis=NONE) header.from=google.com Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1726460AbeIZCsw (ORCPT + 99 others); Tue, 25 Sep 2018 22:48:52 -0400 Received: from mail-qt1-f196.google.com ([209.85.160.196]:41595 "EHLO mail-qt1-f196.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1726173AbeIZCsv (ORCPT ); Tue, 25 Sep 2018 22:48:51 -0400 Received: by mail-qt1-f196.google.com with SMTP id m15-v6so8792180qtp.8 for ; Tue, 25 Sep 2018 13:39:31 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=20161025; h=mime-version:in-reply-to:references:from:date:message-id:subject:to :cc; bh=jdwEDaNGEcKie4P8pUE5aD6mgyefsr8E5wPGeo8cxUw=; b=jSUsMNGqgQI2kKUplsWmD7xwc+f2IqGQUeKZH/8dPJm7gw3dm2YkK7jugmxAR6Z6RV Ib6RPTzI2xSgayRqY2XvV7h3bpm/HCtzGKd+ls+wuo0xNBDDb79jM7YLAHS0HJIBN2wv rTF81x2g2FFYISIjn7tUIff/jbhMh5SucgCzoGIRz26Ho3HAfIDt+iUP6VXpmyv1uUui ovmmi1MRFEZ0PKN5bUhv11fEWpnAK2h6W/CNTJpFUDsjvGJKt453uByQeeu3lRdb6adn chSl3olw4BJk8ajj5QeNIjZJW6LJSBiWGdAoDKMy8ezgtr0C5VjkXt2o+gidJ4b/51y1 qNrA== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:mime-version:in-reply-to:references:from:date :message-id:subject:to:cc; bh=jdwEDaNGEcKie4P8pUE5aD6mgyefsr8E5wPGeo8cxUw=; b=P5LpaAut1RdrofBpKqKL5oalTv+Ndph/9vwkZoB4tH6qLlysN93RPfNzArpsGxbhTO jtLDNb+/0ICusOue282wNo73r8snDhSn/uXj6bR8JBprcM6Sc8zBPx0O/Zgy337m4Htf Yl1qhC/Nrv4BGOPNcXvSm71n7Xu3sVh3+0zJFOyMVjR4wOTZ+Ctbrz2wR6jADjb62hUU fhGANbyHW2EKyRBK3E4UtnLJxaEJpH36dkZxT1iPfet+ptB4fNkTpGl4qBKRQro8dYlc fguaeuECUHFvFLLgJl1ZJ2ycUTEh4CoUTLQvtqKbtCWy6K22/jDS4F2S5K6IIYxlpvjq Rh/g== X-Gm-Message-State: ABuFfog4IM8otyZkofn2mjsRP25LKpZW2ZZTv6kjj/6P1e1v8xCekl++ nEknbZ2psxrm8OD4REUd2dTNs42p/VQfKpFt7LdGoA== X-Received: by 2002:ac8:33da:: with SMTP id d26-v6mr2153868qtb.313.1537907970357; Tue, 25 Sep 2018 13:39:30 -0700 (PDT) MIME-Version: 1.0 Received: by 2002:ac8:2abb:0:0:0:0:0 with HTTP; Tue, 25 Sep 2018 13:39:29 -0700 (PDT) In-Reply-To: References: From: Joel Fernandes Date: Tue, 25 Sep 2018 13:39:29 -0700 Message-ID: Subject: Re: [PATCH 2/6] pstore: Add event tracing support To: Sai Prakash Ranjan Cc: Joel Fernandes , Steven Rostedt , Ingo Molnar , Laura Abbott , Kees Cook , Anton Vorontsov , Rob Herring , devicetree@vger.kernel.org, Colin Cross , Jason Baron , Tony Luck , Arnd Bergmann , Catalin Marinas , Will Deacon , Masami Hiramatsu , Joe Perches , Jim Cromie , Rajendra Nayak , Vivek Gautam , Sibi Sankar , "moderated list:ARM64 PORT (AARCH64 ARCHITECTURE)" , LKML , linux-arm-msm@vger.kernel.org, Greg Kroah-Hartman , Ingo Molnar , Tom Zanussi , Prasad Sodagudi , tsoni@codeaurora.org, Bryan Huntsman , Tingwei Zhang , kernel-team Content-Type: text/plain; charset="UTF-8" Sender: linux-kernel-owner@vger.kernel.org Precedence: bulk List-ID: X-Mailing-List: linux-kernel@vger.kernel.org On Tue, Sep 25, 2018 at 1:37 PM, Joel Fernandes wrote: > On Sun, Sep 23, 2018 at 8:33 AM, Sai Prakash Ranjan > wrote: >> On 9/22/2018 10:07 PM, Sai Prakash Ranjan wrote: >>> >>> On 9/22/2018 2:35 PM, Joel Fernandes wrote: >>>> >>>> On Sat, Sep 8, 2018 at 4:28 PM Sai Prakash Ranjan >>>> wrote: >>>>> >>>>> >>>>> + >>>>> + trace_seq_init(&iter->seq); >>>>> + iter->ent = fbuffer->entry; >>>>> + event_call->event.funcs->trace(iter, 0, event); >>>>> + trace_seq_putc(&iter->seq, 0); >>>> >>>> >>>> Would it be possible to store the binary trace record in the pstore >>>> buffer instead of outputting text? I suspect that will both be faster >>>> and less space. >>>> >>> >>> I will try this and come back. >>> >> >> Hi Joel, >> >> I removed trace_seq_putc and there is some improvement seen: 203 MB/s >> >> # dd if=/dev/zero of=/dev/null status=progress >> 12207371264 bytes (12 GB, 11 GiB) copied, 60 s, 203 MB/s^C >> 24171926+0 records in >> 24171926+0 records out >> 12376026112 bytes (12 GB, 12 GiB) copied, 60.8282 s, 203 MB/s >> >> This seems good when compared to 190 MB/s seen previously. >> If this is Ok, then I will spin v2 with changes suggested. > > Sorry for slow reply, yes that sounds good and a worthwhile perf improvement. > Well so I think you should still not use spinlock to synchronize and split the buffer. You could expand pstore_record to have a ts field or introduce a new API like ->write_percpu instead of write, or something. But I strongly feel you should lock. For ftrace function trace, the perf reduction with locking was dramatic. - Joel