Re: Bind ending up in Parked state.

["Jeremy C. Reed" <reed%reedmedia.net@localhost>]

On Tue, 10 Mar 2015, Christos Zoulas wrote:

> Still I would like to know what is taking all this time... ktrace it
> and then kdump -R to display relative timestamps.

I also have bind 9.10.2 on NetBSD/amd64 6.1.3 in

20117 jreed     43    0  1394M 1296M parked/0   0:52  0.00%  0.00% named

That is after doing a kill of the pid and named logged:

10-Mar-2015 19:17:44.544 no longer listening on ::1#5300
10-Mar-2015 19:17:44.559 exiting

I send ABRT to it and then gdb "bt"

Core was generated by `named'.
Program terminated with signal 6, Aborted.
#0  0x00007f7ff6a3964a in _sys___kevent50 () from /usr/lib/libc.so.12
(gdb) bt
#0  0x00007f7ff6a3964a in _sys___kevent50 () from /usr/lib/libc.so.12
#1  0x00007f7ff6e06ed3 in __kevent50 () from /usr/lib/libpthread.so.1
#2  0x00000000005e7284 in watcher (uap=0x7f7ff7b29000) at socket.c:4190
#3  0x00007f7ff6e0b2ce in ?? () from /usr/lib/libpthread.so.1
#4  0x00007f7ff6a75d80 in ___lwp_park50 () from /usr/lib/libc.so.12

I ran it again with ktrace, sent term signal to it, and it logged exit 
and hung. End of kdump -R showed

  1564      3 named    -1.999996396 RET   _lwp_unpark_all 0
  1564      8 named    0.000007460 CALL  
_lwp_unpark_all(0x7f7ff20000e8,3,0x7f7ff7b90090)
  1564      3 named    0.000002081 CALL  
___lwp_park50(0,0,0x7f7ff7b90090,0x7f7ff7b90090)
  1564      4 named    -1.999999409 CALL  
___lwp_park50(0,0,0x7f7ff7b90090,0x7f7ff7b90090)
  1564      7 named    -1.999994745 RET   ___lwp_park50 0
  1564      7 named    0.000009647 CALL  
___lwp_park50(0,0,0x7f7ff7b90090,0x7f7ff7b90090)
  1564      8 named    0.000002576 RET   _lwp_unpark_all 0
  1564      9 named    0.000002070 RET   ___lwp_park50 0
  1564      2 named    0.000001449 RET   ___lwp_park50 0
  1564      8 named    -1.999998846 CALL  
___lwp_park50(0,0,0x7f7ff7b90090,0x7f7ff7b90090)
  1564      5 named    0.000001263 RET   ___lwp_park50 0
  1564      2 named    0.000002382 CALL  
___lwp_park50(0,0,0x7f7ff7b90090,0x7f7ff7b90090)
  1564      9 named    -1.999999323 CALL  
___lwp_park50(0,0,0x7f7ff7b90090,0x7f7ff7b90090)
  1564      5 named    0.000002127 CALL  
___lwp_park50(0,0,0x7f7ff7b90090,0x7f7ff7b90090)


> Hit 't' to switch to the thread view to get more details about what's going
> on for each individual thread (for the threaded named).

  PID USERNAME PRI NICE   SIZE   RES STATE      TIME   WCPU    CPU COMMAND
 1564 jreed     39    0  1241M 1144M parked/0   1:10  0.00%  0.00% named

after pressing "t"

  PID   LID USERNAME PRI STATE      TIME   WCPU    CPU NAME      COMMAND
 1564     9 jreed     37 parked/2   0:12  0.00%  0.00% -         named
 1564     5 jreed     40 parked/5   0:08  0.00%  0.00% -         named
 1564     2 jreed     41 parked/4   0:07  0.00%  0.00% -         named
 1564     8 jreed     41 parked/7   0:07  0.00%  0.00% -         named
 1564     7 jreed     40 parked/3   0:07  0.00%  0.00% -         named
 1564     4 jreed     39 parked/1   0:07  0.00%  0.00% -         named
 1564     6 jreed     39 parked/0   0:07  0.00%  0.00% -         named
 1564     3 jreed     39 parked/6   0:07  0.00%  0.00% -         named
 1564    10 jreed     43 parked/5   0:02  0.00%  0.00% -         named