Received: by 2002:a05:6a10:a841:0:0:0:0 with SMTP id d1csp434778pxy; Thu, 22 Apr 2021 05:41:14 -0700 (PDT) X-Google-Smtp-Source: ABdhPJyv+sU7KMB8OF4fsubbv3BFK8jtIYiuNXX5mzdAH2vK93ylJDjDsxsJO3fQQUHqppZKbbM8 X-Received: by 2002:a63:f07:: with SMTP id e7mr3403527pgl.341.1619095274474; Thu, 22 Apr 2021 05:41:14 -0700 (PDT) ARC-Seal: i=1; a=rsa-sha256; t=1619095274; cv=none; d=google.com; s=arc-20160816; b=K2GVBhErkF1rffIX92DPsQYR43n4PTXVx1gmnL6yKS1J0YMzDj3XOZCFB1pzyMFXyS dFSag7qubszuakj8coUWB4oACcSGFAv4oaJNqpMipB7zQq3vKEkz6orLnBG4+UNbiJt7 SXSg/aur6MSEBG8zMEmk6NGz3nUwm4ePN5RsPHeDZPGKmg4FdLAgz1ZDkiDNKDXQO6zl gYKQxGjvoCkIFUAMx524sySY4imzelM0IQ0+ddQADRHrI4cj6WTwAN7G+upjWIFA3vsD 4w1/Th8iwaszvssQynhioUQ/LQF4IgfzGEz5qChbfIj4fa//9BA/DhiuKFS8rZAquN2Z Wfag== ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=arc-20160816; h=list-id:precedence:cc:to:subject:message-id:date:from:in-reply-to :references:mime-version:dkim-signature; bh=A665ErDKUdmsQDlfh0SCMscZakL4a8DB0rDSnVVLJ5g=; b=mqLTygAw7DKn3+2uvsecjSExJFsoHFEUX5G+N2FONeULlWW9ujmOcQl4JJKHSICbJ6 c2R75/IibZrSS2/+P7447nFUdbQN4wUAoQxX2dossgPeVct4Is2AdST0slX0x5jwF0Be JYBw96xebAa3Vrhr+Qv43TmcZxibE+kWqdtwwkF1lc1j8865XQLLxJl+EYyvRokBfX6z ZbnPhUCKREHFbh18qz1HMQWSKVSsMBXrCxQnufXw3HceJvA3iKo/PJxIHZXpGITr1+yD FhZOekcbBRYl0tBNvjrslHx3uFYJlkk4pnq5L2M9YtZg63daCIssKsg1YFJIIfr98xLG r9Cg== ARC-Authentication-Results: i=1; mx.google.com; dkim=pass header.i=@chromium.org header.s=google header.b=cbO5PaTx; spf=pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org; dmarc=pass (p=NONE sp=NONE dis=NONE) header.from=chromium.org Return-Path: Received: from vger.kernel.org (vger.kernel.org. [23.128.96.18]) by mx.google.com with ESMTP id c4si2908541pjq.75.2021.04.22.05.41.02; Thu, 22 Apr 2021 05:41:14 -0700 (PDT) Received-SPF: pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) client-ip=23.128.96.18; Authentication-Results: mx.google.com; dkim=pass header.i=@chromium.org header.s=google header.b=cbO5PaTx; spf=pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org; dmarc=pass (p=NONE sp=NONE dis=NONE) header.from=chromium.org Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S236940AbhDVMj3 (ORCPT + 99 others); Thu, 22 Apr 2021 08:39:29 -0400 Received: from lindbergh.monkeyblade.net ([23.128.96.19]:40080 "EHLO lindbergh.monkeyblade.net" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S236293AbhDVMhn (ORCPT ); Thu, 22 Apr 2021 08:37:43 -0400 Received: from mail-il1-x133.google.com (mail-il1-x133.google.com [IPv6:2607:f8b0:4864:20::133]) by lindbergh.monkeyblade.net (Postfix) with ESMTPS id F2980C06138C for ; Thu, 22 Apr 2021 05:37:07 -0700 (PDT) Received: by mail-il1-x133.google.com with SMTP id j12so23774931ils.4 for ; Thu, 22 Apr 2021 05:37:07 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=chromium.org; s=google; h=mime-version:references:in-reply-to:from:date:message-id:subject:to :cc; bh=A665ErDKUdmsQDlfh0SCMscZakL4a8DB0rDSnVVLJ5g=; b=cbO5PaTxCYtLcvQnj3TKsFgXHTI0X/UzwDrcZiH6jvdvJiF4FedjajAB44bF46M8pJ 6eE+oYtoHZkmwd5EhBEY/eJpHH6TIlz1FsIp4motwyZf24wj4xp3SJzhrypsr5IVtVaa XvkYCzm3IR/LOXTj1UTalPBhojQDkssRcW7ZU= X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:mime-version:references:in-reply-to:from:date :message-id:subject:to:cc; bh=A665ErDKUdmsQDlfh0SCMscZakL4a8DB0rDSnVVLJ5g=; b=PD6hobALTvQdri6K1fAuvt4oKO2OWlYwaCreq2DvF9MKBq9G11l6vlztOZitWW1bMR fGyxjjNDhAxpiE/GH6Ey5T8PIEql1TkY6q03WDCygThPdGlAk7Ayx/vGESeG++L6sHTL Gp0FpTeFhAMNuhaTOE/Zg89dvRa/07t2YR+0Du6YP5Hh8zFztsCy1YBZFXUYtkVMygQJ xE1mMLYndYTe7kl4O3e3IlxdNx0+AdLTZKhThi4nN0L6m4k8+wG5nv4RIUQrD+IXQorC HHn1M8XpjM5G+70zW1s9qYoG4Yt+yCo8qxK5bUGbFaMzhWNeZe1jfqVyp4+6q4LWZS2U SK3g== X-Gm-Message-State: AOAM531GiauugtUAC56mZhGoCZR4KFp4tGpaU4PGCBBC09xuAV14ocy3 BQBMy7izGx6C4KqFzhaNh/sCBtd+N8d7UWzCYwlVX3fzOJA/oA== X-Received: by 2002:a05:6e02:219d:: with SMTP id j29mr2669565ila.204.1619095027316; Thu, 22 Apr 2021 05:37:07 -0700 (PDT) MIME-Version: 1.0 References: <20210421190736.1538217-1-linux@rasmusvillemoes.dk> <236995f6-30ee-8047-624c-08d0a1552dc1@rasmusvillemoes.dk> <7e9d3337-eb7b-a2c8-a5ef-037d6a9765d7@rasmusvillemoes.dk> In-Reply-To: <7e9d3337-eb7b-a2c8-a5ef-037d6a9765d7@rasmusvillemoes.dk> From: Florent Revest Date: Thu, 22 Apr 2021 14:36:56 +0200 Message-ID: Subject: Re: [PATCH] bpf: remove pointless code from bpf_do_trace_printk() To: Rasmus Villemoes Cc: Andrii Nakryiko , Daniel Borkmann , Alan Maguire , Steven Rostedt , bpf , open list , Alexei Starovoitov Content-Type: text/plain; charset="UTF-8" Precedence: bulk List-ID: X-Mailing-List: linux-kernel@vger.kernel.org On Thu, Apr 22, 2021 at 12:09 PM Rasmus Villemoes wrote: > > On 22/04/2021 11.23, Florent Revest wrote: > > On Thu, Apr 22, 2021 at 9:13 AM Rasmus Villemoes > > wrote: > >> > >> On 22/04/2021 05.32, Andrii Nakryiko wrote: > >>> On Wed, Apr 21, 2021 at 6:19 PM Rasmus Villemoes > >>> wrote: > >>>> > >>>> The comment is wrong. snprintf(buf, 16, "") and snprintf(buf, 16, > >>>> "%s", "") etc. will certainly put '\0' in buf[0]. The only case where > >>>> snprintf() does not guarantee a nul-terminated string is when it is > >>>> given a buffer size of 0 (which of course prevents it from writing > >>>> anything at all to the buffer). > >>>> > >>>> Remove it before it gets cargo-culted elsewhere. > >>>> > >>>> Signed-off-by: Rasmus Villemoes > >>>> --- > >>>> kernel/trace/bpf_trace.c | 3 --- > >>>> 1 file changed, 3 deletions(-) > >>>> > >>> > >>> The change looks good to me, but please rebase it on top of the > >>> bpf-next tree. This is not a bug, so it doesn't have to go into the > >>> bpf tree. As it is right now, it doesn't apply cleanly onto bpf-next. > > > > FWIW the idea of the patch also looks good to me :) > > > >> Thanks for the pointer. Looking in next-20210420, it seems to me that > >> > >> commit d9c9e4db186ab4d81f84e6f22b225d333b9424e3 > >> Author: Florent Revest > >> Date: Mon Apr 19 17:52:38 2021 +0200 > >> > >> bpf: Factorize bpf_trace_printk and bpf_seq_printf > >> > >> is buggy. In particular, these two snippets: > >> > >> +#define BPF_CAST_FMT_ARG(arg_nb, args, mod) \ > >> + (mod[arg_nb] == BPF_PRINTF_LONG_LONG || \ > >> + (mod[arg_nb] == BPF_PRINTF_LONG && __BITS_PER_LONG == 64) \ > >> + ? (u64)args[arg_nb] \ > >> + : (u32)args[arg_nb]) > >> > >> > >> + ret = snprintf(buf, sizeof(buf), fmt, BPF_CAST_FMT_ARG(0, args, > >> mod), > >> + BPF_CAST_FMT_ARG(1, args, mod), BPF_CAST_FMT_ARG(2, > >> args, mod)); > >> > >> Regardless of the casts done in that macro, the type of the resulting > >> expression is that resulting from C promotion rules. And (foo ? (u64)bla > >> : (u32)blib) has type u64, which is thus the type the compiler uses when > >> building the vararg list being passed into snprintf(). C simply doesn't > >> allow you to change types at run-time in this way. > >> > >> It probably works fine on x86-64, which passes the first six or so > >> argument in registers, va_start() puts those registers into the va_list > >> opaque structure, and when it comes time to do a va_arg(int), just the > >> lower 32 bits are used. It is broken on i386 and other architectures > >> where arguments are passed on the stack (and for x86-64 as well had > >> there been a few more arguments) and va_arg(ap, int) is essentially ({ > >> int res = *(int *)ap; ap += 4; res; }) [or maybe it's -= 4 because stack > >> direction etc., that's not really relevant here]. > >> > >> Rasmus > > > > Thank you Rasmus :) > > > I think you were lucky (or unlucky, depending on how you look at it) > with your test case > > + num_ret = BPF_SNPRINTF(num_out, sizeof(num_out), > + "%d %u %x %li %llu %lX", > + -8, 9, 150, -424242, 1337, 0xDABBAD00); > > because it just so happens that the eventual snprintf() call uses three > arguments for itself, so the first three 32-bit arguments end up being > passed via registers, while the 64 bit arguments are passed via the > stack. Can I get you to test what would happen if you interchanged > these, i.e. changed the test case to do > > + num_ret = BPF_SNPRINTF(num_out, sizeof(num_out), > + "%li %llu %lX %d %u %x", > + -424242, 1337, 0xDABBAD00, -8, 9, 150); > > (or just add a few more expects-a-32-bit argument format specifiers and > corresponding arguments). My guess is that up until formatting -8 it > goes well, but when vsnprintf() is to grab the argument corresponding to > %u, it will get the 0xffffffff from the upper half of (u64)-8. I will need to come up with a repro and let you know yes :) > > It seems that we went offtrack in > > https://lore.kernel.org/bpf/CAEf4BzZVEGM4esi-Rz67_xX_RTDrgxViy0gHfpeauECR5bmRNA@mail.gmail.com/ > > and we do need something like "88a5c690b6 bpf: fix bpf_trace_printk on > > 32 bit archs". Thinking about it again, it's clearer now why the > > __BPF_TP_EMIT macro emits 2^3=8 different __trace_printk() indeed. > > Isn't it 3^3 = 27, or has that been reduced in -next compared to Linus' > master? Doesn't matter much, just curious. > > > In the case of bpf_trace_printk with a maximum of 3 args, it's > > relatively cheap; but for bpf_seq_printf and bpf_snprintf which accept > > up to 12 arguments, that would be 2^12=4096 calls. > > Yeah, that doesn't scale at all. > > Until now > > bpf_seq_printf has just ignored this problem and just considered > > everything as u64, I wonder if that'd be the best approach for these > > two helpers anyway. > > > > [wild handwaving ahead] > > One possibility, if one is willing to get hands dirty and dig into ABI > details on various arches, is to create a > > struct fake_va_list { > union { > va_list ap; /* opaque, compiler-provided */ > arch_va_list _ap; /* arch-provided, must match layout of ap */ > }; > void *stack; > }; > > Then do > > struct fake_va_list fva; > u64 buf[24]; /* or whatever you want to support, can be different in > different functions */ > > fake_va_init(&fva, buf); > /* various C code, parsing format string etc. */ > if (arg[i] is really 32 bits) > fake_va_push(&fva, (u32)arg[i]); > else > fake_va_push(&fva, (u64)arg[i]); > /* etc. */ > ... > vsnprintf(out, size, fmt, fva.va); > > On arches like x86-64, where va_list is really a typedef for a > one-element array of > > struct __va_list_tag { > unsigned int gp_offset; > unsigned int fp_offset; > void * overflow_arg_area; > void * reg_save_area; > }; > > > fake_va_init() would make the va_list look like the reg_save_area is > already used (i.e., set gp_offset to 48), and initialize both > ->_ap.overflow_arg_area and ->stack to point at the given buffer. > fake_va_push() would use and update stack appropriately. For 32 bit x86, > va_list is really just a pointer, so fake_va_init would essentially just > do "fva->_ap = fva->stack = buf", and fake_va_push() would again just > need to manipulate ->stack. > > It's not pretty, but I don't think it necessarily requires too much > arch-specific work (fake_va_push() could be common, perhaps just with a > arch define to say whether 64 bit arguments need ->stack to first be > up-aligned to an 8 byte boundary). > > Rasmus Creative! :D I think these arch-specific structures would be a hard sell though ahah. I was having a stroll through lib/vsprintf.c and noticed bstr_printf: * This function like C99 vsnprintf, but the difference is that vsnprintf gets * arguments from stack, and bstr_printf gets arguments from @bin_buf which is * a binary buffer that generated by vbin_printf. Maybe it would be easier to just build our argument buffer similarly to what vbin_printf does.