From owner-freebsd-stable@freebsd.org Wed Sep 28 10:06:56 2016 Return-Path: Delivered-To: freebsd-stable@mailman.ysv.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) by mailman.ysv.freebsd.org (Postfix) with ESMTP id 00CC5BE8A16 for ; Wed, 28 Sep 2016 10:06:56 +0000 (UTC) (envelope-from julien.charbon@gmail.com) Received: from mail-wm0-x22c.google.com (mail-wm0-x22c.google.com [IPv6:2a00:1450:400c:c09::22c]) (using TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 (128/128 bits)) (Client CN "smtp.gmail.com", Issuer "Google Internet Authority G2" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 6345A14B for ; Wed, 28 Sep 2016 10:06:55 +0000 (UTC) (envelope-from julien.charbon@gmail.com) Received: by mail-wm0-x22c.google.com with SMTP id b130so62283722wmc.0 for ; Wed, 28 Sep 2016 03:06:55 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=subject:to:references:cc:from:message-id:date:user-agent :mime-version:in-reply-to; bh=gDRfkvugcnMbaDg7f8KEuFMyMDy6z8cg/3BszSgIgww=; b=S1IPbpvVcKlxbHwhsVPqfZJL61aq8tjUcCnGf8gEoWTGcmqaPAjJlf0ob8gkA2XTPS p58M6GvCBF1Z/PPIEg7AIsdhqi3Qdt8MsZG2ZE3IreofTFDQZ5/FaJuO7vsRndiRfHpO NGZ04wBMBTOd/3FTmRq6E1CCVfnA/tqSZ59NUzkrQX0IYOaoqq2+jm5s5RUk3anA6u6p r4fbjxOLc7YLDvf9lVI8bjDLov5k31ONQEszYNyas5N2W5+nV8oNuZtjd5g2brQj5agF VM1pNaccqikERoAcZYrekYWyROj0nYCuYDliA9tSjZaZrjfZwa84Y01B3rHG58tV0zZm hOUA== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20130820; h=x-gm-message-state:subject:to:references:cc:from:message-id:date :user-agent:mime-version:in-reply-to; bh=gDRfkvugcnMbaDg7f8KEuFMyMDy6z8cg/3BszSgIgww=; b=mQUFhqfn7Yu1HdfClqRc0DGOaxWsHh7f3AqSXW/Ocr/mv1vj/R+PTQsHQDj0hWotq3 tyxzxffZtjjKV+H+Ev3bXdFZ7HWMlVx4dOjlr+L6/GN5yYp2MHpb7PBzD7ezZm3ieD/D +FArY30fQRDz3k2BVF2g6onweW/rG9vRreMmhhP24g8iOHU235pbEkNbqz16HqQQtl5Z 3eVL0ChvCSyYfZK3Yl1B6v0meYfjLTgvn9tbwqs160MkWYw+W/votRkp1sbuA6NCWrrP VKSHB1Vc7VgRo9suB8asITxMHmKxzeURsql5L8vyRGw5OEP4u5JllYlfDcJuzObZSJrR /IJA== X-Gm-Message-State: AA6/9Rk3X2pooIDbfuQeLZQW+ZdMeu/lh5FX1gbkVtk43BJQl7KiHq4F0wjUuJMEh2a0ow== X-Received: by 10.28.155.203 with SMTP id d194mr7475466wme.72.1475057213643; Wed, 28 Sep 2016 03:06:53 -0700 (PDT) Received: from [10.100.64.19] ([217.30.88.44]) by smtp.gmail.com with ESMTPSA id 142sm7931284wmh.12.2016.09.28.03.06.52 (version=TLS1_2 cipher=ECDHE-RSA-AES128-GCM-SHA256 bits=128/128); Wed, 28 Sep 2016 03:06:52 -0700 (PDT) Subject: Re: 11.0 stuck on high network load To: Slawa Olhovchenkov References: <78cbcdc9-f565-1046-c157-2ddd8fcccc62@freebsd.org> <20160919204328.GN2840@zxy.spb.ru> <8ba75d6e-4f01-895e-0aed-53c6c6692cb9@freebsd.org> <20160920202633.GQ2840@zxy.spb.ru> <20160921195155.GW2840@zxy.spb.ru> <20160923200143.GG2840@zxy.spb.ru> <20160925124626.GI2840@zxy.spb.ru> <20160926172159.GA54003@zxy.spb.ru> Cc: Konstantin Belousov , freebsd-stable@FreeBSD.org, hiren panchasara From: Julien Charbon Message-ID: <62453d9c-b1e4-1129-70ff-654dacea37f9@gmail.com> Date: Wed, 28 Sep 2016 12:06:47 +0200 User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.11; rv:45.0) Gecko/20100101 Thunderbird/45.3.0 MIME-Version: 1.0 In-Reply-To: <20160926172159.GA54003@zxy.spb.ru> Content-Type: multipart/signed; micalg=pgp-sha512; protocol="application/pgp-signature"; boundary="Grg9p8WN0dsPRVQnwhLgfPmlD08BcOFiP" X-BeenThere: freebsd-stable@freebsd.org X-Mailman-Version: 2.1.23 Precedence: list List-Id: Production branch of FreeBSD source code List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 28 Sep 2016 10:06:56 -0000 This is an OpenPGP/MIME signed message (RFC 4880 and 3156) --Grg9p8WN0dsPRVQnwhLgfPmlD08BcOFiP Content-Type: multipart/mixed; boundary="eJoODbLvCaU8HsWinURaWqn1UNV0S7cOH"; protected-headers="v1" From: Julien Charbon To: Slawa Olhovchenkov Cc: Konstantin Belousov , freebsd-stable@FreeBSD.org, hiren panchasara Message-ID: <62453d9c-b1e4-1129-70ff-654dacea37f9@gmail.com> Subject: Re: 11.0 stuck on high network load References: <78cbcdc9-f565-1046-c157-2ddd8fcccc62@freebsd.org> <20160919204328.GN2840@zxy.spb.ru> <8ba75d6e-4f01-895e-0aed-53c6c6692cb9@freebsd.org> <20160920202633.GQ2840@zxy.spb.ru> <20160921195155.GW2840@zxy.spb.ru> <20160923200143.GG2840@zxy.spb.ru> <20160925124626.GI2840@zxy.spb.ru> <20160926172159.GA54003@zxy.spb.ru> In-Reply-To: <20160926172159.GA54003@zxy.spb.ru> --eJoODbLvCaU8HsWinURaWqn1UNV0S7cOH Content-Type: text/plain; charset=windows-1252 Content-Transfer-Encoding: quoted-printable Hi Slawa, On 9/26/16 7:22 PM, Slawa Olhovchenkov wrote: > On Mon, Sep 26, 2016 at 11:33:12AM +0200, Julien Charbon wrote: >> On 9/25/16 2:46 PM, Slawa Olhovchenkov wrote: >>> On Fri, Sep 23, 2016 at 11:01:43PM +0300, Slawa Olhovchenkov wrote: >>>>> On 9/21/16 9:51 PM, Slawa Olhovchenkov wrote: >>>>>> On Wed, Sep 21, 2016 at 09:11:24AM +0200, Julien Charbon wrote: >>>>>>> You can also use Dtrace and lockstat (especially with the lockst= at -s >>>>>>> option): >>>>>>> >>>>>>> https://wiki.freebsd.org/DTrace/One-Liners#Kernel_Locks >>>>>>> https://www.freebsd.org/cgi/man.cgi?query=3Dlockstat&manpath=3DFr= eeBSD+11.0-RELEASE >>>>>>> >>>>>>> But I am less familiar with Dtrace/lockstat tools. >>>>>> >>>>>> I am still use old kernel and got lockdown again. >>>>>> Try using lockstat (I am save more output), interesting may be nex= t: >>>>>> >>>>>> R/W writer spin on writer: 190019 events in 1.070 seconds (177571 = events/sec) >>>>>> >>>>>> ------------------------------------------------------------------= ------------- >>>>>> Count indv cuml rcnt nsec Lock Caller = =20 >>>>>> 140839 74% 74% 0.00 24659 tcpinp tcp_tw_2msl_= scan+0xc6 =20 >>>>>> >>>>>> nsec ------ Time Distribution ------ count Stack = =20 >>>>>> 4096 | 913 tcp_twstart+0= xa3 =20 >>>>>> 8192 |@@@@@@@@@@@@ 58191 tcp_do_segmen= t+0x201f =20 >>>>>> 16384 |@@@@@@ 29594 tcp_input+0xe= 1c =20 >>>>>> 32768 |@@@@ 23447 ip_input+0x15= f =20 >>>>>> 65536 |@@@ 16197 =20 >>>>>> 131072 |@ 8674 =20 >>>>>> 262144 | 3358 =20 >>>>>> 524288 | 456 =20 >>>>>> 1048576 | 9 =20 >>>>>> ------------------------------------------------------------------= ------------- >>>>>> Count indv cuml rcnt nsec Lock Caller = =20 >>>>>> 49180 26% 100% 0.00 15929 tcpinp tcp_tw_2msl_s= can+0xc6 =20 >>>>>> >>>>>> nsec ------ Time Distribution ------ count Stack = =20 >>>>>> 4096 | 157 pfslowtimo+0x= 54 =20 >>>>>> 8192 |@@@@@@@@@@@@@@@ 24796 softclock_cal= l_cc+0x179=20 >>>>>> 16384 |@@@@@@ 11223 softclock+0x4= 4 =20 >>>>>> 32768 |@@@@ 7426 intr_event_ex= ecute_handlers+0x95 >>>>>> 65536 |@@ 3918 =20 >>>>>> 131072 | 1363 =20 >>>>>> 262144 | 278 =20 >>>>>> 524288 | 19 =20 >>>>>> ------------------------------------------------------------------= ------------- >>>>> >>>>> This is interesting, it seems that you have two call paths competi= ng >>>>> for INP locks here: >>>>> >>>>> - pfslowtimo()/tcp_tw_2msl_scan(reuse=3D0) and >>>>> >>>>> - tcp_input()/tcp_twstart()/tcp_tw_2msl_scan(reuse=3D1) >>>> >>>> My current hypothesis: >>>> >>>> nginx do write() (or may be close()?) to socket, kernel lock >>>> first inp in V_twq_2msl, happen callout for pfslowtimo() on the same= >>>> CPU core and tcp_tw_2msl_scan infinity locked on same inp. >>>> >>>> In this case you modification can't help, before next try we need so= me >>>> like yeld(). >>> >>> Or may be locks leaks. >>> Or both. >> >> You are totally right, pfslowtimo()/tcp_tw_2msl_scan(reuse=3D0) is >> infinitely blocked on INP_WLOCK() by "something" (that could be relate= d >> to write()). >> >> As I reached my limit of debugging without WITNESS, could you share >> your /etc/sysctl.conf, /boot/loader.conf files? And any specific >> configuration you have (like having a Nginx workers affinity, Nginx >> special options, etc.). Like that I can try to reproduce it on releng= /11.0. >=20 > Some more traces from ddb: >=20 > Tracing command intr pid 12 tid 100103 td 0xfffff8012508ea00 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe2020ea8330 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe2020= ea8360 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe2020ea83a0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe2020ea8420 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe2020= ea8460 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe2020= ea8490 > tcp_do_segment() at 0xffffffff80610226 =3D tcp_do_segment+0x1666/frame = 0xfffffe2020ea8590 > tcp_input() at 0xffffffff8060e17c =3D tcp_input+0xe1c/frame 0xfffffe202= 0ea86e0 > ip_input() at 0xffffffff805a087f =3D ip_input+0x15f/frame 0xfffffe2020e= a8740 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020ea87a0 > ether_demux() at 0xffffffff80575b3a =3D ether_demux+0x12a/frame 0xfffff= e2020ea87d0 > ether_nh_input() at 0xffffffff80576792 =3D ether_nh_input+0x322/frame 0= xfffffe2020ea8830 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020ea8890 > ether_input() at 0xffffffff80575db6 =3D ether_input+0x26/frame 0xfffffe= 2020ea88b0 > ixgbe_rxeof() at 0xffffffff813df36b =3D ixgbe_rxeof+0x7ab/frame 0xfffff= e2020ea8990 > ixgbe_msix_que() at 0xffffffff813da57c =3D ixgbe_msix_que+0x8c/frame 0x= fffffe2020ea89e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe2020ea8a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe2020ea8a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe2020= ea8ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe2020ea8ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100105 td 0xfffff8012508e000 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe2020eb2330 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe2020= eb2360 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe2020eb23a0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe2020eb2420 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe2020= eb2460 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe2020= eb2490 > tcp_do_segment() at 0xffffffff80610226 =3D tcp_do_segment+0x1666/frame = 0xfffffe2020eb2590 > tcp_input() at 0xffffffff8060e17c =3D tcp_input+0xe1c/frame 0xfffffe202= 0eb26e0 > ip_input() at 0xffffffff805a087f =3D ip_input+0x15f/frame 0xfffffe2020e= b2740 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020eb27a0 > ether_demux() at 0xffffffff80575b3a =3D ether_demux+0x12a/frame 0xfffff= e2020eb27d0 > ether_nh_input() at 0xffffffff80576792 =3D ether_nh_input+0x322/frame 0= xfffffe2020eb2830 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020eb2890 > ether_input() at 0xffffffff80575db6 =3D ether_input+0x26/frame 0xfffffe= 2020eb28b0 > ixgbe_rxeof() at 0xffffffff813df36b =3D ixgbe_rxeof+0x7ab/frame 0xfffff= e2020eb2990 > ixgbe_msix_que() at 0xffffffff813da57c =3D ixgbe_msix_que+0x8c/frame 0x= fffffe2020eb29e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe2020eb2a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe2020eb2a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe2020= eb2ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe2020eb2ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100107 td 0xfffff8012508d500 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe2020ebc2b0 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe2020= ebc2e0 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe2020ebc320 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe2020ebc3a0 > soalloc() at 0xffffffff8051b914 =3D soalloc+0x1b4/frame 0xfffffe2020ebc= 3f0 > sonewconn() at 0xffffffff8051bb9f =3D sonewconn+0xbf/frame 0xfffffe2020= ebc430 > syncache_expand() at 0xffffffff8061b85b =3D syncache_expand+0x78b/frame= 0xfffffe2020ebc590 > tcp_input() at 0xffffffff8060e10e =3D tcp_input+0xdae/frame 0xfffffe202= 0ebc6e0 > ip_input() at 0xffffffff805a087f =3D ip_input+0x15f/frame 0xfffffe2020e= bc740 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020ebc7a0 > ether_demux() at 0xffffffff80575b3a =3D ether_demux+0x12a/frame 0xfffff= e2020ebc7d0 > ether_nh_input() at 0xffffffff80576792 =3D ether_nh_input+0x322/frame 0= xfffffe2020ebc830 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020ebc890 > ether_input() at 0xffffffff80575db6 =3D ether_input+0x26/frame 0xfffffe= 2020ebc8b0 > ixgbe_rxeof() at 0xffffffff813df36b =3D ixgbe_rxeof+0x7ab/frame 0xfffff= e2020ebc990 > ixgbe_msix_que() at 0xffffffff813da57c =3D ixgbe_msix_que+0x8c/frame 0x= fffffe2020ebc9e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe2020ebca20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe2020ebca70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe2020= ebcab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe2020ebcab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100111 td 0xfffff801250a2000 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe2020f302f0 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe2020= f30320 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe2020f30360 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe2020f303e0 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe2020= f30420 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe2020= f30450 > tcp_twstart() at 0xffffffff8061f0e7 =3D tcp_twstart+0x2b7/frame 0xfffff= e2020f30490 > tcp_do_segment() at 0xffffffff80610bdf =3D tcp_do_segment+0x201f/frame = 0xfffffe2020f30590 > tcp_input() at 0xffffffff8060e17c =3D tcp_input+0xe1c/frame 0xfffffe202= 0f306e0 > ip_input() at 0xffffffff805a087f =3D ip_input+0x15f/frame 0xfffffe2020f= 30740 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020f307a0 > ether_demux() at 0xffffffff80575b3a =3D ether_demux+0x12a/frame 0xfffff= e2020f307d0 > ether_nh_input() at 0xffffffff80576792 =3D ether_nh_input+0x322/frame 0= xfffffe2020f30830 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020f30890 > ether_input() at 0xffffffff80575db6 =3D ether_input+0x26/frame 0xfffffe= 2020f308b0 > ixgbe_rxeof() at 0xffffffff813df36b =3D ixgbe_rxeof+0x7ab/frame 0xfffff= e2020f30990 > ixgbe_msix_que() at 0xffffffff813da57c =3D ixgbe_msix_que+0x8c/frame 0x= fffffe2020f309e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe2020f30a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe2020f30a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe2020= f30ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe2020f30ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100113 td 0xfffff801250a1500 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe2020f3a2f0 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe2020= f3a320 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe2020f3a360 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe2020f3a3e0 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe2020= f3a420 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe2020= f3a450 > tcp_twstart() at 0xffffffff8061f0e7 =3D tcp_twstart+0x2b7/frame 0xfffff= e2020f3a490 > tcp_do_segment() at 0xffffffff80610bdf =3D tcp_do_segment+0x201f/frame = 0xfffffe2020f3a590 > tcp_input() at 0xffffffff8060e17c =3D tcp_input+0xe1c/frame 0xfffffe202= 0f3a6e0 > ip_input() at 0xffffffff805a087f =3D ip_input+0x15f/frame 0xfffffe2020f= 3a740 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020f3a7a0 > ether_demux() at 0xffffffff80575b3a =3D ether_demux+0x12a/frame 0xfffff= e2020f3a7d0 > ether_nh_input() at 0xffffffff80576792 =3D ether_nh_input+0x322/frame 0= xfffffe2020f3a830 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020f3a890 > ether_input() at 0xffffffff80575db6 =3D ether_input+0x26/frame 0xfffffe= 2020f3a8b0 > ixgbe_rxeof() at 0xffffffff813df36b =3D ixgbe_rxeof+0x7ab/frame 0xfffff= e2020f3a990 > ixgbe_msix_que() at 0xffffffff813da57c =3D ixgbe_msix_que+0x8c/frame 0x= fffffe2020f3a9e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe2020f3aa20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe2020f3aa70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe2020= f3aab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe2020f3aab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100115 td 0xfffff801250a0a00 > cpustop_handler() at 0xffffffff80775998 =3D cpustop_handler+0x28/frame = 0xfffffe1f9e182cf0 > ipi_nmi_handler() at 0xffffffff8077595a =3D ipi_nmi_handler+0x4a/frame = 0xfffffe1f9e182d10 > trap() at 0xffffffff806e2e4a =3D trap+0x3a/frame 0xfffffe1f9e182f20 > nmi_calltrap() at 0xffffffff806cb413 =3D nmi_calltrap+0x8/frame 0xfffff= e1f9e182f20 > --- trap 0x13, rip =3D 0xffffffff8059b9f9, rsp =3D 0xfffffe2020f44420, = rbp =3D 0xfffffe2020f44420 --- > in_pcbref() at 0xffffffff8059b9f9 =3D in_pcbref+0x9/frame 0xfffffe2020f= 44420 > tcp_tw_2msl_scan() at 0xffffffff8061f1a3 =3D tcp_tw_2msl_scan+0x73/fram= e 0xfffffe2020f44450 > tcp_twstart() at 0xffffffff8061eed3 =3D tcp_twstart+0xa3/frame 0xfffffe= 2020f44490 > tcp_do_segment() at 0xffffffff80610bdf =3D tcp_do_segment+0x201f/frame = 0xfffffe2020f44590 > tcp_input() at 0xffffffff8060e17c =3D tcp_input+0xe1c/frame 0xfffffe202= 0f446e0 > ip_input() at 0xffffffff805a087f =3D ip_input+0x15f/frame 0xfffffe2020f= 44740 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020f447a0 > ether_demux() at 0xffffffff80575b3a =3D ether_demux+0x12a/frame 0xfffff= e2020f447d0 > ether_nh_input() at 0xffffffff80576792 =3D ether_nh_input+0x322/frame 0= xfffffe2020f44830 > netisr_dispatch_src() at 0xffffffff80583db5 =3D netisr_dispatch_src+0xa= 5/frame 0xfffffe2020f44890 > ether_input() at 0xffffffff80575db6 =3D ether_input+0x26/frame 0xfffffe= 2020f448b0 > ixgbe_rxeof() at 0xffffffff813df36b =3D ixgbe_rxeof+0x7ab/frame 0xfffff= e2020f44990 > ixgbe_msix_que() at 0xffffffff813da57c =3D ixgbe_msix_que+0x8c/frame 0x= fffffe2020f449e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe2020f44a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe2020f44a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe2020= f44ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe2020f44ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command ps pid 37011 tid 101992 td 0xfffff80144378000 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe20224367b0 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe2022= 4367e0 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe2022436820 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe20224368a0 > soalloc() at 0xffffffff8051b914 =3D soalloc+0x1b4/frame 0xfffffe2022436= 8f0 > socreate() at 0xffffffff8051b617 =3D socreate+0xa7/frame 0xfffffe202243= 6940 > sys_socket() at 0xffffffff8052144d =3D sys_socket+0xed/frame 0xfffffe20= 224369a0 > amd64_syscall() at 0xffffffff806e4051 =3D amd64_syscall+0x2c1/frame 0xf= ffffe2022436ab0 > Xfast_syscall() at 0xffffffff806cb2bb =3D Xfast_syscall+0xfb/frame 0xff= fffe2022436ab0 > --- syscall (97, FreeBSD ELF64, sys_socket), rip =3D 0x8011c413a, rsp =3D= 0x7fffffffc748, rbp =3D 0x7fffffffc770 --- >=20 > Tracing command cron pid 37008 tid 102228 td 0xfffff801a4090000 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe20228d67b0 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe2022= 8d67e0 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe20228d6820 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe20228d68a0 > soalloc() at 0xffffffff8051b914 =3D soalloc+0x1b4/frame 0xfffffe20228d6= 8f0 > socreate() at 0xffffffff8051b617 =3D socreate+0xa7/frame 0xfffffe20228d= 6940 > sys_socket() at 0xffffffff8052144d =3D sys_socket+0xed/frame 0xfffffe20= 228d69a0 > amd64_syscall() at 0xffffffff806e4051 =3D amd64_syscall+0x2c1/frame 0xf= ffffe20228d6ab0 > Xfast_syscall() at 0xffffffff806cb2bb =3D Xfast_syscall+0xfb/frame 0xff= fffe20228d6ab0 > --- syscall (97, FreeBSD ELF64, sys_socket), rip =3D 0x800d8c13a, rsp =3D= 0x7fffffffd658, rbp =3D 0x7fffffffd6f0 --- >=20 > [many likes] >=20 > Tracing command intr pid 12 tid 100015 td 0xfffff8011422b000 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1cf760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1cf790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1cf7d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1cf850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1cf890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1cf8c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1cf8f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1cf9c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1cf9e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1cfa20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1cfa70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1cfab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1cfab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100016 td 0xfffff8011422aa00 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1d4760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1d4790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1d47d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1d4850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1d4890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1d48c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1d48f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1d49c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1d49e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1d4a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1d4a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1d4ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1d4ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100017 td 0xfffff8011422a500 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1d9760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1d9790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1d97d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1d9850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1d9890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1d98c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1d98f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1d99c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1d99e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1d9a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1d9a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1d9ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1d9ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100018 td 0xfffff8011422a000 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1de760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1de790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1de7d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1de850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1de890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1de8c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1de8f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1de9c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1de9e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1dea20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1dea70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1deab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1deab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100019 td 0xfffff8011424da00 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1e3760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1e3790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1e37d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1e3850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1e3890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1e38c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1e38f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1e39c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1e39e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute-- 7:zsh -- time-stamp -- Sep/26/16 20:00:13 -- > -- 7:zsh -- time-stamp -- Sep/26/16 20:00:13 -- > _handlers+0x95/frame 0xfffffe1f9e1e3a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1e3a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1e3ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1e3ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100020 td 0xfffff8011424d500 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1e8760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1e8790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1e87d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1e8850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1e8890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1e88c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1e88f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1e89c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1e89e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1e8a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1e8a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1e8ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1e8ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100021 td 0xfffff8011424d000 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1ed760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1ed790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1ed7d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1ed850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1ed890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1ed8c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1ed8f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1ed9c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1ed9e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1eda20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1eda70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1edab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1edab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100022 td 0xfffff8011424ca00 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1f2760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1f2790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1f27d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1f2850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1f2890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1f28c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1f28f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1f29c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1f29e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1f2a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1f2a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1f2ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1f2ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100023 td 0xfffff8011424c500 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1f7760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1f7790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1f77d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1f7850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1f7890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1f78c0 > tcp_timer_rexmt() at 0xffffffff8061e454 =3D tcp_timer_rexmt+0x114/frame= 0xfffffe1f9e1f78f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1f79c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1f79e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1f7a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1f7a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1f7ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1f7ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100024 td 0xfffff8011424c000 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe1f9e1fc760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe1f9e= 1fc790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe1f9e1fc7d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe1f9e1fc850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe1f9e= 1fc890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe1f9e= 1fc8c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe1f9e1fc8f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe1f9e1fc9c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe1f9e= 1fc9e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe1f9e1fca20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe1f9e1fca70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe1f9e= 1fcab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe1f9e1fcab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100025 td 0xfffff8011424ba00 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe0000382760 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe0000= 382790 > turnstile_wait() at 0xffffffff804ef177 =3D turnstile_wait+0x2a7/frame 0= xfffffe00003827d0 > __mtx_lock_sleep() at 0xffffffff80484d9d =3D __mtx_lock_sleep+0x13d/fra= me 0xfffffe0000382850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe0000= 382890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe0000= 3828c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe00003828f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe00003829c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe0000= 3829e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe0000382a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe0000382a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe0000= 382ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe0000382ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- >=20 > Tracing command intr pid 12 tid 100026 td 0xfffff8011424b500 > sched_switch() at 0xffffffff804c956d =3D sched_switch+0x6ad/frame 0xfff= ffe00003876f0 > mi_switch() at 0xffffffff804a8d92 =3D mi_switch+0xd2/frame 0xfffffe0000= 387720 > critical_exit() at 0xffffffff804a6bee =3D critical_exit+0x7e/frame 0xff= fffe0000387740 > ipi_bitmap_handler() at 0xffffffff80775629 =3D ipi_bitmap_handler+0x79/= frame 0xfffffe0000387780 > Xipi_intr_bitmap_handler() at 0xffffffff806cc15e =3D Xipi_intr_bitmap_h= andler+0x8e/frame 0xfffffe0000387780 > --- interrupt, rip =3D 0xffffffff80484c1f, rsp =3D 0xfffffe0000387850, = rbp =3D 0xfffffe0000387850 --- > __mtx_lock_flags() at 0xffffffff80484c1f =3D __mtx_lock_flags+0x2f/fram= e 0xfffffe0000387850 > sodealloc() at 0xffffffff8051b992 =3D sodealloc+0x32/frame 0xfffffe0000= 387890 > tcp_close() at 0xffffffff80618150 =3D tcp_close+0xd0/frame 0xfffffe0000= 3878c0 > tcp_timer_2msl() at 0xffffffff8061dda3 =3D tcp_timer_2msl+0x1f3/frame 0= xfffffe00003878f0 > softclock_call_cc() at 0xffffffff804b4ca9 =3D softclock_call_cc+0x179/f= rame 0xfffffe00003879c0 > softclock() at 0xffffffff804b5034 =3D softclock+0x44/frame 0xfffffe0000= 3879e0 > intr_event_execute_handlers() at 0xffffffff8046c605 =3D intr_event_exec= ute_handlers+0x95/frame 0xfffffe0000387a20 > ithread_loop() at 0xffffffff8046cc26 =3D ithread_loop+0xa6/frame 0xffff= fe0000387a70 > fork_exit() at 0xffffffff8046a211 =3D fork_exit+0x71/frame 0xfffffe0000= 387ab0 > fork_trampoline() at 0xffffffff806cb50e =3D fork_trampoline+0xe/frame 0= xfffffe0000387ab0 > --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 --- Nice stack traces, all threads are blocked in sodealloc() or soalloc() and if you look at how mtx_lock(&so_global_mtx) and mtx_unlock(&so_global_mtx) are used, it is hard to think about a scenario that can lead to this state. I am still trying to reproduce your issue, without success so far. -- Julien --eJoODbLvCaU8HsWinURaWqn1UNV0S7cOH-- --Grg9p8WN0dsPRVQnwhLgfPmlD08BcOFiP Content-Type: application/pgp-signature; name="signature.asc" Content-Description: OpenPGP digital signature Content-Disposition: attachment; filename="signature.asc" -----BEGIN PGP SIGNATURE----- Comment: GPGTools - https://gpgtools.org iQEcBAEBCgAGBQJX65Y6AAoJEKVlQ5Je6dhxd8cH/3GbB4ohO33iOXTxdggxQk4l HR24PwCIFVvbVoJ+GokjIbaCD33tWHkL3N1BcdNQ2d/l+72HzciDVIsnCr4VSmQ8 n8frasRJrggQaK61DWofBY5e/YIgsKxRh+k3xtiHJpc6us31CS+DR/7w5MgNAVmW 8v59r84iohbUdKceWQ92vWcaIDw7n9dObRptPHWXCuKXDDkLwUC2bWPgv2pljd6+ ODam89eRG3oNffS2xoUgBrlTkkkXFnL2MGsr/57Af9nFcLCtuRVG2n8O9HaRTuzT /mbrZm0zQ8JX7KIoiEQHl3UaXraUqn6TruOiFPzyJI5VXckS7q5cYhwpABRBowA= =q3Qe -----END PGP SIGNATURE----- --Grg9p8WN0dsPRVQnwhLgfPmlD08BcOFiP--