witness report

classic Classic list List threaded Threaded
3 messages Options
Reply | Threaded
Open this post in threaded view
|

witness report

Hrvoje Popovski
Hi all,

i'm having samba server, transmission client and gnome desktop on one
box. from time to time i'm getting witness log below. source is clean
and fetched few hours ago and compiled with WITNESS. userland and
packages are up to date ..
i put kern.witness.watch=3 in sysctl.conf so now i'm in ddb and will
leave it like this if something is needed

witness log from console:

witness: acquiring duplicate lock of same type: "&to->uv_lock"
 1st unveil
 2nd unveil
Starting stack trace...
witness_checkorder(ffff800000af9078,9,0) at witness_checkorder+0x826
rw_enter_write(ffff800000af9068) at rw_enter_write+0x43
unveil_copy() at unveil_copy+0x183
process_new(ffff8000331b8278,ffff800032f94d50,1) at process_new+0xde
fork1() at fork1+0x2d7
syscall(ffff80003300b1f0) at syscall+0x389
Xsyscall(6,2,c781ea04749,2,7f7ffffcd478,c7a81850500) at Xsyscall+0x128
end of kernel
end trace frame: 0x7f7ffffcd040, count: 250
End of stack trace.




ddb output with kern.witness.watch=3


witness: acquiring duplicate lock of same type: "&to->uv_lock"
 1st unveil
 2nd unveil
Starting stack trace...
witness_checkorder(ffff800000af9078,9,0) at witness_checkorder+0x826
rw_enter_write(ffff800000af9068) at rw_enter_write+0x43
unveil_copy() at unveil_copy+0x183
process_new(ffff8000331b8278,ffff800032f94d50,1) at process_new+0xde
fork1() at fork1+0x2d7
syscall(ffff80003300b1f0) at syscall+0x389
Xsyscall(6,2,c781ea04749,2,7f7ffffcd478,c7a81850500) at Xsyscall+0x128
end of kernel
end trace frame: 0x7f7ffffcd040, count: 250
End of stack trace.
Stopped at      db_enter+0x10:  popq    %rbp

ddb{1}> trace
db_enter() at db_enter+0x10
witness_checkorder(ffff800000af9078,9,0) at witness_checkorder+0x82b
rw_enter_write(ffff800000af9068) at rw_enter_write+0x43
unveil_copy() at unveil_copy+0x183
process_new(ffff8000331b8278,ffff800032f94d50,1) at process_new+0xde
fork1() at fork1+0x2d7
syscall(ffff80003300b1f0) at syscall+0x389
Xsyscall(6,2,c781ea04749,2,7f7ffffcd478,c7a81850500) at Xsyscall+0x128
end of kernel
end trace frame: 0x7f7ffffcd040, count: -8
ddb{1}>

