0 0 424 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 1 0 776 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 2 0 408 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 3 0 624 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 4 0 408 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 5 0 648 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 6 0 408 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 7 0 664 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 8 0 404 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 9 0 764 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 10 0 512 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 11 0 436 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 12 0 548 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 13 0 616 msleep: thread 0xc26ac160 (pid 30, yarrow) on - (0xc0884d40) 14 0 428 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 15 0 684 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 16 0 452 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 17 0 624 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 18 0 876 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 19 0 600 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 20 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 21 0 3812 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 22 0 780 mi_switch: old thread 0xc26ac160 (pid 30, yarrow) 23 0 424 mi_switch: new thread 0xc2663580 (pid 11, idle) 24 0 8139424 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 25 0 18900 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 26 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 27 0 10182720 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 28 0 5584 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 29 0 600 swi_sched pid 28(swi5: clock sio) need=0 30 0 504 ithread_schedule: pid 28: (swi5: clock sio) need = 0 31 0 392 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 32 0 624 ithread_schedule: setrunqueue 28 33 0 808 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 34 0 2748 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 35 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 36 0 2992 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 37 0 920 mi_switch: old thread 0xc2663580 (pid 11, idle) 38 0 420 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 39 0 380 ithread_loop: pid 28: resumed 40 0 568 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 41 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 42 0 500 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 43 0 472 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 44 0 724 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 45 0 436 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 46 0 672 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 47 0 476 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 48 0 632 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 49 0 580 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 50 0 464 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 51 0 380 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 52 0 592 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 53 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 54 0 704 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 55 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 56 0 2716 ithread_loop: pid 28: done 57 0 888 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 58 0 368 mi_switch: new thread 0xc2663580 (pid 11, idle) 59 0 8456300 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 60 0 18836 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 61 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 62 0 15417984 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 63 0 5312 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 64 0 508 swi_sched pid 28(swi5: clock sio) need=0 65 0 436 ithread_schedule: pid 28: (swi5: clock sio) need = 0 66 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 67 0 628 ithread_schedule: setrunqueue 28 68 0 776 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 69 0 2416 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 70 0 1516 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 71 0 2944 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 72 0 952 mi_switch: old thread 0xc2663580 (pid 11, idle) 73 0 424 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 74 0 376 ithread_loop: pid 28: resumed 75 0 544 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 76 0 580 ithread_loop: pid 28: (swi5: clock sio) need=1 77 0 448 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 78 0 484 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 79 0 648 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 80 0 916 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 81 0 548 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:423 82 0 436 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:488 83 0 496 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:515 84 0 640 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:435 85 0 456 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 86 0 504 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 87 0 704 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 88 0 768 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 89 0 720 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 90 0 444 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 91 0 632 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 92 0 544 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 93 0 464 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 94 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 95 0 636 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 96 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 97 0 708 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 98 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 99 0 2992 ithread_loop: pid 28: done 100 0 824 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 101 0 376 mi_switch: new thread 0xc2663580 (pid 11, idle) 102 0 3216904 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 103 0 18760 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 104 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 105 0 18669064 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 106 0 18752 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 107 0 4484 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 108 0 1960876 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 109 0 5308 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 110 0 544 swi_sched pid 28(swi5: clock sio) need=0 111 0 432 ithread_schedule: pid 28: (swi5: clock sio) need = 0 112 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 113 0 648 ithread_schedule: setrunqueue 28 114 0 720 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 115 0 2712 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 116 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 117 0 3528 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 118 0 896 mi_switch: old thread 0xc2663580 (pid 11, idle) 119 0 416 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 120 0 360 ithread_loop: pid 28: resumed 121 0 580 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 122 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 123 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 124 0 820 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 125 0 1372 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 126 0 408 sleepq_timeout: thread 0xc2ba16e0 (pid 471, sendmail) 127 0 704 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 128 0 1004 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 129 0 956 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 130 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 131 0 1688 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 132 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 133 0 512 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 134 0 736 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 135 0 356 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 136 0 2076 sleepq_wakeup: thread 0xc2ba16e0 (pid 471, sendmail) 137 0 832 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 138 0 520 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 139 0 708 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 140 0 836 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 141 0 836 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 142 0 492 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 143 0 620 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 144 0 556 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 145 0 460 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 146 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 147 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 148 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 149 0 708 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 150 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 151 0 2752 ithread_loop: pid 28: done 152 0 5272 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 153 0 904 mi_switch: new thread 0xc2ba16e0 (pid 471, sendmail) 154 0 1336 sleepq resume: thread 0xc2ba16e0 (pid 471, sendmail) 155 0 1372 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:562 156 0 616 LOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_condvar.c:322 157 0 796 UNLOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_condvar.c:325 158 0 952 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_condvar.c:331 159 0 3228 LOCK (sleep mutex) sellck r = 0 at ../../../kern/kern_condvar.c:332 160 0 720 LOCK (spin mutex) sched lock r = 0 at ../../../kern/sys_generic.c:757 161 0 576 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/sys_generic.c:759 162 0 2124 UNLOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:760 163 0 18200 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/sys_generic.c:782 164 0 1132 LOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_sig.c:634 165 0 17976 UNLOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_sig.c:662 166 0 2864 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_sysctl.c:1255 167 0 5188 XLOCK (sx) user map r = 0 at ../../../vm/vm_glue.c:175 168 0 1344 XUNLOCK (sx) user map r = 0 at ../../../vm/vm_glue.c:178 169 0 4556 XLOCK (sx) sysctl lock r = 0 at ../../../kern/kern_sysctl.c:1315 170 0 732 XUNLOCK (sx) sysctl lock r = 0 at ../../../kern/kern_sysctl.c:1326 171 0 54892 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_sysctl.c:1268 172 0 1248 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_sysctl.c:1255 173 0 944 XLOCK (sx) user map r = 0 at ../../../vm/vm_glue.c:175 174 0 596 XUNLOCK (sx) user map r = 0 at ../../../vm/vm_glue.c:178 175 0 1140 XLOCK (sx) sysctl lock r = 0 at ../../../kern/kern_sysctl.c:1315 176 0 676 XUNLOCK (sx) sysctl lock r = 0 at ../../../kern/kern_sysctl.c:1326 177 0 11972 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_sysctl.c:1268 178 0 820 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_sysctl.c:1255 179 0 564 XLOCK (sx) user map r = 0 at ../../../vm/vm_glue.c:175 180 0 448 XUNLOCK (sx) user map r = 0 at ../../../vm/vm_glue.c:178 181 0 836 XLOCK (sx) sysctl lock r = 0 at ../../../kern/kern_sysctl.c:1315 182 0 536 XUNLOCK (sx) sysctl lock r = 0 at ../../../kern/kern_sysctl.c:1326 183 0 20556 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_sysctl.c:1268 184 0 2108 LOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_sig.c:634 185 0 35932 UNLOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_sig.c:662 186 0 868 LOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_prot.c:89 187 0 3124 UNLOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_prot.c:91 188 0 832 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_sysctl.c:1255 189 0 1068 XLOCK (sx) user map r = 0 at ../../../vm/vm_glue.c:175 190 0 560 XUNLOCK (sx) user map r = 0 at ../../../vm/vm_glue.c:178 191 0 10136 XLOCK (sx) sysctl lock r = 0 at ../../../kern/kern_sysctl.c:1315 192 0 1376 SLOCK (sx) allproc r = 0 at ../../../kern/kern_proc.c:253 193 0 1016 LOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_proc.c:256 194 0 4880 SUNLOCK (sx) allproc r = 0 at ../../../kern/kern_proc.c:259 195 0 852 LOCK (sleep mutex) struct pargs.ref r = 0 at ../../../kern/kern_proc.c:1100 196 0 496 UNLOCK (sleep mutex) struct pargs.ref r = 0 at ../../../kern/kern_proc.c:1102 197 0 428 UNLOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_proc.c:1153 198 0 460 LOCK (sleep mutex) struct pargs.ref r = 0 at ../../../kern/kern_proc.c:1111 199 0 3324 UNLOCK (sleep mutex) struct pargs.ref r = 0 at ../../../kern/kern_proc.c:1116 200 0 1420 uma_zalloc_arg thread c2ba16e0 zone 64 flags 2 201 0 968 LOCK (sleep mutex) UMA pcpu r = 0 at ../../../vm/uma_core.c:1800 202 0 1824 UNLOCK (sleep mutex) UMA pcpu r = 0 at ../../../vm/uma_core.c:1821 203 0 460 LOCK (sleep mutex) proc-args r = 0 at ../../../kern/kern_malloc.c:185 204 0 1184 UNLOCK (sleep mutex) proc-args r = 0 at ../../../kern/kern_malloc.c:195 205 0 412 LOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_proc.c:1168 206 0 412 UNLOCK (sleep mutex) process lock r = 0 at ../../../kern/kern_proc.c:1171 207 0 408 LOCK (sleep mutex) struct pargs.ref r = 0 at ../../../kern/kern_proc.c:1111 208 0 2792 UNLOCK (sleep mutex) struct pargs.ref r = 0 at ../../../kern/kern_proc.c:1113 209 0 1120 uma_zfree_arg thread c2ba16e0 zone 64 210 0 832 LOCK (sleep mutex) UMA pcpu r = 0 at ../../../vm/uma_core.c:2206 211 0 848 UNLOCK (sleep mutex) UMA pcpu r = 0 at ../../../vm/uma_core.c:2231 212 0 484 LOCK (sleep mutex) proc-args r = 0 at ../../../kern/kern_malloc.c:210 213 0 636 UNLOCK (sleep mutex) proc-args r = 0 at ../../../kern/kern_malloc.c:216 214 0 580 XUNLOCK (sx) sysctl lock r = 0 at ../../../kern/kern_sysctl.c:1326 215 0 4588 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_sysctl.c:1268 216 0 1680 LOCK (sleep mutex) Giant r = 0 at ../../../kern/sys_generic.c:647 217 0 368 LOCK (sleep mutex) filedesc structure r = 0 at ../../../kern/sys_generic.c:648 218 0 2240 UNLOCK (sleep mutex) filedesc structure r = 0 at ../../../kern/sys_generic.c:652 219 0 508 LOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:712 220 0 444 LOCK (spin mutex) sched lock r = 0 at ../../../kern/sys_generic.c:715 221 0 564 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/sys_generic.c:717 222 0 480 UNLOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:718 223 0 3624 LOCK (sleep mutex) filedesc structure r = 0 at ../../../kern/sys_generic.c:800 224 0 464 LOCK (sleep mutex) so_rcv r = 0 at ../../../kern/uipc_socket.c:2239 225 0 424 LOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:1056 226 0 404 UNLOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:1074 227 0 1728 UNLOCK (sleep mutex) so_rcv r = 0 at ../../../kern/uipc_socket.c:2242 228 0 444 LOCK (sleep mutex) so_rcv r = 0 at ../../../kern/uipc_socket.c:2239 229 0 408 LOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:1056 230 0 380 UNLOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:1074 231 0 1448 UNLOCK (sleep mutex) so_rcv r = 0 at ../../../kern/uipc_socket.c:2242 232 0 400 LOCK (sleep mutex) so_rcv r = 0 at ../../../kern/uipc_socket.c:2239 233 0 396 LOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:1056 234 0 376 UNLOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:1074 235 0 556 UNLOCK (sleep mutex) so_rcv r = 0 at ../../../kern/uipc_socket.c:2242 236 0 428 UNLOCK (sleep mutex) filedesc structure r = 0 at ../../../kern/sys_generic.c:823 237 0 852 LOCK (sleep mutex) sellck r = 0 at ../../../kern/sys_generic.c:721 238 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/sys_generic.c:740 239 0 1564 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/sys_generic.c:745 240 0 468 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 241 0 512 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_condvar.c:307 242 0 5608 UNLOCK (sleep mutex) sellck r = 0 at ../../../kern/kern_condvar.c:308 243 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 244 0 864 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 245 0 1808 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 246 0 720 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 247 0 380 sleepq catching signals: thread 0xc2ba16e0 (pid 471, sendmail) 248 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:348 249 0 712 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:351 250 0 628 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 251 0 876 LOCK (sleep mutex) process lock r = 0 at ../../../kern/subr_sleepqueue.c:355 252 0 484 LOCK (sleep mutex) sigacts r = 0 at ../../../kern/subr_sleepqueue.c:356 253 0 516 UNLOCK (sleep mutex) sigacts r = 0 at ../../../kern/subr_sleepqueue.c:358 254 0 528 UNLOCK (sleep mutex) process lock r = 0 at ../../../kern/subr_sleepqueue.c:363 255 0 496 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 256 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:370 257 0 660 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:375 258 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 259 0 4604 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 260 0 2952 mi_switch: old thread 0xc2ba16e0 (pid 471, sendmail) 261 0 808 mi_switch: new thread 0xc2663580 (pid 11, idle) 262 0 16369788 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 263 0 19128 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 264 0 4436 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 265 0 7195556 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 266 0 5940 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 267 0 604 swi_sched pid 28(swi5: clock sio) need=0 268 0 488 ithread_schedule: pid 28: (swi5: clock sio) need = 0 269 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 270 0 752 ithread_schedule: setrunqueue 28 271 0 716 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 272 0 2368 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 273 0 1620 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 274 0 3344 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 275 0 1304 mi_switch: old thread 0xc2663580 (pid 11, idle) 276 0 420 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 277 0 388 ithread_loop: pid 28: resumed 278 0 612 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 279 0 604 ithread_loop: pid 28: (swi5: clock sio) need=1 280 0 544 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 281 0 972 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 282 0 644 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 283 0 2416 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 284 0 488 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 285 0 7224 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 286 0 428 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 287 0 512 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 288 0 592 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 289 0 1808 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 290 0 412 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 291 0 4340 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 292 0 472 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 293 0 476 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 294 0 792 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 295 0 412 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 296 0 648 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 297 0 408 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 298 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 299 0 612 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 300 0 436 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 301 0 404 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 302 0 640 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 303 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 304 0 1060 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 305 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 306 0 3128 ithread_loop: pid 28: done 307 0 900 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 308 0 376 mi_switch: new thread 0xc2663580 (pid 11, idle) 309 0 11421136 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 310 0 19048 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 311 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 312 0 12430920 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 313 0 4872 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 314 0 520 swi_sched pid 28(swi5: clock sio) need=0 315 0 432 ithread_schedule: pid 28: (swi5: clock sio) need = 0 316 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 317 0 632 ithread_schedule: setrunqueue 28 318 0 720 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 319 0 2412 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 320 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 321 0 2972 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 322 0 908 mi_switch: old thread 0xc2663580 (pid 11, idle) 323 0 424 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 324 0 428 ithread_loop: pid 28: resumed 325 0 580 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 326 0 708 ithread_loop: pid 28: (swi5: clock sio) need=1 327 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 328 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 329 0 744 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 330 0 488 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 331 0 708 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 332 0 488 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 333 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 334 0 508 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 335 0 440 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 336 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 337 0 616 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 338 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 339 0 696 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 340 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 341 0 3080 ithread_loop: pid 28: done 342 0 844 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 343 0 360 mi_switch: new thread 0xc2663580 (pid 11, idle) 344 0 6208972 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 345 0 18832 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 346 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 347 0 17666184 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 348 0 4820 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 349 0 520 swi_sched pid 28(swi5: clock sio) need=0 350 0 436 ithread_schedule: pid 28: (swi5: clock sio) need = 0 351 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 352 0 628 ithread_schedule: setrunqueue 28 353 0 696 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 354 0 2428 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 355 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 356 0 3208 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 357 0 916 mi_switch: old thread 0xc2663580 (pid 11, idle) 358 0 420 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 359 0 376 ithread_loop: pid 28: resumed 360 0 544 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 361 0 608 ithread_loop: pid 28: (swi5: clock sio) need=1 362 0 480 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 363 0 492 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 364 0 632 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 365 0 1304 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 366 0 576 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:423 367 0 436 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:488 368 0 500 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:515 369 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:435 370 0 456 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 371 0 516 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 372 0 692 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 373 0 400 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 374 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 375 0 420 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 376 0 636 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 377 0 544 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 378 0 460 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 379 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 380 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 381 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 382 0 708 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 383 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 384 0 3052 ithread_loop: pid 28: done 385 0 852 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 386 0 380 mi_switch: new thread 0xc2663580 (pid 11, idle) 387 0 968852 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 388 0 18836 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 389 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 390 0 18669036 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 391 0 18704 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 392 0 4484 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 393 0 4209148 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 394 0 4872 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 395 0 520 swi_sched pid 28(swi5: clock sio) need=0 396 0 440 ithread_schedule: pid 28: (swi5: clock sio) need = 0 397 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 398 0 628 ithread_schedule: setrunqueue 28 399 0 720 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 400 0 2616 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 401 0 1456 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 402 0 3668 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 403 0 904 mi_switch: old thread 0xc2663580 (pid 11, idle) 404 0 408 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 405 0 376 ithread_loop: pid 28: resumed 406 0 556 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 407 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 408 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 409 0 552 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 410 0 736 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 411 0 436 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 412 0 728 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 413 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 414 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 415 0 500 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 416 0 436 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 417 0 384 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 418 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 419 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 420 0 692 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 421 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 422 0 3068 ithread_loop: pid 28: done 423 0 840 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 424 0 360 mi_switch: new thread 0xc2663580 (pid 11, idle) 425 0 14430404 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 426 0 18856 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 427 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 428 0 9444144 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 429 0 5052 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 430 0 508 swi_sched pid 28(swi5: clock sio) need=0 431 0 432 ithread_schedule: pid 28: (swi5: clock sio) need = 0 432 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 433 0 640 ithread_schedule: setrunqueue 28 434 0 724 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 435 0 2652 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 436 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 437 0 2996 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 438 0 896 mi_switch: old thread 0xc2663580 (pid 11, idle) 439 0 412 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 440 0 376 ithread_loop: pid 28: resumed 441 0 568 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 442 0 580 ithread_loop: pid 28: (swi5: clock sio) need=1 443 0 448 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 444 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 445 0 752 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 446 0 488 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 447 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 448 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 449 0 628 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 450 0 560 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 451 0 464 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 452 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 453 0 628 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 454 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 455 0 692 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 456 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 457 0 3036 ithread_loop: pid 28: done 458 0 824 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 459 0 364 mi_switch: new thread 0xc2663580 (pid 11, idle) 460 0 9195764 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 461 0 18732 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 462 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 463 0 14679484 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 464 0 5284 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 465 0 508 swi_sched pid 28(swi5: clock sio) need=0 466 0 444 ithread_schedule: pid 28: (swi5: clock sio) need = 0 467 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 468 0 632 ithread_schedule: setrunqueue 28 469 0 732 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 470 0 2508 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 471 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 472 0 3040 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 473 0 908 mi_switch: old thread 0xc2663580 (pid 11, idle) 474 0 424 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 475 0 376 ithread_loop: pid 28: resumed 476 0 580 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 477 0 672 ithread_loop: pid 28: (swi5: clock sio) need=1 478 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 479 0 476 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 480 0 648 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 481 0 408 sleepq_timeout: thread 0xc26ac000 (pid 4, g_down) 482 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 483 0 612 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 484 0 600 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 485 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 486 0 716 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 487 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 488 0 496 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 489 0 688 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 490 0 356 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 491 0 1140 sleepq_wakeup: thread 0xc26ac000 (pid 4, g_down) 492 0 668 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 493 0 500 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 494 0 600 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 495 0 376 sleepq_timeout: thread 0xc266cdc0 (pid 3, g_up) 496 0 348 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 497 0 696 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 498 0 428 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 499 0 348 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 500 0 584 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 501 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 502 0 536 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 503 0 564 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 504 0 332 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 505 0 604 sleepq_wakeup: thread 0xc266cdc0 (pid 3, g_up) 506 0 592 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 507 0 564 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 508 0 720 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 509 0 480 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 510 0 624 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 511 0 412 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 512 0 592 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 513 0 508 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 514 0 440 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 515 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 516 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 517 0 540 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 518 0 704 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 519 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 520 0 3016 ithread_loop: pid 28: done 521 0 908 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 522 0 384 mi_switch: new thread 0xc26ac000 (pid 4, g_down) 523 0 468 sleepq resume: thread 0xc26ac000 (pid 4, g_down) 524 0 1312 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:546 525 0 924 LOCK (sleep mutex) bio queue r = 0 at ../../../geom/geom_io.c:65 526 0 472 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 527 0 396 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 528 0 512 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 529 0 376 msleep: thread 0xc26ac000 (pid 4, g_down) on - (0xc0889ac8) 530 0 552 UNLOCK (sleep mutex) bio queue r = 0 at ../../../kern/kern_synch.c:198 531 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 532 0 608 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 533 0 444 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 534 0 656 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 535 0 420 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 536 0 636 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 537 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 538 0 3772 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 539 0 736 mi_switch: old thread 0xc26ac000 (pid 4, g_down) 540 0 336 mi_switch: new thread 0xc266cdc0 (pid 3, g_up) 541 0 368 sleepq resume: thread 0xc266cdc0 (pid 3, g_up) 542 0 652 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:546 543 0 564 LOCK (sleep mutex) bio queue r = 0 at ../../../geom/geom_io.c:65 544 0 448 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 545 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 546 0 444 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 547 0 368 msleep: thread 0xc266cdc0 (pid 3, g_up) on - (0xc0889ac4) 548 0 504 UNLOCK (sleep mutex) bio queue r = 0 at ../../../kern/kern_synch.c:198 549 0 356 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 550 0 540 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 551 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 552 0 500 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 553 0 380 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 554 0 532 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 555 0 336 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 556 0 2864 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 557 0 880 mi_switch: old thread 0xc266cdc0 (pid 3, g_up) 558 0 380 mi_switch: new thread 0xc2663580 (pid 11, idle) 559 0 3921204 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 560 0 18760 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 561 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 562 0 18669064 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 563 0 18752 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 564 0 4484 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 565 0 1222396 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 566 0 5492 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 567 0 608 swi_sched pid 28(swi5: clock sio) need=0 568 0 444 ithread_schedule: pid 28: (swi5: clock sio) need = 0 569 0 384 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 570 0 656 ithread_schedule: setrunqueue 28 571 0 708 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 572 0 2416 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 573 0 1428 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 574 0 3144 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 575 0 896 mi_switch: old thread 0xc2663580 (pid 11, idle) 576 0 428 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 577 0 392 ithread_loop: pid 28: resumed 578 0 584 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 579 0 604 ithread_loop: pid 28: (swi5: clock sio) need=1 580 0 532 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 581 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 582 0 760 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 583 0 488 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 584 0 644 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 585 0 552 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 586 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 587 0 4416 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 588 0 572 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:423 589 0 408 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:488 590 0 492 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:515 591 0 624 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:435 592 0 500 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 593 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 594 0 572 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 595 0 3464 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 596 0 384 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 597 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 598 0 400 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 599 0 448 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 600 0 616 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 601 0 436 sleepq_timeout: thread 0xc266cc60 (pid 2, g_event) 602 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 603 0 608 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 604 0 508 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 605 0 380 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 606 0 652 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 607 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 608 0 488 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 609 0 652 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 610 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 611 0 1124 sleepq_wakeup: thread 0xc266cc60 (pid 2, g_event) 612 0 692 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 613 0 448 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 614 0 544 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 615 0 380 sleepq_timeout: thread 0xc26ac160 (pid 30, yarrow) 616 0 688 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 617 0 588 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 618 0 492 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 619 0 380 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 620 0 592 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 621 0 380 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 622 0 528 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 623 0 564 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 624 0 332 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 625 0 584 sleepq_wakeup: thread 0xc26ac160 (pid 30, yarrow) 626 0 596 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 627 0 456 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 628 0 552 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 629 0 948 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 630 0 520 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:423 631 0 420 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:488 632 0 460 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:515 633 0 740 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:435 634 0 456 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 635 0 428 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 636 0 724 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 637 0 408 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 638 0 624 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 639 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 640 0 600 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 641 0 572 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 642 0 468 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 643 0 384 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 644 0 600 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 645 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 646 0 708 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 647 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 648 0 2700 ithread_loop: pid 28: done 649 0 864 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 650 0 544 mi_switch: new thread 0xc266cc60 (pid 2, g_event) 651 0 416 sleepq resume: thread 0xc266cc60 (pid 2, g_event) 652 0 904 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:546 653 0 504 XLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:157 654 0 740 LOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:159 655 0 412 UNLOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:165 656 0 400 LOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:170 657 0 508 UNLOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:173 658 0 552 XUNLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:174 659 0 456 XLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:202 660 0 540 XUNLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:209 661 0 504 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 662 0 400 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 663 0 508 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 664 0 596 msleep: thread 0xc266cc60 (pid 2, g_event) on - (0xc0889abc) 665 0 472 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 666 0 532 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 667 0 456 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 668 0 572 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 669 0 416 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 670 0 524 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 671 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 672 0 3128 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 673 0 1020 mi_switch: old thread 0xc266cc60 (pid 2, g_event) 674 0 332 mi_switch: new thread 0xc26ac160 (pid 30, yarrow) 675 0 356 sleepq resume: thread 0xc26ac160 (pid 30, yarrow) 676 0 1744 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:546 677 0 496 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 678 0 692 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 679 0 364 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 680 0 576 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 681 0 372 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 682 0 588 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 683 0 352 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 684 0 556 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 685 0 372 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 686 0 572 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 687 0 352 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 688 0 668 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 689 0 460 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 690 0 352 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 691 0 484 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 692 0 524 msleep: thread 0xc26ac160 (pid 30, yarrow) on - (0xc0884d40) 693 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 694 0 624 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 695 0 424 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 696 0 544 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 697 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 698 0 580 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 699 0 344 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 700 0 3400 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 701 0 760 mi_switch: old thread 0xc26ac160 (pid 30, yarrow) 702 0 384 mi_switch: new thread 0xc2663580 (pid 11, idle) 703 0 17348512 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 704 0 18968 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 705 0 4468 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 706 0 6457148 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 707 0 5164 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 708 0 604 swi_sched pid 28(swi5: clock sio) need=0 709 0 444 ithread_schedule: pid 28: (swi5: clock sio) need = 0 710 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 711 0 620 ithread_schedule: setrunqueue 28 712 0 724 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 713 0 2380 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 714 0 1428 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 715 0 2940 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 716 0 912 mi_switch: old thread 0xc2663580 (pid 11, idle) 717 0 424 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 718 0 380 ithread_loop: pid 28: resumed 719 0 568 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 720 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 721 0 500 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 722 0 480 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 723 0 740 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 724 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 725 0 672 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 726 0 492 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 727 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 728 0 508 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 729 0 436 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 730 0 384 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 731 0 596 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 732 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 733 0 704 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 734 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 735 0 2816 ithread_loop: pid 28: done 736 0 860 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 737 0 380 mi_switch: new thread 0xc2663580 (pid 11, idle) 738 0 12182892 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 739 0 18752 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 740 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 741 0 11692468 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 742 0 4792 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 743 0 508 swi_sched pid 28(swi5: clock sio) need=0 744 0 440 ithread_schedule: pid 28: (swi5: clock sio) need = 0 745 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 746 0 632 ithread_schedule: setrunqueue 28 747 0 724 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 748 0 2364 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 749 0 1456 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 750 0 3068 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 751 0 944 mi_switch: old thread 0xc2663580 (pid 11, idle) 752 0 420 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 753 0 480 ithread_loop: pid 28: resumed 754 0 572 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 755 0 580 ithread_loop: pid 28: (swi5: clock sio) need=1 756 0 448 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 757 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 758 0 744 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 759 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 760 0 656 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 761 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 762 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 763 0 508 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 764 0 440 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 765 0 392 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 766 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 767 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 768 0 692 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 769 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 770 0 3508 ithread_loop: pid 28: done 771 0 848 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 772 0 364 mi_switch: new thread 0xc2663580 (pid 11, idle) 773 0 6947464 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 774 0 18904 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 775 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 776 0 16927368 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 777 0 4856 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 778 0 520 swi_sched pid 28(swi5: clock sio) need=0 779 0 432 ithread_schedule: pid 28: (swi5: clock sio) need = 0 780 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 781 0 632 ithread_schedule: setrunqueue 28 782 0 720 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 783 0 2364 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 784 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 785 0 2936 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 786 0 908 mi_switch: old thread 0xc2663580 (pid 11, idle) 787 0 412 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 788 0 376 ithread_loop: pid 28: resumed 789 0 592 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 790 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 791 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 792 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 793 0 752 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 794 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 795 0 656 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 796 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 797 0 616 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 798 0 504 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 799 0 436 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 800 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 801 0 616 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 802 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 803 0 692 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 804 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 805 0 3264 ithread_loop: pid 28: done 806 0 852 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 807 0 360 mi_switch: new thread 0xc2663580 (pid 11, idle) 808 0 1712808 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 809 0 18904 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 810 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 811 0 18668920 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 812 0 18752 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 813 0 4484 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 814 0 3470404 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 815 0 4852 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 816 0 508 swi_sched pid 28(swi5: clock sio) need=0 817 0 440 ithread_schedule: pid 28: (swi5: clock sio) need = 0 818 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 819 0 628 ithread_schedule: setrunqueue 28 820 0 732 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 821 0 2364 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 822 0 1444 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 823 0 2940 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 824 0 900 mi_switch: old thread 0xc2663580 (pid 11, idle) 825 0 420 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 826 0 360 ithread_loop: pid 28: resumed 827 0 544 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 828 0 580 ithread_loop: pid 28: (swi5: clock sio) need=1 829 0 448 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 830 0 484 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 831 0 640 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 832 0 860 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 833 0 548 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:423 834 0 436 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:488 835 0 500 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:515 836 0 704 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:435 837 0 456 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 838 0 524 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 839 0 716 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 840 0 388 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 841 0 684 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 842 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 843 0 632 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 844 0 544 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 845 0 464 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 846 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 847 0 612 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 848 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 849 0 772 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 850 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 851 0 3076 ithread_loop: pid 28: done 852 0 860 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 853 0 384 mi_switch: new thread 0xc2663580 (pid 11, idle) 854 0 15165524 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 855 0 18836 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 856 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 857 0 8705276 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 858 0 4884 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 859 0 508 swi_sched pid 28(swi5: clock sio) need=0 860 0 440 ithread_schedule: pid 28: (swi5: clock sio) need = 0 861 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 862 0 632 ithread_schedule: setrunqueue 28 863 0 736 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 864 0 2376 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 865 0 1456 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 866 0 2980 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 867 0 888 mi_switch: old thread 0xc2663580 (pid 11, idle) 868 0 424 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 869 0 360 ithread_loop: pid 28: resumed 870 0 556 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 871 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 872 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 873 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 874 0 732 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 875 0 436 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 876 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 877 0 444 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 878 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 879 0 576 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 880 0 460 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 881 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 882 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 883 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 884 0 680 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 885 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 886 0 2736 ithread_loop: pid 28: done 887 0 872 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 888 0 360 mi_switch: new thread 0xc2663580 (pid 11, idle) 889 0 9935852 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 890 0 19604 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 891 0 4432 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 892 0 13939244 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 893 0 4884 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 894 0 508 swi_sched pid 28(swi5: clock sio) need=0 895 0 432 ithread_schedule: pid 28: (swi5: clock sio) need = 0 896 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 897 0 640 ithread_schedule: setrunqueue 28 898 0 720 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 899 0 2412 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 900 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 901 0 3044 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 902 0 896 mi_switch: old thread 0xc2663580 (pid 11, idle) 903 0 424 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 904 0 360 ithread_loop: pid 28: resumed 905 0 568 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 906 0 580 ithread_loop: pid 28: (swi5: clock sio) need=1 907 0 448 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 908 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 909 0 1228 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 910 0 868 LOCK (sleep mutex) log_mtx r = 0 at ../../../kern/subr_log.c:199 911 0 1116 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 912 0 592 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 913 0 536 UNLOCK (sleep mutex) log_mtx r = 0 at ../../../kern/subr_log.c:211 914 0 508 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 915 0 728 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 916 0 396 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 917 0 632 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 918 0 420 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 919 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 920 0 544 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 921 0 828 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 922 0 408 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 923 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 924 0 516 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 925 0 704 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 926 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 927 0 3044 ithread_loop: pid 28: done 928 0 904 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 929 0 368 mi_switch: new thread 0xc2663580 (pid 11, idle) 930 0 4694568 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 931 0 18868 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 932 0 4696 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 933 0 18668916 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 934 0 18820 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 935 0 4472 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 936 0 483236 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 937 0 4884 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 938 0 508 swi_sched pid 28(swi5: clock sio) need=0 939 0 440 ithread_schedule: pid 28: (swi5: clock sio) need = 0 940 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 941 0 632 ithread_schedule: setrunqueue 28 942 0 724 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 943 0 2412 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 944 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 945 0 3012 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 946 0 868 mi_switch: old thread 0xc2663580 (pid 11, idle) 947 0 416 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 948 0 360 ithread_loop: pid 28: resumed 949 0 580 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 950 0 580 ithread_loop: pid 28: (swi5: clock sio) need=1 951 0 448 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 952 0 476 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 953 0 780 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 954 0 436 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 955 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 956 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 957 0 616 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 958 0 580 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 959 0 460 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 960 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 961 0 628 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 962 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 963 0 680 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 964 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 965 0 2780 ithread_loop: pid 28: done 966 0 856 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 967 0 372 mi_switch: new thread 0xc2663580 (pid 11, idle) 968 0 18157292 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 969 0 18772 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 970 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 971 0 5718428 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 972 0 4848 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 973 0 544 swi_sched pid 28(swi5: clock sio) need=0 974 0 436 ithread_schedule: pid 28: (swi5: clock sio) need = 0 975 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 976 0 632 ithread_schedule: setrunqueue 28 977 0 696 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 978 0 2416 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 979 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 980 0 2956 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 981 0 880 mi_switch: old thread 0xc2663580 (pid 11, idle) 982 0 420 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 983 0 360 ithread_loop: pid 28: resumed 984 0 544 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 985 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 986 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 987 0 488 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 988 0 640 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 989 0 944 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 990 0 528 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:423 991 0 440 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:488 992 0 500 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:515 993 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:435 994 0 432 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 995 0 508 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 996 0 700 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 997 0 396 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 998 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 999 0 412 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1000 0 620 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1001 0 488 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1002 0 440 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1003 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1004 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1005 0 436 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1006 0 764 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 1007 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 1008 0 2976 ithread_loop: pid 28: done 1009 0 924 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 1010 0 360 mi_switch: new thread 0xc2663580 (pid 11, idle) 1011 0 12917572 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 1012 0 18656 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1013 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1014 0 10953632 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1015 0 4872 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 1016 0 544 swi_sched pid 28(swi5: clock sio) need=0 1017 0 436 ithread_schedule: pid 28: (swi5: clock sio) need = 0 1018 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 1019 0 632 ithread_schedule: setrunqueue 28 1020 0 720 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 1021 0 2364 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1022 0 1444 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1023 0 2820 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 1024 0 860 mi_switch: old thread 0xc2663580 (pid 11, idle) 1025 0 412 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 1026 0 376 ithread_loop: pid 28: resumed 1027 0 544 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 1028 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 1029 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 1030 0 480 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 1031 0 1604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1032 0 444 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1033 0 668 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1034 0 500 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1035 0 564 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1036 0 436 sleepq_timeout: thread 0xc26ac000 (pid 4, g_down) 1037 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 1038 0 616 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 1039 0 500 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 1040 0 376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 1041 0 680 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 1042 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 1043 0 484 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 1044 0 648 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 1045 0 356 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 1046 0 784 sleepq_wakeup: thread 0xc26ac000 (pid 4, g_down) 1047 0 640 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 1048 0 456 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1049 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1050 0 376 sleepq_timeout: thread 0xc266cdc0 (pid 3, g_up) 1051 0 344 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 1052 0 564 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 1053 0 480 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 1054 0 348 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 1055 0 652 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 1056 0 352 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 1057 0 544 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 1058 0 572 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 1059 0 332 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 1060 0 556 sleepq_wakeup: thread 0xc266cdc0 (pid 3, g_up) 1061 0 604 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 1062 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1063 0 696 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1064 0 392 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1065 0 616 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1066 0 436 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1067 0 596 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1068 0 520 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1069 0 436 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1070 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1071 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1072 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1073 0 708 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 1074 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 1075 0 3320 ithread_loop: pid 28: done 1076 0 860 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 1077 0 384 mi_switch: new thread 0xc26ac000 (pid 4, g_down) 1078 0 396 sleepq resume: thread 0xc26ac000 (pid 4, g_down) 1079 0 732 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:546 1080 0 536 LOCK (sleep mutex) bio queue r = 0 at ../../../geom/geom_io.c:65 1081 0 496 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 1082 0 400 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 1083 0 516 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 1084 0 380 msleep: thread 0xc26ac000 (pid 4, g_down) on - (0xc0889ac8) 1085 0 528 UNLOCK (sleep mutex) bio queue r = 0 at ../../../kern/kern_synch.c:198 1086 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 1087 0 600 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 1088 0 496 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1089 0 632 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1090 0 404 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 1091 0 632 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 1092 0 424 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 1093 0 3152 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 1094 0 792 mi_switch: old thread 0xc26ac000 (pid 4, g_down) 1095 0 336 mi_switch: new thread 0xc266cdc0 (pid 3, g_up) 1096 0 356 sleepq resume: thread 0xc266cdc0 (pid 3, g_up) 1097 0 680 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:546 1098 0 564 LOCK (sleep mutex) bio queue r = 0 at ../../../geom/geom_io.c:65 1099 0 428 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 1100 0 348 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 1101 0 460 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 1102 0 356 msleep: thread 0xc266cdc0 (pid 3, g_up) on - (0xc0889ac4) 1103 0 500 UNLOCK (sleep mutex) bio queue r = 0 at ../../../kern/kern_synch.c:198 1104 0 352 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 1105 0 564 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 1106 0 448 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1107 0 508 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1108 0 352 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 1109 0 552 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 1110 0 340 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 1111 0 2956 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 1112 0 844 mi_switch: old thread 0xc266cdc0 (pid 3, g_up) 1113 0 376 mi_switch: new thread 0xc2663580 (pid 11, idle) 1114 0 7647024 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 1115 0 18756 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1116 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1117 0 16188720 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1118 0 5600 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 1119 0 604 swi_sched pid 28(swi5: clock sio) need=0 1120 0 444 ithread_schedule: pid 28: (swi5: clock sio) need = 0 1121 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 1122 0 620 ithread_schedule: setrunqueue 28 1123 0 692 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 1124 0 2432 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1125 0 1428 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1126 0 3080 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 1127 0 860 mi_switch: old thread 0xc2663580 (pid 11, idle) 1128 0 424 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 1129 0 368 ithread_loop: pid 28: resumed 1130 0 584 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 1131 0 604 ithread_loop: pid 28: (swi5: clock sio) need=1 1132 0 480 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 1133 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 1134 0 684 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1135 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1136 0 648 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1137 0 516 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1138 0 612 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1139 0 3816 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 1140 0 568 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:423 1141 0 388 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:488 1142 0 488 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:515 1143 0 624 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:435 1144 0 500 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 1145 0 456 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1146 0 580 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1147 0 4008 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 1148 0 404 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1149 0 672 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1150 0 496 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 1151 0 480 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1152 0 648 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1153 0 516 sleepq_timeout: thread 0xc266cc60 (pid 2, g_event) 1154 0 464 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 1155 0 672 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 1156 0 508 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 1157 0 500 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 1158 0 748 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 1159 0 428 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 1160 0 548 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 1161 0 716 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 1162 0 416 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 1163 0 844 sleepq_wakeup: thread 0xc266cc60 (pid 2, g_event) 1164 0 788 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 1165 0 516 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1166 0 612 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1167 0 436 sleepq_timeout: thread 0xc26ac160 (pid 30, yarrow) 1168 0 444 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:724 1169 0 668 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:727 1170 0 540 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 1171 0 440 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:729 1172 0 660 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:749 1173 0 412 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:603 1174 0 592 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:606 1175 0 624 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:239 1176 0 384 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:623 1177 0 768 sleepq_wakeup: thread 0xc26ac160 (pid 30, yarrow) 1178 0 700 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:633 1179 0 516 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1180 0 756 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1181 0 448 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1182 0 672 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1183 0 524 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1184 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1185 0 636 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1186 0 516 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1187 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1188 0 664 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1189 0 552 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1190 0 752 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 1191 0 460 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 1192 0 3284 ithread_loop: pid 28: done 1193 0 776 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 1194 0 492 mi_switch: new thread 0xc266cc60 (pid 2, g_event) 1195 0 500 sleepq resume: thread 0xc266cc60 (pid 2, g_event) 1196 0 924 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:546 1197 0 580 XLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:157 1198 0 476 LOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:159 1199 0 480 UNLOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:165 1200 0 468 LOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:170 1201 0 564 UNLOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:173 1202 0 616 XUNLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:174 1203 0 552 XLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:202 1204 0 592 XUNLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:209 1205 0 520 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 1206 0 488 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 1207 0 568 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 1208 0 688 msleep: thread 0xc266cc60 (pid 2, g_event) on - (0xc0889abc) 1209 0 428 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 1210 0 676 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 1211 0 504 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1212 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1213 0 500 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 1214 0 688 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 1215 0 424 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 1216 0 2976 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 1217 0 996 mi_switch: old thread 0xc266cc60 (pid 2, g_event) 1218 0 428 mi_switch: new thread 0xc26ac160 (pid 30, yarrow) 1219 0 452 sleepq resume: thread 0xc26ac160 (pid 30, yarrow) 1220 0 772 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:546 1221 0 500 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 1222 0 772 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 1223 0 412 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 1224 0 632 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 1225 0 436 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 1226 0 636 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 1227 0 404 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 1228 0 624 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 1229 0 424 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 1230 0 636 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 1231 0 404 LOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:248 1232 0 728 UNLOCK (spin mutex) entropy harvest r = 0 at ../../../dev/random/randomdev_soft.c:262 1233 0 508 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:223 1234 0 444 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:169 1235 0 548 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:190 1236 0 616 msleep: thread 0xc26ac160 (pid 30, yarrow) on - (0xc0884d40) 1237 0 432 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:297 1238 0 668 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:300 1239 0 452 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1240 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1241 0 452 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:229 1242 0 604 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:231 1243 0 440 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:407 1244 0 3868 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:408 1245 0 824 mi_switch: old thread 0xc26ac160 (pid 30, yarrow) 1246 0 476 mi_switch: new thread 0xc2663580 (pid 11, idle) 1247 0 2382084 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 1248 0 18836 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1249 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1250 0 18669060 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1251 0 18752 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1252 0 4460 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1253 0 2731636 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1254 0 5420 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 1255 0 564 swi_sched pid 28(swi5: clock sio) need=0 1256 0 444 ithread_schedule: pid 28: (swi5: clock sio) need = 0 1257 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 1258 0 616 ithread_schedule: setrunqueue 28 1259 0 688 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 1260 0 2376 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1261 0 1428 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1262 0 2964 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 1263 0 880 mi_switch: old thread 0xc2663580 (pid 11, idle) 1264 0 424 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 1265 0 484 ithread_loop: pid 28: resumed 1266 0 580 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 1267 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 1268 0 500 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 1269 0 484 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 1270 0 744 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1271 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1272 0 648 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1273 0 488 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1274 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1275 0 560 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1276 0 464 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1277 0 380 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1278 0 616 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1279 0 464 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1280 0 680 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 1281 0 364 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 1282 0 2724 ithread_loop: pid 28: done 1283 0 844 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 1284 0 372 mi_switch: new thread 0xc2663580 (pid 11, idle) 1285 0 15908484 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 1286 0 18752 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1287 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1288 0 7966708 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1289 0 4896 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 1290 0 508 swi_sched pid 28(swi5: clock sio) need=0 1291 0 432 ithread_schedule: pid 28: (swi5: clock sio) need = 0 1292 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 1293 0 632 ithread_schedule: setrunqueue 28 1294 0 796 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 1295 0 2700 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1296 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1297 0 2876 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 1298 0 944 mi_switch: old thread 0xc2663580 (pid 11, idle) 1299 0 408 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 1300 0 376 ithread_loop: pid 28: resumed 1301 0 568 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 1302 0 580 ithread_loop: pid 28: (swi5: clock sio) need=1 1303 0 448 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 1304 0 496 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 1305 0 692 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1306 0 992 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:332 1307 0 576 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:423 1308 0 444 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:488 1309 0 496 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:515 1310 0 652 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:435 1311 0 456 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:369 1312 0 504 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1313 0 724 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1314 0 388 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1315 0 660 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1316 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1317 0 648 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1318 0 536 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1319 0 460 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1320 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1321 0 620 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1322 0 460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1323 0 692 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 1324 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 1325 0 2856 ithread_loop: pid 28: done 1326 0 844 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 1327 0 360 mi_switch: new thread 0xc2663580 (pid 11, idle) 1328 0 10668844 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 1329 0 18828 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1330 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1331 0 13201680 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1332 0 5804 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 1333 0 508 swi_sched pid 28(swi5: clock sio) need=0 1334 0 436 ithread_schedule: pid 28: (swi5: clock sio) need = 0 1335 0 372 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 1336 0 632 ithread_schedule: setrunqueue 28 1337 0 732 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 1338 0 2712 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1339 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1340 0 2876 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 1341 0 888 mi_switch: old thread 0xc2663580 (pid 11, idle) 1342 0 408 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 1343 0 376 ithread_loop: pid 28: resumed 1344 0 544 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 1345 0 600 ithread_loop: pid 28: (swi5: clock sio) need=1 1346 0 472 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 1347 0 480 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 1348 0 808 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1349 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1350 0 744 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1351 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1352 0 588 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1353 0 496 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1354 0 436 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1355 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1356 0 604 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1357 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1358 0 692 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 1359 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 1360 0 2860 ithread_loop: pid 28: done 1361 0 856 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 1362 0 376 mi_switch: new thread 0xc2663580 (pid 11, idle) 1363 0 5437600 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 1364 0 18960 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1365 0 4428 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1366 0 18436868 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1367 0 5596 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 1368 0 508 swi_sched pid 28(swi5: clock sio) need=0 1369 0 436 ithread_schedule: pid 28: (swi5: clock sio) need = 0 1370 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 1371 0 628 ithread_schedule: setrunqueue 28 1372 0 720 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 1373 0 2700 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1374 0 1444 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1375 0 2980 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:108 1376 0 876 mi_switch: old thread 0xc2663580 (pid 11, idle) 1377 0 420 mi_switch: new thread 0xc2663000 (pid 28, swi5: clock sio) 1378 0 360 ithread_loop: pid 28: resumed 1379 0 544 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:611 1380 0 580 ithread_loop: pid 28: (swi5: clock sio) need=1 1381 0 448 ithread_loop: pid 28 ih=0xc26ad500: 0xc0603e90(0x0) flg=80000000 1382 0 476 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:286 1383 0 744 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1384 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1385 0 680 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1386 0 468 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1387 0 592 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:330 1388 0 556 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1389 0 460 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1390 0 376 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:488 1391 0 608 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:515 1392 0 440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:370 1393 0 704 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:395 1394 0 360 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:604 1395 0 2656 ithread_loop: pid 28: done 1396 0 836 mi_switch: old thread 0xc2663000 (pid 28, swi5: clock sio) 1397 0 392 mi_switch: new thread 0xc2663580 (pid 11, idle) 1398 0 202844 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:110 1399 0 18904 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1400 0 4480 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1401 0 18668992 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1402 0 18752 intr_execute_handlers: executing handler 0xc0772804(0xdb882c98) 1403 0 4484 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:278 1404 0 4979764 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:280 1405 0 4900 intr_execute_handlers: executing handler 0xc07724dc(0xdb882c98) 1406 0 544 swi_sched pid 28(swi5: clock sio) need=0 1407 0 432 ithread_schedule: pid 28: (swi5: clock sio) need = 0 1408 0 368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:405 1409 0 636 ithread_schedule: setrunqueue 28 1410 0 720 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:414 1411 0 2700 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1412 0 1448 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1413 0 3004 LOCK (spin mutex) sched lock