From owner-freebsd-current@freebsd.org Fri Jan 15 16:35:36 2021 Return-Path: Delivered-To: freebsd-current@mailman.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mailman.nyi.freebsd.org (Postfix) with ESMTP id CFE2A4EB062 for ; Fri, 15 Jan 2021 16:35:36 +0000 (UTC) (envelope-from kostikbel@gmail.com) Received: from kib.kiev.ua (kib.kiev.ua [IPv6:2001:470:d5e7:1::1]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits) server-digest SHA256) (Client did not present a certificate) by mx1.freebsd.org (Postfix) with ESMTPS id 4DHRcm3CDNz3Dcs; Fri, 15 Jan 2021 16:35:36 +0000 (UTC) (envelope-from kostikbel@gmail.com) Received: from tom.home (kib@localhost [127.0.0.1]) by kib.kiev.ua (8.16.1/8.16.1) with ESMTPS id 10FGZRZt008964 (version=TLSv1.3 cipher=TLS_AES_256_GCM_SHA384 bits=256 verify=NO); Fri, 15 Jan 2021 18:35:30 +0200 (EET) (envelope-from kostikbel@gmail.com) DKIM-Filter: OpenDKIM Filter v2.10.3 kib.kiev.ua 10FGZRZt008964 Received: (from kostik@localhost) by tom.home (8.16.1/8.16.1/Submit) id 10FGZRMc008963; Fri, 15 Jan 2021 18:35:27 +0200 (EET) (envelope-from kostikbel@gmail.com) X-Authentication-Warning: tom.home: kostik set sender to kostikbel@gmail.com using -f Date: Fri, 15 Jan 2021 18:35:27 +0200 From: Konstantin Belousov To: =?utf-8?Q?Mika=C3=ABl?= Urankar Cc: freebsd-current Subject: Re: Waiting for bufdaemon Message-ID: References: <7c4da243-52ff-c5ee-3d56-1ae651286e0e@alvermark.net> <369b3d82-98c5-b31e-6168-4003a042f174@FreeBSD.org> <556d40b8-92d7-303e-7d87-ea496d0ca733@FreeBSD.org> <51df64cc-f01c-0030-a708-f4002be6411e@FreeBSD.org> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Disposition: inline Content-Transfer-Encoding: 8bit In-Reply-To: <51df64cc-f01c-0030-a708-f4002be6411e@FreeBSD.org> X-Spam-Status: No, score=-1.0 required=5.0 tests=ALL_TRUSTED,BAYES_00, DKIM_ADSP_CUSTOM_MED,FORGED_GMAIL_RCVD,FREEMAIL_FROM, NML_ADSP_CUSTOM_MED autolearn=no autolearn_force=no version=3.4.4 X-Spam-Checker-Version: SpamAssassin 3.4.4 (2020-01-24) on tom.home X-Rspamd-Queue-Id: 4DHRcm3CDNz3Dcs X-Spamd-Bar: ---- X-Spamd-Result: default: False [-4.00 / 15.00]; REPLY(-4.00)[] X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.34 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 15 Jan 2021 16:35:36 -0000 On Fri, Jan 15, 2021 at 05:18:56PM +0100, Mikaël Urankar wrote: > On 15/01/2021 16:45, Konstantin Belousov wrote: > > On Fri, Jan 15, 2021 at 04:30:19PM +0100, Mikaël Urankar wrote: > > > On 15/01/2021 16:02, Konstantin Belousov wrote: > > > > On Fri, Jan 15, 2021 at 03:56:01PM +0100, Mikaël Urankar wrote: > > > > > On 15/01/2021 11:26, Jakob Alvermark wrote: > > > > > > Hi, > > > > > > > > > > > > > > > > > > When rebooting my thinkpad the 'bufdaemon' times out: > > > > > > > > > > > > Waiting (max 60 seconds) for system thread 'bufdaemon' to stop ... timed > > > > > > out > > > > > > > > > > > > Waiting (max 60 seconds) for system thread 'bufspacedaemon-0' to stop > > > > > > ... timed out > > > > > > > > > > > > Waiting (max 60 seconds) for system thread 'bufspacedaemon-1' to stop > > > > > > ... timed out > > > > > > > > > > > > Waiting (max 60 seconds) for system thread 'bufspacedaemon-2' to stop > > > > > > ... timed out > > > > > > > > > > > > Waiting (max 60 seconds) for system thread 'bufspacedaemon-3' to stop > > > > > > ... timed out > > > > > > > > > > > > Waiting (max 60 seconds) for system thread 'bufspacedaemon-4' to stop > > > > > > ... timed out > > > > > > > > > > > > Waiting (max 60 seconds) for system thread 'bufspacedaemon-5' to stop > > > > > > ... timed out > > > > > > > > > > > > > > > > > > This started happening recently (within the last week I think). > > > > > > > > > > Hi, > > > > > > > > > > I'm also affected. I have an AMD Ryzen 9 3900X cpu, running bare metal. > > > > > > > > > > 5844bd058aed6f3d0c8cbbddd6aa95993ece0189 (jobc: rework detection of orphaned > > > > > groups) "seems" ok > > > > > > > > > > cd240c9cf100bec3def38ceb4a320611b1d02693 (x86 vdso gettc: Add RDTSCP > > > > > support), affected by the timeout. > > > > > > > > > > I haven't tried the intermediate commit yet. > > > > > > > > > > My intel machine doesn't seem to be affected > > > > > > > > If you revert only 9e680e4005b7, is it fixed ? > > > > > > > Yes it seems to be fixed with 9e680e4005b7 reverted (I've only done 2 tests, > > > I can do more if you want) > > Please show me the output from sysctl > > kern.timecounter > > kern.eventtimer > > and first 100 lines of dmesg from the verbose boot (that contains the CPU > > ident lines). > > > > I put the /var/run/dmesg.boot file on freefall /home/mikael/dmesg.boot (it > seems to be truncated though, I don't know how to retrieve the full log) > > sysctl kern.timecounter > kern.timecounter.tsc_shift: 1 > kern.timecounter.smp_tsc_adjust: 0 > kern.timecounter.smp_tsc: 1 > kern.timecounter.invariant_tsc: 1 > kern.timecounter.fast_gettime: 1 > kern.timecounter.tick: 1 > kern.timecounter.choice: ACPI-fast(900) HPET(950) i8254(0) TSC-low(1000) > dummy(-1000000) > kern.timecounter.hardware: TSC-low > kern.timecounter.alloweddeviation: 5 > kern.timecounter.timehands_count: 2 > kern.timecounter.stepwarnings: 0 > kern.timecounter.tc.ACPI-fast.quality: 900 > kern.timecounter.tc.ACPI-fast.frequency: 3579545 > kern.timecounter.tc.ACPI-fast.counter: 1470549582 > kern.timecounter.tc.ACPI-fast.mask: 4294967295 > kern.timecounter.tc.HPET.quality: 950 > kern.timecounter.tc.HPET.frequency: 14318180 > kern.timecounter.tc.HPET.counter: 378058131 > kern.timecounter.tc.HPET.mask: 4294967295 > kern.timecounter.tc.i8254.quality: 0 > kern.timecounter.tc.i8254.frequency: 1193182 > kern.timecounter.tc.i8254.counter: 20425 > kern.timecounter.tc.i8254.mask: 65535 > kern.timecounter.tc.TSC-low.quality: 1000 > kern.timecounter.tc.TSC-low.frequency: 1900039387 > kern.timecounter.tc.TSC-low.counter: 2386729797 > kern.timecounter.tc.TSC-low.mask: 4294967295 > > sysctl kern.eventtimer > kern.eventtimer.choice: LAPIC(600) HPET(350) HPET1(350) HPET2(350) > i8254(100) RTC(0) > kern.eventtimer.et.HPET2.quality: 350 > kern.eventtimer.et.HPET2.frequency: 14318180 > kern.eventtimer.et.HPET2.flags: 3 > kern.eventtimer.et.HPET1.quality: 350 > kern.eventtimer.et.HPET1.frequency: 14318180 > kern.eventtimer.et.HPET1.flags: 3 > kern.eventtimer.et.HPET.quality: 350 > kern.eventtimer.et.HPET.frequency: 14318180 > kern.eventtimer.et.HPET.flags: 3 > kern.eventtimer.et.RTC.quality: 0 > kern.eventtimer.et.RTC.frequency: 32768 > kern.eventtimer.et.RTC.flags: 17 > kern.eventtimer.et.i8254.quality: 100 > kern.eventtimer.et.i8254.frequency: 1193182 > kern.eventtimer.et.i8254.flags: 1 > kern.eventtimer.et.LAPIC.quality: 600 > kern.eventtimer.et.LAPIC.frequency: 50001034 > kern.eventtimer.et.LAPIC.flags: 7 > kern.eventtimer.periodic: 0 > kern.eventtimer.timer: LAPIC > kern.eventtimer.idletick: 0 > kern.eventtimer.singlemul: 2 It is clipped at the start, and that was the information which I need. Add something like kern.msgbufsize=1048576 to /boot/loader.conf and try again. I need to see the lines starting with CPU: Intel(R) Core(TM) i7-8550U CPU @ 1.80GHz (1992.08-MHz K8-class CPU) Origin="GenuineIntel" Id=0x806ea Family=0x6 Model=0x8e Stepping=10 Features=0xbfebfbff ... (well, this is my w/s, your CPU would be different of course).