ddb{1}> mach ddbcpu 0
Stopped at      x86_ipi_db+0x12:        leave
ddb{0}> trace
x86_ipi_db(ffffffff81d0fff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(6,ffffffff81d0fff0,ffff8000000c9b00,0,0,ffffffff81e7da68)
at Xresume_lapic_ipi+0x23
__mp_lock(ffffffff81e7da68) at __mp_lock+0xae
intr_handler(ffff800022287240,ffff8000000c9b00) at intr_handler+0x44
Xintr_ioapic_edge23_untramp(0,ffffffff81d0fff0,fffffd81eef2edc0,0,0,ffffffff81e
7da68) at Xintr_ioapic_edge23_untramp+0x19f
__mp_lock(ffffffff81e7da68) at __mp_lock+0xa9
sowakeup(fffffd81eef2edc0,fffffd81eef2ee48) at sowakeup+0x8f
sorwakeup(fffffd81eef2edc0) at sorwakeup+0x78
udp_sbappend(fffffd81eeafa7b0,fffffd800e7b4700,fffffd80131a7490,0,14,fffffd8013
1a74a4) at udp_sbappend+0x1c8
udp_input(ffff800022287588,ffff800022287594,11,2) at udp_input+0xd21
ip_deliver(ffff800022287588,ffff800022287594,11,2) at ip_deliver+0x223
ipintr() at ipintr+0x5f
if_netisr(0) at if_netisr+0x4e
taskq_thread(ffff800000026080) at taskq_thread+0x67
end trace frame: 0x0, count: -15
ddb{0}>


ddb{0}> mach ddbcpu 2
Stopped at      x86_ipi_db+0x12:        leave
ddb{2}> trace
x86_ipi_db(ffff80002201aff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(0,0,1388,0,ffff8000000cca80,ffff80002201b6f8) at
Xresume_lapic_ipi+0x23
acpicpu_idle() at acpicpu_idle+0x271
sched_idle(ffff80002201aff0) at sched_idle+0x225
end trace frame: 0x0, count: -5
ddb{2}>


ddb{3}> trace
x86_ipi_db(ffff800022023ff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(c,ffff800022023ff0,ffff800022023ff0,0,3,ffffffff81e7da68)
at Xresume_lapic_ipi+0x23
__mp_lock(ffffffff81e7da68) at __mp_lock+0xa9
__mp_acquire_count(ffffffff81e7da68,1) at __mp_acquire_count+0x38
mi_switch() at mi_switch+0x243
sleep_finish(ffff80003345ce48,1) at sleep_finish+0x84
tsleep(fffffd80b7e19f28,118,ffffffff81aefeef,485) at tsleep+0xc7
kqueue_scan(fffffd80b7e19f28,40,66acc36f000,ffff80003345d208,ffff800032f3e2a8,f
fff80003345d248) at kqueue_scan+0x4ec
sys_kevent(ffff800032f3e2a8,ffff80003345d2b0,ffff80003345d310) at
sys_kevent+0x28f
syscall(ffff80003345d380) at syscall+0x389
Xsyscall(0,48,7f7ffffbd0b0,48,0,66acc36f000) at Xsyscall+0x128
end of kernel
end trace frame: 0x7f7ffffbd070, count: -12
ddb{3}>


ddb{1}> show locks
shared rwlock unveil r = 0 (0xffff800000966078)
exclusive kernel_lock &kernel_lock r = 0 (0xffffffff81e7dc70)


ddb{1}> show all locks
Process 8789 (transmission-dae) thread 0xffff800032fb73b0 (223699)
exclusive rrwlock inode r = 0 (0xfffffd81ec938818)
Process 33527 (ntpd) thread 0xffff800032f6c500 (150820)
shared rwlock unveil r = 0 (0xffff800000966078)
exclusive kernel_lock &kernel_lock r = 0 (0xffffffff81e7dc70)
Process 32311 (softnet) thread 0xffff800022260750 (481883)
exclusive rwlock netlock r = 0 (0xffffffff81d2d728)
shared rwlock softnet r = 0 (0xffff8000000260d8)
ddb{1}>


ddb{1}> show uvm
Current UVM status:
  pagesize=4096 (0x1000), pagemask=0xfff, pageshift=12
  1984776 VM pages: 91278 active, 108829 inactive, 20163 wired, 549862
free (82
200 zero)
  min  10% (25) anon, 10% (25) vnode, 5% (12) vtext
  freemin=66159, free-target=88212, inactive-target=0, wired-max=661592
  faults=10318249, traps=11117532, intrs=2615550, ctxswitch=13959612
fpuswitch=
0
  softint=963678, syscalls=33501310, kmapent=101
  fault counts:
    noram=0, noanon=0, noamap=0, pgwait=0, pgrele=0
    ok relocks(total)=412659(414555), anget(retries)=751549(0),
amapcopy=753147

    neighbor anon/obj pg=42370/1010563, gets(lock/unlock)=928034/414555
    cases: anon=692300, anoncow=59249, obj=805902, prcopy=120236,
przero=864035
0
  daemon and swap counts:
    woke=0, revs=0, scans=0, obscans=0, anscans=0
    busy=0, freed=0, reactivate=0, deactivate=0
    pageouts=0, pending=0, nswget=0
    nswapdev=1
    swpages=2130010, swpginuse=0, swpgonly=0 paging=0
  kernel pointers:
    objs(kern)=0xffffffff81df23d0
ddb{1}>


ddb{1}> show bcstats
Current Buffer Cache status:
numbufs 89768 busymapped 3, delwri 43
kvaslots 6553 avail kva slots 6550
bufpages 1151851, dmapages 165003, dirtypages 172
pendingreads 1, pendingwrites 0
highflips 421588, highflops 0, dmaflips 6733



ddb{1}> ps
   PID     TID   PPID    UID  S       FLAGS  WAIT          COMMAND
 65386  426603   7192    676  3        0x82  poll          gsd-media-keys
 65386  155334   7192    676  3   0x4000082  poll          gsd-media-keys
 65386  298392   7192    676  3   0x4000082  poll          gsd-media-keys
 65386  284678   7192    676  3   0x4000082  poll          gsd-media-keys
 50644  164544   7192    676  3        0x82  poll          gsd-datetime
 50644  407040   7192    676  3   0x4000082  poll          gsd-datetime
 50644  413327   7192    676  3   0x4000082  poll          gsd-datetime
  6290  479248   7192    676  3        0x82  poll          gsd-dummy
  6290  299404   7192    676  3   0x4000082  poll          gsd-dummy
  6290   22875   7192    676  3   0x4000082  poll          gsd-dummy
  4051  383848   7192    676  3        0x82  poll          gsd-a11y-setting
  4051  509515   7192    676  3   0x4000082  poll          gsd-a11y-setting
  4051  228371   7192    676  3   0x4000082  poll          gsd-a11y-setting
  4051   80071   7192    676  3   0x4000082  poll          gsd-a11y-setting
 62111  405622   7192    676  3        0x82  poll          gsd-sharing
 62111  474913   7192    676  3   0x4000082  poll          gsd-sharing
 62111   17399   7192    676  3   0x4000082  poll          gsd-sharing
 62111  475335   7192    676  3   0x4000082  poll          gsd-sharing
 15048  137852   7192    676  3        0x82  poll          gsd-print-notifi
 15048  128472   7192    676  3   0x4000082  poll          gsd-print-notifi
 15048  383512   7192    676  3   0x4000082  poll          gsd-print-notifi
 11054  376171  28717    676  3        0x82  poll          ibus-engine-simp
 11054  396475  28717    676  3   0x4000082  poll          ibus-engine-simp
 11054  461527  28717    676  3   0x4000082  poll          ibus-engine-simp
 42269  146617   7192    676  3        0x82  poll          gsd-keyboard
 42269  257807   7192    676  3   0x4000082  poll          gsd-keyboard
 42269  283529   7192    676  3   0x4000082  poll          gsd-keyboard
 42269  206034   7192    676  3   0x4000082  poll          gsd-keyboard
 48465  479494   7192    676  3        0x82  poll          gsd-color
 48465  508834   7192    676  3   0x4000082  poll          gsd-color
 48465  319497   7192    676  3   0x4000082  poll          gsd-color
 48465  472004   7192    676  3   0x4000082  poll          gsd-color
 21878   56849   7192    676  3        0x82  poll          gsd-sound
 21878   98640   7192    676  3   0x4000082  poll          gsd-sound
 21878  100258   7192    676  3   0x4000082  poll          gsd-sound
 21878  222482   7192    676  3   0x4000082  poll          gsd-sound
 50951   63522   7192    676  3        0x82  poll          gsd-screensaver-
 50951  448785   7192    676  3   0x4000082  poll          gsd-screensaver-
 50951  451387   7192    676  3   0x4000082  poll          gsd-screensaver-
 57162  347746   7192    676  3        0x82  poll          gsd-power
 57162   59286   7192    676  3   0x4000082  poll          gsd-power
 57162   40253   7192    676  3   0x4000082  poll          gsd-power
 57162  110701   7192    676  3   0x4000082  poll          gsd-power
 62202  235167   7192    676  3        0x82  poll          gsd-mouse
 62202   96044   7192    676  3   0x4000082  poll          gsd-mouse
 62202  466546   7192    676  3   0x4000082  poll          gsd-mouse
 93912  272658   7192    676  3        0x82  poll          gsd-housekeeping
 93912  276613   7192    676  3   0x4000082  poll          gsd-housekeeping
 93912  306019   7192    676  3   0x4000082  poll          gsd-housekeeping
 28946  521644   7192    676  3        0x82  poll          gsd-xsettings
 28946  356673   7192    676  3   0x4000082  poll          gsd-xsettings
 28946  270153   7192    676  3   0x4000082  poll          gsd-xsettings
 28946  515862   7192    676  3   0x4000082  poll          gsd-xsettings
 96852  266707   7192    676  3        0x82  poll          gsd-clipboard
 96852  219968   7192    676  3   0x4000082  poll          gsd-clipboard
 96852  479618   7192    676  3   0x4000082  poll          gsd-clipboard
 99796  179549   7192    676  3        0x82  poll          gsd-smartcard
 99796  365356   7192    676  3   0x4000082  poll          gsd-smartcard
 99796  376054   7192    676  3   0x4000082  poll          gsd-smartcard
 99796  466751   7192    676  3   0x4000082  poll          gsd-smartcard
 99796   75130   7192    676  3   0x4000082  poll          gsd-smartcard
 65830   95026   7192    676  3        0x82  poll          gsd-dummy
 65830  515661   7192    676  3   0x4000082  poll          gsd-dummy
 65830  302791   7192    676  3   0x4000082  poll          gsd-dummy
  2689  351532      1    731  3        0x82  poll          geoclue
  2689  201008      1    731  3   0x4000082  poll          geoclue
  2689  481778      1    731  3   0x4000082  poll          geoclue
 53863   51621      1    676  3        0x82  poll          ibus-x11
 53863  506676      1    676  3   0x4000082  poll          ibus-x11
 53863  172528      1    676  3   0x4000082  poll          ibus-x11
 61513  502416  28717    676  3        0x82  poll          ibus-dconf
 61513  187878  28717    676  3   0x4000082  poll          ibus-dconf
 61513  446759  28717    676  3   0x4000082  poll          ibus-dconf
 61513  355355  28717    676  3   0x4000082  poll          ibus-dconf
 28717  466426  97199    676  3        0x82  poll          ibus-daemon
 28717  490424  97199    676  3   0x4000082  poll          ibus-daemon
 28717  523141  97199    676  3   0x4000082  poll          ibus-daemon
 97199  176007   7192    676  3    0x200082  poll          gnome-shell
 97199   35304   7192    676  3   0x4200082  poll          gnome-shell
 97199  443700   7192    676  3   0x4200082  poll          gnome-shell
 97199  252374   7192    676  3   0x4200082  poll          gnome-shell
 97199   71948   7192    676  3   0x4200082  fsleep        gnome-shell
 97199  365254   7192    676  3   0x4200082  fsleep        gnome-shell
 97199  324085   7192    676  3   0x4200082  fsleep        gnome-shell
 97199  269366   7192    676  3   0x4200082  fsleep        gnome-shell
 97436  299333      1    676  3        0x82  poll          at-spi2-registry
 97436  201250      1    676  3   0x4000082  poll          at-spi2-registry
 97436   73422      1    676  3   0x4000082  poll          at-spi2-registry
 32711  116031  11426    676  3        0x82  poll          dbus-daemon
 11426  359967      1    676  3        0x82  poll          at-spi-bus-launc
 11426   47860      1    676  3   0x4000082  poll          at-spi-bus-launc
 11426  464903      1    676  3   0x4000082  poll          at-spi-bus-launc
 11426  125877      1    676  3   0x4000082  poll          at-spi-bus-launc
  7192  347673  15800    676  3        0x82  poll          gnome-session-bi
  7192  391935  15800    676  3   0x4000082  poll          gnome-session-bi
  7192   52295  15800    676  3   0x4000082  poll          gnome-session-bi
  7192  395314  15800    676  3   0x4000082  poll          gnome-session-bi
 41404  486445  15800    676  3        0x82  poll          dbus-daemon
 15800  114253   7848    676  3        0x82  wait          dbus-run-session
  7848  256177   8366      0  3        0x93  poll          gdm-session-work
  7848   97942   8366      0  3   0x4000093  poll          gdm-session-work
  7848  298114   8366      0  3   0x4000093  poll          gdm-session-work
 88090  300695      1    676  3        0x82  poll          gsd-dummy
 88090  333774      1    676  3   0x4000082  poll          gsd-dummy
 32594  365482      1    676  3        0x82  poll          gsd-color
 32594  211701      1    676  3   0x4000082  poll          gsd-color
 32594  125018      1    676  3   0x4000082  poll          gsd-color
 32594  187181      1    676  3   0x4000082  poll          gsd-color
 23861  406695  27363      0  3    0x100080  netio         Xorg
 58717  122742      1    676  3        0x82  poll          gsd-keyboard
 58717   19447      1    676  3   0x4000082  poll          gsd-keyboard
 58717  105645      1    676  3   0x4000082  poll          gsd-keyboard
 58717  367805      1    676  3   0x4000082  poll          gsd-keyboard
 79795  493246      1    676  3        0x82  poll          gsd-power
 79795  279601      1    676  3   0x4000082  poll          gsd-power
 79795  401758      1    676  3   0x4000082  poll          gsd-power
 79795  444362      1    676  3   0x4000082  poll          gsd-power
 27363  124919   8366     35  3        0x92  poll          Xorg
 27363   67079   8366     35  3   0x4000092  poll          Xorg
  8366  240549      1      0  3        0x83  poll          gdm
  8366  200222      1      0  3   0x4000083  poll          gdm
  8366  393917      1      0  3   0x4000083  poll          gdm
 91848  116283  93979     27  3    0x100090  select        sshd
 93979  240069  88031      0  3        0x82  poll          sshd
 43610  193528  22578     73  7    0x100090                syslogd
 22578  326956      1      0  3    0x100082  netio         syslogd
 66671  351191  43055      0  3    0x100083  ttyin         ksh
 43055  192614  48867   1000  3    0x10008b  pause         ksh
 48867   10020  77853   1000  3        0x90  select        sshd
 77853  278572  88031      0  3        0x82  poll          sshd
 10651  520347      1    694  3        0x82  poll          colord
 10651  246965      1    694  3   0x4000082  poll          colord
 10651  170893      1    694  3   0x4000082  poll          colord
 15356  355185      1      0  3        0x82  poll          upowerd
 15356  116038      1      0  3   0x4000082  poll          upowerd
 15356  280477      1      0  3   0x4000082  poll          upowerd
 63772  120040      1      0  3        0x82  poll          gam_server
 72962  414241  72981      0  3        0x90  poll          smbd
 72962  242171  72981      0  3   0x4000090  fsleep        smbd
 72962   87756  72981      0  3   0x4000090  fsleep        smbd
 72962  522687  72981      0  3   0x4000090  fsleep        smbd
 72962    1046  72981      0  3   0x4000090  fsleep        smbd
 72962  511185  72981      0  3   0x4000090  fsleep        smbd
 72962  192214  72981      0  3   0x4000090  fsleep        smbd
 72962  136644  72981      0  3   0x4000090  fsleep        smbd
 72962  403374  72981      0  3   0x4000090  fsleep        smbd
 45230  501178      1      0  3        0x82  poll          console-kit-daem
 45230  177283      1      0  3   0x4000082  poll          console-kit-daem
 45230  209315      1      0  3   0x4000082  poll          console-kit-daem
 45230  263261      1      0  3   0x4000082  fsleep        console-kit-daem
 45230   26296      1      0  3   0x4000082  wswait2       console-kit-daem
 45230   62756      1      0  3   0x4000082  wswait2       console-kit-daem
 45230  392866      1      0  3   0x4000082  wswait2       console-kit-daem
 45230  248938      1      0  3   0x4000082  wswait2       console-kit-daem
 75605  397790      1    701  3        0x92  poll          polkitd
 75605   53443      1    701  3   0x4000092  poll          polkitd
 75605  128845      1    701  3   0x4000092  poll          polkitd
 75605  107532      1    701  3   0x4000092  fsleep        polkitd
 75605  519693      1    701  3   0x4000092  fsleep        polkitd
 75605  198894      1    701  3   0x4000092  fsleep        polkitd
 75605  191359      1    701  3   0x4000092  fsleep        polkitd
 75605   88560      1    701  3   0x4000092  poll          polkitd
 61337  357953      1      0  3    0x100083  ttyin         ksh
 89131  311523      1      0  3    0x100083  ttyin         getty
 29833  203820      1      0  3    0x100083  ttyin         getty
 82581   38783      1      0  3    0x100083  ttyin         getty
 17115  449929      1      0  3    0x100083  ttyin         getty
 71910  392355      1      0  3    0x100083  ttyin         getty
 50547  297408      1      0  3    0x100098  poll          cron
 65696  188968      1      0  3        0x82  poll          accounts-daemon
 65696  343033      1      0  3   0x4000082  poll          accounts-daemon
 65696  339943      1      0  3   0x4000082  poll          accounts-daemon
 69076  478143      1    629  3        0x90  poll          avahi-daemon
 50039   93309      1    572  3        0x90  poll          dbus-daemon
  8789  218099      1    662  3        0x80  kqread        transmission-dae
  8789  223017      1    662  3   0x4000080  piperd        transmission-dae
  8789  223699      1    662  3   0x4000000  biowait       transmission-dae
  8789  262829      1    662  3   0x4000080  select        transmission-dae
 34510   15761      1      0  3        0x80  poll          nmbd
 55392  278771  72981      0  3        0x80  poll          smbd
 61387  462470  72981      0  3        0x80  poll          smbd
 72981  295547      1      0  3        0x90  poll          smbd
 23685  459513      1     99  3    0x100090  poll          sndiod
 78034  153512      1    110  3    0x100090  poll          sndiod
  9936   20617  58351     95  3    0x100092  kqread        smtpd
 16193   62843  58351    103  3    0x100092  kqread        smtpd
 26280  339269  58351     95  3    0x100092  kqread        smtpd
 37088  465803  58351     95  3    0x100092  kqread        smtpd
  5448  128200  58351     95  3    0x100092  kqread        smtpd
  6202  450068  58351     95  3    0x100092  kqread        smtpd
 58351  357432      1      0  3    0x100080  kqread        smtpd
 88031  212573      1      0  3        0x80  select        sshd
*33527  150820      1      0  7    0x100000                ntpd
 56611  486091  19105     83  3    0x100092  poll          ntpd
 19105  392879      1     83  3    0x100092  poll          ntpd
 29600  312229  81998    115  3    0x100092  kqread        slaacd
 20314  507855  81998    115  3    0x100092  kqread        slaacd
 81998  409869      1      0  3    0x100080  kqread        slaacd
 84048   44793      0      0  3     0x14280  schto         i915/signal:2
  8772  265525      0      0  3     0x14280  schto         i915/signal:1
 89671  479308      0      0  3     0x14280  schto         i915/signal:0
 43183  262681      0      0  3     0x14200  bored         i915-userptr-acq
 56303  343455      0      0  3     0x14200  bored         i915_modeset
 84445   94755      0      0  3     0x14200  bored         i915-dp
 10117  194175      0      0  3     0x14200  bored         i915
 30261  253356      0      0  3     0x14200  pgzero        zerothread
 47355  451614      0      0  3     0x14200  aiodoned      aiodoned
 18091   53688      0      0  3     0x14200  syncer        update
 72138  341644      0      0  3     0x14200  cleaner       cleaner
 84849  336789      0      0  3     0x14200  reaper        reaper
  7754  270800      0      0  3     0x14200  pgdaemon      pagedaemon
 39526  416591      0      0  3     0x14200  bored         crynlk
 78716     537      0      0  3     0x14200  bored         crypto
 60391  519576      0      0  3     0x14200  usbtsk        usbtask
 25433  379960      0      0  3     0x14200  usbatsk       usbatsk
 32931  407272      0      0  3     0x14200  bored         drmtskl
  8130  414115      0      0  3     0x14200  bored         drmlwq
 74293  246133      0      0  3     0x14200  bored         drmubwq
 55332  419367      0      0  3     0x14200  bored         drmwq
 78463  445177      0      0  3  0x40014200  acpi0         acpi0
 52266  297807      0      0  3  0x40014200                idle3
  4541   18001      0      0  7  0x40014200                idle2
 48855  126894      0      0  3  0x40014200                idle1
 55257  212055      0      0  3     0x14200  bored         sensors
 32311  481883      0      0  7     0x14200                softnet
 60446  250039      0      0  3     0x14200  bored         systqmp
  6593  300843      0      0  3     0x14200  bored         systq
 67683  133634      0      0  3  0x40014200  bored         softclock
 83716  281224      0      0  3  0x40014200                idle0
 44271   27743      0      0  3     0x14200  bored         smr
     1  132074      0      0  3        0x82  wait          init
     0       0     -1      0  3     0x10200  scheduler     swapper
ddb{1}>


dmesg
OpenBSD 6.5-current (GENERIC.MP) #10: Mon Jun  3 13:42:48 CEST 2019
    [hidden email]:/sys/arch/amd64/compile/GENERIC.MP
real mem = 8456089600 (8064MB)
avail mem = 8121155584 (7744MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0: SMBIOS rev. 2.7 @ 0xe87b1 (86 entries)
bios0: vendor Hewlett-Packard version "J01 v02.29" date 04/04/2016
bios0: Hewlett-Packard HP Compaq 8200 Elite CMT PC
acpi0 at bios0: rev 2
acpi0: sleep states S0 S3 S4 S5
acpi0: tables DSDT FACP APIC SSDT MCFG HPET SSDT SLIC TCPA
acpi0: wakeup devices PS2K(S3) PS2M(S3) BR20(S4) EUSB(S3) USBE(S3)
PEX0(S4) PEX
1(S4) PEX2(S4) PEX3(S4) PEX4(S4) PEX5(S4) PEX6(S4) PEX7(S4) P0P1(S4)
P0P2(S4) P
0P3(S4) [...]
acpitimer0 at acpi0: 3579545 Hz, 24 bits
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel(R) Core(TM) i5-2500 CPU @ 3.30GHz, 3293.10 MHz, 06-2a-07
cpu0:
FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,C
FLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,V
MX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,A
ES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,
SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu0: 256KB 64b/line 8-way L2 cache
cpu0: smt 0, core 0, package 0
mtrr: Pentium Pro MTRR support, 10 var ranges, 88 fixed ranges
cpu0: apic clock running at 99MHz
cpu0: mwait min=64, max=64, C-substates=0.2.1.1, IBE
cpu1 at mainbus0: apid 2 (application processor)
cpu1: Intel(R) Core(TM) i5-2500 CPU @ 3.30GHz, 3292.53 MHz, 06-2a-07
cpu1:
FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,C
FLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,V
MX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,A
ES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,
SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu1: 256KB 64b/line 8-way L2 cache
cpu1: smt 0, core 1, package 0
cpu2 at mainbus0: apid 4 (application processor)
cpu2: Intel(R) Core(TM) i5-2500 CPU @ 3.30GHz, 3292.53 MHz, 06-2a-07
cpu2:
FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,C
FLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,V
MX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,A
ES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,
SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu2: 256KB 64b/line 8-way L2 cache
cpu2: smt 0, core 2, package 0
cpu3 at mainbus0: apid 6 (application processor)
cpu3: Intel(R) Core(TM) i5-2500 CPU @ 3.30GHz, 3292.53 MHz, 06-2a-07
cpu3:
FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,C
FLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,V
MX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,A
ES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,
SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu3: 256KB 64b/line 8-way L2 cache
cpu3: smt 0, core 3, package 0
ioapic0 at mainbus0: apid 0 pa 0xfec00000, version 20, 24 pins
acpimcfg0 at acpi0
acpimcfg0: addr 0xe0000000, bus 0-255
acpihpet0 at acpi0: 14318179 Hz
acpiprt0 at acpi0: bus 0 (PCI0)
acpiprt1 at acpi0: bus 5 (BR20)
acpiprt2 at acpi0: bus 1 (PEX0)
acpiprt3 at acpi0: bus -1 (PEX1)
acpiprt4 at acpi0: bus -1 (PEX2)
acpiprt5 at acpi0: bus -1 (PEX3)
acpiprt6 at acpi0: bus 2 (PEX4)
acpiprt7 at acpi0: bus -1 (PEX5)
acpiprt8 at acpi0: bus 3 (PEX6)
acpiprt9 at acpi0: bus 4 (PEX7)
acpiprt10 at acpi0: bus -1 (P0P1)
acpiprt11 at acpi0: bus -1 (P0P2)
acpiprt12 at acpi0: bus -1 (P0P3)
acpiprt13 at acpi0: bus -1 (P0P4)
acpicpu0 at acpi0: C1(1000@1 halt), PSS
acpicpu1 at acpi0: C1(1000@1 halt), PSS
acpicpu2 at acpi0: C1(1000@1 halt), PSS
acpicpu3 at acpi0: C1(1000@1 halt), PSS
acpipci0 at acpi0 PCI0: 0x00000010 0x00000011 0x00000000
acpicmos0 at acpi0
tpm0 at acpi0: TPM_ addr 0xfed40000/0x5000, Infineon SLB9635 1.2 rev 0x10
acpibtn0 at acpi0: PWRB
"PNP0C14" at acpi0 not configured
ipmi at mainbus0 not configured
cpu0: using VERW MDS workaround (except on vmm entry)
cpu0: Enhanced SpeedStep 3293 MHz: speeds: 3301, 3300, 3100, 2900, 2700,
2500, 2
300, 2100, 1900, 1700, 1600 MHz
pci0 at mainbus0 bus 0
pchb0 at pci0 dev 0 function 0 "Intel Core 2G Host" rev 0x09
inteldrm0 at pci0 dev 2 function 0 "Intel HD Graphics 2000" rev 0x09
drm0 at inteldrm0
inteldrm0: msi
em0 at pci0 dev 25 function 0 "Intel 82579LM" rev 0x04: msi, address
78:ac:c0:b
a:4a:7c
ehci0 at pci0 dev 26 function 0 "Intel 6 Series USB" rev 0x04: apic 0 int 16
usb0 at ehci0: USB revision 2.0
uhub0 at usb0 configuration 1 interface 0 "Intel EHCI root hub" rev
2.00/1.00 a
ddr 1
azalia0 at pci0 dev 27 function 0 "Intel 6 Series HD Audio" rev 0x04: msi
azalia0: codecs: Realtek ALC662, Intel/0x2805, using Realtek ALC662
audio0 at azalia0
ppb0 at pci0 dev 28 function 0 "Intel 6 Series PCIE" rev 0xb4: msi
pci1 at ppb0 bus 1
ppb1 at pci0 dev 28 function 4 "Intel 6 Series PCIE" rev 0xb4: msi
pci2 at ppb1 bus 2
ppb2 at pci0 dev 28 function 6 "Intel 6 Series PCIE" rev 0xb4: msi
pci3 at ppb2 bus 3
ppb3 at pci0 dev 28 function 7 "Intel 6 Series PCIE" rev 0xb4: msi
pci4 at ppb3 bus 4
ehci1 at pci0 dev 29 function 0 "Intel 6 Series USB" rev 0x04: apic 0 int 23
usb1 at ehci1: USB revision 2.0
uhub1 at usb1 configuration 1 interface 0 "Intel EHCI root hub" rev
2.00/1.00 a
ddr 1
ppb4 at pci0 dev 30 function 0 "Intel 82801BA Hub-to-PCI" rev 0xa4
pci5 at ppb4 bus 5
pcib0 at pci0 dev 31 function 0 "Intel Q67 LPC" rev 0x04
ahci0 at pci0 dev 31 function 2 "Intel 6 Series AHCI" rev 0x04: msi,
AHCI 1.3
ahci0: port 0: 6.0Gb/s
ahci0: port 1: 6.0Gb/s
ahci0: port 2: 1.5Gb/s
ahci0: port 3: 3.0Gb/s
scsibus1 at ahci0: 32 targets
sd0 at scsibus1 targ 0 lun 0: <ATA, WDC WD1000DHTZ-0, 04.0> SCSI3
0/direct fixe
d naa.50014ee7aaada488
sd0: 953869MB, 512 bytes/sector, 1953525168 sectors
sd1 at scsibus1 targ 1 lun 0: <ATA, WDC WD30EZRX-00D, 80.0> SCSI3
0/direct fixe
d naa.50014ee25e87ef8e
sd1: 2861588MB, 512 bytes/sector, 5860533168 sectors
cd0 at scsibus1 targ 2 lun 0: <hp, DVD A DH16ABLH, 3HD9> ATAPI 5/cdrom
removabl
e
sd2 at scsibus1 targ 3 lun 0: <ATA, ST3500413AS, HP61> SCSI3 0/direct
fixed naa
.5000c5002db8bf5e
sd2: 476940MB, 512 bytes/sector, 976773168 sectors
ichiic0 at pci0 dev 31 function 3 "Intel 6 Series SMBus" rev 0x04: apic
0 int 1
8
iic0 at ichiic0
spdmem0 at iic0 addr 0x50: 2GB DDR3 SDRAM PC3-10600
spdmem1 at iic0 addr 0x51: 2GB DDR3 SDRAM PC3-10600
spdmem2 at iic0 addr 0x52: 2GB DDR3 SDRAM PC3-10600
spdmem3 at iic0 addr 0x53: 2GB DDR3 SDRAM PC3-10600
isa0 at pcib0
isadma0 at isa0
com0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
com0: console
pckbc0 at isa0 port 0x60/5 irq 1 irq 12
pckbd0 at pckbc0 (kbd slot)
wskbd0 at pckbd0: console keyboard
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
uhub2 at uhub0 port 1 configuration 1 interface 0 "Intel Rate Matching
Hub" rev
 2.00/0.00 addr 2
uhidev0 at uhub2 port 3 configuration 1 interface 0 "Logitech Optical
USB Mouse
" rev 2.00/3.40 addr 3
uhidev0: iclass 3/1
ums0 at uhidev0: 3 buttons, Z dir
wsmouse0 at ums0 mux 0
uhidev1 at uhub2 port 5 configuration 1 interface 0 "LITE-ON Technology
USB Net
Vista Full Width Keyboard." rev 1.10/1.09 addr 4
uhidev1: iclass 3/1
ukbd0 at uhidev1: 8 variable keys, 6 key codes
wskbd1 at ukbd0 mux 1
uhub3 at uhub1 port 1 configuration 1 interface 0 "Intel Rate Matching
Hub" rev
 2.00/0.00 addr 2
vscsi0 at root
scsibus2 at vscsi0: 256 targets
softraid0 at root
scsibus3 at softraid0: 256 targets
root on sd2a (9fc0acceac93c3a4.a) swap on sd2b dump on sd2b
inteldrm0: 1920x1080, 32bpp
wsdisplay0 at inteldrm0 mux 1: console (std, vt100 emulation), using wskbd0
wskbd1: connecting to wsdisplay0
wsdisplay0: screen 1-5 added (std, vt100 emulation)

Reply | Threaded
Open this post in threaded view
|

Re: witness report

Philip Guenther-3
On Mon, 3 Jun 2019, Hrvoje Popovski wrote:
> i'm having samba server, transmission client and gnome desktop on one
> box. from time to time i'm getting witness log below. source is clean
> and fetched few hours ago and compiled with WITNESS. userland and
> packages are up to date ..
> i put kern.witness.watch=3 in sysctl.conf so now i'm in ddb and will
> leave it like this if something is needed

From [hidden email] Sat Jun  1 13:25:04 2019
Date: Sat, 1 Jun 2019 13:25:00 -0700
From: Philip Guenther <[hidden email]>
To: Antoine Jacoutot <[hidden email]>
Cc: [hidden email]
Subject: Re: witness and unveil

On Sat, 1 Jun 2019, Antoine Jacoutot wrote:
> Running a WITNESS kernel, mpi@ told me to send this here.
>
> kern.version=OpenBSD 6.5-current (GENERIC.MP) #0: Sat Jun  1 18:29:16 CEST 2019
>
> witness: acquiring duplicate lock of same type: "&to->uv_lock"
>  1st unveil
>  2nd unveil

Give this diff a try.

Philip

Index: kern_unveil.c
===================================================================
RCS file: /data/src/openbsd/src/sys/kern/kern_unveil.c,v
retrieving revision 1.25
diff -u -p -r1.25 kern_unveil.c
--- kern_unveil.c 26 Mar 2019 13:41:40 -0000 1.25
+++ kern_unveil.c 1 Jun 2019 20:17:47 -0000
@@ -124,19 +124,25 @@ unveil_delete_names(struct unveil *uv)
 }
 
 void
-unveil_add_name(struct unveil *uv, char *name, u_char flags)
+unveil_add_name_unlocked(struct unveil *uv, char *name, u_char flags)
 {
  struct unvname *unvn;
 
- rw_enter_write(&uv->uv_lock);
  unvn = unvname_new(name, strlen(name) + 1, flags);
  RBT_INSERT(unvname_rbt, &uv->uv_names, unvn);
- rw_exit_write(&uv->uv_lock);
 #ifdef DEBUG_UNVEIL
  printf("added name %s underneath vnode %p\n", name, uv->uv_vp);
 #endif
 }
 
+void
+unveil_add_name(struct unveil *uv, char *name, u_char flags)
+{
+ rw_enter_write(&uv->uv_lock);
+ unveil_add_name_unlocked(uv, name, flags);
+ rw_exit_write(&uv->uv_lock);
+}
+
 struct unvname *
 unveil_namelookup(struct unveil *uv, char *name)
 {
@@ -228,8 +234,8 @@ unveil_copy(struct process *parent, stru
  RBT_INIT(unvname_rbt, &to->uv_names);
  rw_enter_read(&from->uv_lock);
  RBT_FOREACH_SAFE(unvn, unvname_rbt, &from->uv_names, next) {
- unveil_add_name(&child->ps_uvpaths[i], unvn->un_name,
-    unvn->un_flags);
+ unveil_add_name_unlocked(&child->ps_uvpaths[i],
+    unvn->un_name, unvn->un_flags);
  child->ps_uvncount++;
  }
  rw_exit_read(&from->uv_lock);

Reply | Threaded
Open this post in threaded view
|

Re: witness report

Hrvoje Popovski
On 3.6.2019. 18:32, Philip Guenther wrote:

> On Mon, 3 Jun 2019, Hrvoje Popovski wrote:
>> i'm having samba server, transmission client and gnome desktop on one
>> box. from time to time i'm getting witness log below. source is clean
>> and fetched few hours ago and compiled with WITNESS. userland and
>> packages are up to date ..
>> i put kern.witness.watch=3 in sysctl.conf so now i'm in ddb and will
>> leave it like this if something is needed
>
> From [hidden email] Sat Jun  1 13:25:04 2019
> Date: Sat, 1 Jun 2019 13:25:00 -0700
> From: Philip Guenther <[hidden email]>
> To: Antoine Jacoutot <[hidden email]>
> Cc: [hidden email]
> Subject: Re: witness and unveil
>
> On Sat, 1 Jun 2019, Antoine Jacoutot wrote:
>> Running a WITNESS kernel, mpi@ told me to send this here.
>>
>> kern.version=OpenBSD 6.5-current (GENERIC.MP) #0: Sat Jun  1 18:29:16 CEST 2019
>>
>> witness: acquiring duplicate lock of same type: "&to->uv_lock"
>>  1st unveil
>>  2nd unveil
>
> Give this diff a try.

Hi,

with this diff i can't reproduce witness log. if something comes up i
will report it back ..