0 1 1862864822144 ithread_loop: pid 87: resumed 1 1 1862864823192 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 2 1 1862864825428 ithread_loop: pid 87: (swi4: clock sio) need=1 3 2 1862864825964 _mtx_lock_spin: 0xc0ec1040 spin done 4 1 1862864826724 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 5 2 1862864827836 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 6 1 1862864828232 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 7 1 1862864829292 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 8 1 1862864830376 sleepq_timeout: thread 0xc5c7b780 (pid 4, g_down) 9 1 1862864832460 _mtx_lock_spin: 0xc0ec1040 spinning 10 2 1862864834768 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 11 2 1862864836720 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 12 2 1862864837608 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 13 0 1862864841000 _mtx_lock_spin: 0xc0ec1040 spin done 14 0 1862864841952 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 15 0 1862864847564 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 16 0 1862864849508 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 17 0 1862864850332 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 18 1 1862864854288 _mtx_lock_spin: 0xc0ec1040 spin done 19 1 1862864855124 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:753 20 1 1862864855884 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:756 21 1 1862864856832 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 22 1 1862864858276 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:759 23 1 1862864858956 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:779 24 1 1862864860212 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:638 25 1 1862864860900 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:641 26 1 1862864861604 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:251 27 1 1862864862520 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:658 28 1 1862864863148 sleepq_wakeup: thread 0xc5c7b780 (pid 4, g_down) 29 1 1862864869436 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:668 30 0 1862864869756 _mtx_lock_spin: 0xc0ec1040 spinning 31 1 1862864871804 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 32 2 1862864872408 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 33 3 1862864873012 _mtx_lock_spin: 0xc0ec1040 spinning 34 1 1862864873292 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 35 1 1862864875204 sleepq_timeout: thread 0xc5c7b600 (pid 3, g_up) 36 1 1862864877448 _mtx_lock_spin: 0xc0ec1040 spinning 37 2 1862864878776 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 38 2 1862864883632 mi_switch: new thread 0xc5c7b780 (kse 0xc5c7b8d0, pid 4, g_down) 39 2 1862864884436 sleepq resume: thread 0xc5c7b780 (pid 4, g_down) 40 2 1862864885896 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:581 41 0 1862864888472 _mtx_lock_spin: 0xc0ec1040 spin done 42 0 1862864889468 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 43 2 1862864889928 LOCK (sleep mutex) bio queue r = 0 at ../../../geom/geom_io.c:66 44 2 1862864893184 g_down going to sleep 45 0 1862864895384 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 46 2 1862864895816 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 47 2 1862864896628 msleep: thread 0xc5c7b780 (pid 4, g_down) on - (0xc08bd388) 48 2 1862864898976 UNLOCK (sleep mutex) bio queue r = 0 at ../../../kern/kern_synch.c:188 49 0 1862864899532 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 50 0 1862864900528 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 51 3 1862864904316 _mtx_lock_spin: 0xc0ec1040 spin done 52 2 1862864904716 _mtx_lock_spin: 0xc0ec1040 spinning 53 3 1862864905536 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 54 3 1862864911232 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 55 3 1862864913384 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 56 3 1862864914196 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 57 1 1862864916688 _mtx_lock_spin: 0xc0ec1040 spin done 58 1 1862864917368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:753 59 1 1862864917900 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:756 60 2 1862864920052 _mtx_lock_spin: 0xc0ec1040 spin done 61 1 1862864920428 _mtx_lock_spin: 0xc0ec661c spinning 62 2 1862864921736 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:317 63 2 1862864923108 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:322 64 2 1862864925320 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 65 2 1862864926532 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 66 2 1862864927428 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:216 67 2 1862864928336 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:218 68 2 1862864929552 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:433 69 2 1862864930176 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:434 70 1 1862864931788 _mtx_lock_spin: 0xc0ec661c spin done 71 1 1862864932304 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 72 1 1862864934076 _mtx_lock_spin: 0xc0ec1040 spinning 73 2 1862864936248 mi_switch: old thread 0xc5c7b780 (kse 0xc5c7b8d0, pid 4, g_down) 74 2 1862864938868 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 75 2 1862864939776 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 76 1 1862864941924 _mtx_lock_spin: 0xc0ec1040 spin done 77 1 1862864942476 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:759 78 1 1862864942996 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:779 79 1 1862864944368 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:638 80 1 1862864944868 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:641 81 1 1862864945528 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:251 82 1 1862864946412 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:658 83 1 1862864946840 sleepq_wakeup: thread 0xc5c7b600 (pid 3, g_up) 84 1 1862864952264 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:668 85 0 1862864952612 _mtx_lock_spin: 0xc0ec1040 spinning 86 1 1862864954668 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 87 0 1862864954820 _mtx_lock_spin: 0xc0ec1040 spin done 88 2 1862864955768 _mtx_lock_spin: 0xc0ec1040 spinning 89 3 1862864956100 _mtx_lock_spin: 0xc0ec1040 spinning 90 0 1862864956928 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 91 1 1862864957472 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 92 1 1862864960740 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:247 93 0 1862864963204 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 94 1 1862864965528 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:333 95 1 1862864967200 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:398 96 0 1862864967508 mi_switch: new thread 0xc5c7b600 (kse 0xc5c7b750, pid 3, g_up) 97 0 1862864968408 sleepq resume: thread 0xc5c7b600 (pid 3, g_up) 98 1 1862864968536 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:439 99 1 1862864969720 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:345 100 0 1862864970040 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:581 101 3 1862864972536 _mtx_lock_spin: 0xc0ec1040 spin done 102 1 1862864973968 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:279 103 3 1862864974220 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 104 0 1862864975948 LOCK (sleep mutex) bio queue r = 0 at ../../../geom/geom_io.c:66 105 1 1862864976768 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 106 1 1862864978748 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 107 0 1862864981040 g_up going to sleep 108 3 1862864981292 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 109 1 1862864981704 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 110 1 1862864982876 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 111 0 1862864983656 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 112 3 1862864985784 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 113 0 1862864986244 msleep: thread 0xc5c7b600 (pid 3, g_up) on - (0xc08bd384) 114 1 1862864986460 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 115 3 1862864987064 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 116 2 1862864989056 _mtx_lock_spin: 0xc0ec1040 spin done 117 1 1862864989696 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 118 2 1862864990236 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 119 0 1862864991952 UNLOCK (sleep mutex) bio queue r = 0 at ../../../kern/kern_synch.c:188 120 1 1862864992140 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 121 0 1862864995836 _mtx_lock_spin: 0xc0ec1040 spinning 122 2 1862864996024 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 123 2 1862864997940 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 124 1 1862864999388 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 125 2 1862864999548 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 126 1 1862865003168 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 127 0 1862865004164 _mtx_lock_spin: 0xc0ec1040 spin done 128 1 1862865004316 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 129 0 1862865005812 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:317 130 1 1862865006732 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 131 0 1862865007252 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:322 132 1 1862865008464 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 133 0 1862865009604 _mtx_lock_spin: 0xc0ec2b60 spinning 134 0 1862865010584 _mtx_lock_spin: 0xc0ec2b60 spin done 135 1 1862865011260 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 136 0 1862865011712 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 137 1 1862865012492 ithread_loop: pid 87: done 138 0 1862865013628 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 139 0 1862865015324 _mtx_lock_spin: 0xc0ec1040 spinning 140 1 1862865018264 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 141 1 1862865021556 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 142 1 1862865022436 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 143 0 1862865024012 _mtx_lock_spin: 0xc0ec1040 spin done 144 0 1862865024940 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:216 145 0 1862865026004 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:218 146 0 1862865026868 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:433 147 0 1862865027380 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:434 148 0 1862865031612 mi_switch: old thread 0xc5c7b600 (kse 0xc5c7b750, pid 3, g_up) 149 0 1862865033280 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 150 0 1862865033812 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 151 0 1862865413326 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 152 0 1862889294380 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 153 0 1862895356956 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 154 0 1862895364004 swi_sched pid 87(swi4: clock sio) need=0 155 0 1862895364624 ithread_schedule: pid 87: (swi4: clock sio) need = 0 156 0 1862895365472 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 157 0 1862895366008 ithread_schedule: setrunqueue 87 158 0 1862895366808 maybe_preempt: in critical section 2 159 0 1862895368088 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 160 0 1862895372164 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 161 0 1862895375828 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 162 1 1862895376408 _mtx_lock_spin: 0xc0ec1040 spinning 163 1 1862895377896 _mtx_lock_spin: 0xc0ec1040 spin done 164 1 1862895379032 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 165 2 1862895380938 _mtx_lock_spin: 0xc0ec1040 spinning 166 0 1862895380776 _mtx_lock_spin: 0xc0ec1040 spinning 167 3 1862895381794 _mtx_lock_spin: 0xc0ec1040 spinning 168 1 1862895384940 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 169 1 1862895387680 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 170 1 1862895388404 ithread_loop: pid 87: resumed 171 1 1862895389244 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 172 1 1862895391624 ithread_loop: pid 87: (swi4: clock sio) need=1 173 2 1862895392310 _mtx_lock_spin: 0xc0ec1040 spin done 174 1 1862895392788 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 175 2 1862895393966 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 176 1 1862895394288 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 177 1 1862895396228 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 178 1 1862895397772 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 179 1 1862895398564 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 180 1 1862895400476 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 181 2 1862895400818 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 182 1 1862895401688 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 183 2 1862895403414 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 184 1 1862895403892 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 185 2 1862895405730 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 186 0 1862895409380 _mtx_lock_spin: 0xc0ec1040 spin done 187 1 1862895409512 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 188 0 1862895410892 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 189 1 1862895411560 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 190 1 1862895413244 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 191 1 1862895415408 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 192 1 1862895416368 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 193 0 1862895416732 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 194 1 1862895419476 _mtx_lock_spin: 0xc0ec1040 spinning 195 0 1862895420076 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 196 0 1862895421328 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 197 3 1862895423386 _mtx_lock_spin: 0xc0ec1040 spin done 198 3 1862895424446 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 199 3 1862895429810 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 200 3 1862895431138 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 201 3 1862895432082 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 202 1 1862895434104 _mtx_lock_spin: 0xc0ec1040 spin done 203 1 1862895434740 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 204 1 1862895435368 ithread_loop: pid 87: done 205 1 1862895440216 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 206 1 1862895441892 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 207 1 1862895442472 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 208 0 1862913174280 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 209 0 1862925926744 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 210 0 1862925933616 swi_sched pid 87(swi4: clock sio) need=0 211 0 1862925934220 ithread_schedule: pid 87: (swi4: clock sio) need = 0 212 0 1862925934880 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 213 0 1862925935404 ithread_schedule: setrunqueue 87 214 0 1862925936208 maybe_preempt: in critical section 2 215 0 1862925937308 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 216 0 1862925941316 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 217 0 1862925945216 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 218 1 1862925945752 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 219 2 1862925946428 _mtx_lock_spin: 0xc0ec1040 spinning 220 3 1862925950484 _mtx_lock_spin: 0xc0ec1040 spinning 221 0 1862925951028 _mtx_lock_spin: 0xc0ec1040 spinning 222 1 1862925951280 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 223 1 1862925955944 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 224 1 1862925956612 ithread_loop: pid 87: resumed 225 1 1862925957608 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 226 3 1862925959780 _mtx_lock_spin: 0xc0ec1040 spin done 227 1 1862925960272 ithread_loop: pid 87: (swi4: clock sio) need=1 228 3 1862925961692 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 229 1 1862925962192 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 230 1 1862925963440 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 231 1 1862925964752 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 232 1 1862925966200 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 233 1 1862925967164 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 234 1 1862925968436 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 235 3 1862925968788 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 236 1 1862925969636 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 237 3 1862925972312 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 238 1 1862925972740 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 239 3 1862925973524 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 240 0 1862925976140 _mtx_lock_spin: 0xc0ec1040 spin done 241 0 1862925977404 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 242 1 1862925978600 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 243 1 1862925980024 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 244 1 1862925981488 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 245 1 1862925982756 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 246 0 1862925983132 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 247 1 1862925983732 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 248 0 1862925985560 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 249 1 1862925987676 _mtx_lock_spin: 0xc0ec1040 spinning 250 0 1862925987772 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 251 2 1862925991124 _mtx_lock_spin: 0xc0ec1040 spin done 252 2 1862925991848 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 253 2 1862925996372 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 254 2 1862925997628 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 255 2 1862925998580 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 256 1 1862926000420 _mtx_lock_spin: 0xc0ec1040 spin done 257 1 1862926001160 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 258 1 1862926001688 ithread_loop: pid 87: done 259 1 1862926005700 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 260 1 1862926007588 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 261 1 1862926008196 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 262 0 1862937053768 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 263 0 1862956494404 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 264 0 1862956501000 swi_sched pid 87(swi4: clock sio) need=0 265 0 1862956501632 ithread_schedule: pid 87: (swi4: clock sio) need = 0 266 0 1862956502288 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 267 0 1862956502840 ithread_schedule: setrunqueue 87 268 0 1862956503628 maybe_preempt: in critical section 2 269 0 1862956504972 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 270 0 1862956509080 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 271 0 1862956513380 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 272 1 1862956513708 _mtx_lock_spin: 0xc0ec1040 spinning 273 2 1862956514756 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 274 3 1862956517756 _mtx_lock_spin: 0xc0ec1040 spinning 275 0 1862956518232 _mtx_lock_spin: 0xc0ec1040 spinning 276 2 1862956521932 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 277 2 1862956526956 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 278 2 1862956527636 ithread_loop: pid 87: resumed 279 2 1862956528464 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 280 2 1862956530576 ithread_loop: pid 87: (swi4: clock sio) need=1 281 0 1862956531172 _mtx_lock_spin: 0xc0ec1040 spin done 282 2 1862956532532 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 283 0 1862956533176 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 284 2 1862956535152 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 285 2 1862956538136 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 286 2 1862956539568 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 287 2 1862956540672 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 288 0 1862956541204 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 289 2 1862956542320 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 290 2 1862956543376 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 291 0 1862956544356 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 292 0 1862956545404 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 293 2 1862956546476 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 294 3 1862956549340 _mtx_lock_spin: 0xc0ec1040 spin done 295 3 1862956550376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 296 2 1862956553096 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 297 3 1862956555264 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 298 2 1862956555436 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 299 2 1862956556716 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 300 3 1862956557756 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 301 3 1862956558936 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 302 2 1862956559512 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 303 2 1862956560492 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 304 1 1862956561284 _mtx_lock_spin: 0xc0ec1040 spin done 305 1 1862956563412 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 306 2 1862956564016 _mtx_lock_spin: 0xc0ec1040 spinning 307 1 1862956569444 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 308 1 1862956571740 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 309 1 1862956572692 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 310 2 1862956574316 _mtx_lock_spin: 0xc0ec1040 spin done 311 2 1862956574760 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 312 2 1862956575276 ithread_loop: pid 87: done 313 2 1862956580000 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 314 2 1862956581704 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 315 2 1862956582300 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 316 0 1862960936374 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 317 0 1862984814668 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 318 0 1862987064110 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 319 0 1862987071994 swi_sched pid 87(swi4: clock sio) need=0 320 0 1862987072626 ithread_schedule: pid 87: (swi4: clock sio) need = 0 321 0 1862987073430 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 322 0 1862987074250 ithread_schedule: setrunqueue 87 323 0 1862987075610 maybe_preempt: in critical section 2 324 0 1862987076582 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 325 0 1862987080874 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 326 0 1862987084474 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 327 1 1862987087390 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 328 2 1862987087734 _mtx_lock_spin: 0xc0ec1040 spinning 329 3 1862987088014 _mtx_lock_spin: 0xc0ec1040 spinning 330 0 1862987091326 _mtx_lock_spin: 0xc0ec1040 spinning 331 1 1862987095046 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 332 1 1862987098934 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 333 1 1862987099690 ithread_loop: pid 87: resumed 334 1 1862987100494 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 335 1 1862987102630 ithread_loop: pid 87: (swi4: clock sio) need=1 336 2 1862987104030 _mtx_lock_spin: 0xc0ec1040 spin done 337 1 1862987104514 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 338 2 1862987106394 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 339 1 1862987106674 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 340 1 1862987108890 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 341 1 1862987111006 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:247 342 2 1862987115370 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 343 1 1862987116618 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:333 344 2 1862987118718 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 345 1 1862987119146 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:398 346 2 1862987120494 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 347 1 1862987122058 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:439 348 1 1862987123282 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:345 349 0 1862987124374 _mtx_lock_spin: 0xc0ec1040 spin done 350 0 1862987125594 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 351 1 1862987127354 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:279 352 1 1862987128990 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 353 1 1862987130322 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 354 0 1862987131634 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 355 1 1862987131926 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 356 1 1862987133234 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 357 0 1862987134066 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 358 1 1862987135050 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 359 0 1862987135374 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 360 1 1862987136634 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 361 3 1862987137650 _mtx_lock_spin: 0xc0ec1040 spin done 362 1 1862987139826 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 363 3 1862987140222 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 364 1 1862987144450 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 365 1 1862987146262 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 366 3 1862987147054 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 367 1 1862987147558 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 368 3 1862987149162 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 369 1 1862987149834 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 370 3 1862987152094 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 371 1 1862987152358 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 372 1 1862987154986 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 373 1 1862987155478 ithread_loop: pid 87: done 374 1 1862987160158 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 375 1 1862987162146 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 376 1 1862987162734 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 377 0 1863008696916 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 378 0 1863017631596 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 379 0 1863017639020 swi_sched pid 87(swi4: clock sio) need=0 380 0 1863017639640 ithread_schedule: pid 87: (swi4: clock sio) need = 0 381 0 1863017640300 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 382 0 1863017640852 ithread_schedule: setrunqueue 87 383 0 1863017641760 maybe_preempt: in critical section 2 384 0 1863017643004 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 385 0 1863017646996 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 386 0 1863017650884 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 387 1 1863017652264 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 388 2 1863017652934 _mtx_lock_spin: 0xc0ec1040 spinning 389 3 1863017653342 _mtx_lock_spin: 0xc0ec1040 spinning 390 0 1863017656400 _mtx_lock_spin: 0xc0ec1040 spinning 391 1 1863017659472 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 392 1 1863017662164 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 393 1 1863017662840 ithread_loop: pid 87: resumed 394 1 1863017663696 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 395 1 1863017665760 ithread_loop: pid 87: (swi4: clock sio) need=1 396 1 1863017666732 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 397 3 1863017667254 _mtx_lock_spin: 0xc0ec1040 spin done 398 1 1863017668000 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 399 3 1863017668386 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 400 1 1863017669360 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 401 1 1863017671772 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 402 1 1863017672552 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 403 1 1863017673792 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 404 1 1863017674796 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 405 3 1863017675414 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 406 1 1863017677268 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 407 3 1863017678018 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 408 3 1863017678958 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 409 0 1863017682656 _mtx_lock_spin: 0xc0ec1040 spin done 410 1 1863017683528 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 411 0 1863017683792 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 412 1 1863017686056 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 413 1 1863017688308 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 414 0 1863017689796 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 415 1 1863017690068 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 416 1 1863017691344 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 417 0 1863017692332 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 418 0 1863017693392 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 419 1 1863017695272 _mtx_lock_spin: 0xc0ec1040 spinning 420 2 1863017696082 _mtx_lock_spin: 0xc0ec1040 spin done 421 2 1863017698602 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 422 2 1863017703058 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 423 2 1863017704398 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 424 2 1863017705174 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 425 1 1863017707200 _mtx_lock_spin: 0xc0ec1040 spin done 426 1 1863017707796 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 427 1 1863017708300 ithread_loop: pid 87: done 428 1 1863017712964 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 429 1 1863017714648 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 430 1 1863017715216 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 431 0 1863032576308 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 432 0 1863048199148 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 433 0 1863048205964 swi_sched pid 87(swi4: clock sio) need=0 434 0 1863048206584 ithread_schedule: pid 87: (swi4: clock sio) need = 0 435 0 1863048207292 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 436 0 1863048207816 ithread_schedule: setrunqueue 87 437 0 1863048208616 maybe_preempt: in critical section 2 438 0 1863048209844 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 439 0 1863048213756 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 440 0 1863048217452 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 441 1 1863048219248 _mtx_lock_spin: 0xc0ec1040 spinning 442 1 1863048222288 _mtx_lock_spin: 0xc0ec1040 spin done 443 2 1863048222644 _mtx_lock_spin: 0xc0ec1040 spinning 444 0 1863048223052 _mtx_lock_spin: 0xc0ec1040 spinning 445 1 1863048223624 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 446 3 1863048225920 _mtx_lock_spin: 0xc0ec1040 spinning 447 1 1863048232144 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 448 1 1863048234844 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 449 1 1863048235504 ithread_loop: pid 87: resumed 450 1 1863048236348 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 451 1 1863048237924 ithread_loop: pid 87: (swi4: clock sio) need=1 452 2 1863048238480 _mtx_lock_spin: 0xc0ec1040 spin done 453 1 1863048239876 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 454 2 1863048240756 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 455 1 1863048241252 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 456 1 1863048242380 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 457 1 1863048243976 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 458 1 1863048244796 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 459 1 1863048246004 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 460 1 1863048247060 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 461 2 1863048248256 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 462 1 1863048249000 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 463 2 1863048251172 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 464 2 1863048252116 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 465 1 1863048254400 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 466 0 1863048254852 _mtx_lock_spin: 0xc0ec1040 spin done 467 1 1863048255772 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 468 0 1863048255948 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 469 1 1863048257364 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 470 1 1863048259028 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 471 1 1863048259748 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 472 0 1863048262112 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 473 1 1863048262404 _mtx_lock_spin: 0xc0ec1040 spinning 474 0 1863048265288 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 475 0 1863048266260 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 476 3 1863048268532 _mtx_lock_spin: 0xc0ec1040 spin done 477 3 1863048269272 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 478 3 1863048273764 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 479 3 1863048275112 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 480 3 1863048275944 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 481 1 1863048278008 _mtx_lock_spin: 0xc0ec1040 spin done 482 1 1863048278664 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 483 1 1863048279164 ithread_loop: pid 87: done 484 1 1863048284340 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 485 1 1863048286140 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 486 1 1863048286764 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 487 0 1863056457944 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 488 0 1863078769016 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 489 0 1863078775788 swi_sched pid 87(swi4: clock sio) need=0 490 0 1863078776532 ithread_schedule: pid 87: (swi4: clock sio) need = 0 491 0 1863078777188 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 492 0 1863078777808 ithread_schedule: setrunqueue 87 493 0 1863078778624 maybe_preempt: in critical section 2 494 0 1863078779476 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 495 0 1863078783732 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 496 0 1863078788564 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 497 2 1863078789788 _mtx_lock_spin: 0xc0ec1040 spinning 498 1 1863078790136 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 499 3 1863078791608 _mtx_lock_spin: 0xc0ec1040 spinning 500 0 1863078794452 _mtx_lock_spin: 0xc0ec1040 spinning 501 1 1863078797560 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 502 1 1863078800252 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 503 1 1863078800932 ithread_loop: pid 87: resumed 504 1 1863078801848 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 505 1 1863078804528 ithread_loop: pid 87: (swi4: clock sio) need=1 506 2 1863078805016 _mtx_lock_spin: 0xc0ec1040 spin done 507 1 1863078805800 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 508 2 1863078806144 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 509 1 1863078808640 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 510 1 1863078809588 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 511 1 1863078811108 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 512 1 1863078811920 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 513 2 1863078813416 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 514 1 1863078813760 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 515 1 1863078814680 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 516 2 1863078816912 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 517 2 1863078818632 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 518 1 1863078819064 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 519 0 1863078821620 _mtx_lock_spin: 0xc0ec1040 spin done 520 0 1863078823216 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 521 1 1863078825308 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 522 1 1863078826760 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 523 1 1863078827980 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 524 0 1863078829532 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 525 1 1863078829676 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 526 1 1863078830768 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 527 0 1863078832284 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 528 0 1863078834244 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 529 1 1863078834544 _mtx_lock_spin: 0xc0ec1040 spinning 530 1 1863078836924 _mtx_lock_spin: 0xc0ec1040 spin done 531 1 1863078837792 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 532 1 1863078838792 ithread_loop: pid 87: done 533 1 1863078843272 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 534 1 1863078844904 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 535 1 1863078845900 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 536 3 1863078847692 _mtx_lock_spin: 0xc0ec1040 spin done 537 3 1863078848440 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 538 3 1863078853688 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 539 3 1863078854760 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 540 3 1863078855448 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 541 0 1863080338030 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 542 0 1863104216672 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 543 0 1863109336548 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 544 0 1863109343888 swi_sched pid 87(swi4: clock sio) need=0 545 0 1863109344676 ithread_schedule: pid 87: (swi4: clock sio) need = 0 546 0 1863109345336 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 547 0 1863109345888 ithread_schedule: setrunqueue 87 548 0 1863109346680 maybe_preempt: in critical section 2 549 0 1863109348460 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 550 0 1863109352308 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 551 0 1863109355992 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 552 2 1863109358002 _mtx_lock_spin: 0xc0ec1040 spinning 553 1 1863109358352 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 554 3 1863109359066 _mtx_lock_spin: 0xc0ec1040 spinning 555 0 1863109362188 _mtx_lock_spin: 0xc0ec1040 spinning 556 1 1863109365752 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 557 1 1863109368364 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 558 1 1863109369020 ithread_loop: pid 87: resumed 559 1 1863109369872 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 560 1 1863109372176 ithread_loop: pid 87: (swi4: clock sio) need=1 561 3 1863109372846 _mtx_lock_spin: 0xc0ec1040 spin done 562 1 1863109373456 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 563 3 1863109374486 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 564 1 1863109374876 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 565 1 1863109377240 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 566 1 1863109379756 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:247 567 3 1863109382170 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 568 1 1863109383200 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 569 3 1863109385142 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 570 1 1863109385356 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 571 3 1863109386670 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 572 1 1863109389076 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:279 573 0 1863109389712 _mtx_lock_spin: 0xc0ec1040 spin done 574 0 1863109391184 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 575 1 1863109391608 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 576 1 1863109393076 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 577 1 1863109395804 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 578 1 1863109396840 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 579 0 1863109397612 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 580 1 1863109398288 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 581 1 1863109399516 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 582 0 1863109399744 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 583 0 1863109400796 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 584 1 1863109401564 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:247 585 2 1863109404070 _mtx_lock_spin: 0xc0ec1040 spin done 586 2 1863109404778 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 587 1 1863109410608 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:333 588 1 1863109411676 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:398 589 1 1863109412480 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:439 590 2 1863109413038 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 591 1 1863109413552 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:345 592 2 1863109415418 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 593 1 1863109417120 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:279 594 2 1863109417490 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 595 1 1863109418596 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 596 1 1863109420728 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 597 1 1863109422296 LOCK (spin mutex) sched lock r = 0 at ../../../kern/sched_4bsd.c:314 598 1 1863109424128 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/sched_4bsd.c:316 599 1 1863109425040 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 600 1 1863109425980 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 601 1 1863109426840 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 602 1 1863109427404 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 603 1 1863109428108 sleepq_timeout: thread 0xc5c7b480 (pid 2, g_event) 604 1 1863109428896 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:753 605 1 1863109429412 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:756 606 1 1863109430292 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 607 1 1863109431284 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:759 608 1 1863109431864 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:779 609 1 1863109432888 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:638 610 1 1863109433400 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:641 611 1 1863109434092 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:251 612 1 1863109434992 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:658 613 1 1863109435456 sleepq_wakeup: thread 0xc5c7b480 (pid 2, g_event) 614 1 1863109440412 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:668 615 0 1863109440908 _mtx_lock_spin: 0xc0ec1040 spinning 616 1 1863109442268 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 617 0 1863109443536 _mtx_lock_spin: 0xc0ec1040 spin done 618 1 1863109443696 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 619 2 1863109445048 _mtx_lock_spin: 0xc0ec1040 spinning 620 1 1863109446352 sleepq_timeout: thread 0xc5c7b900 (pid 89, yarrow) 621 3 1863109447172 _mtx_lock_spin: 0xc0ec1040 spinning 622 0 1863109448376 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 623 1 1863109450396 _mtx_lock_spin: 0xc0ec1040 spinning 624 0 1863109455308 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 625 0 1863109458500 mi_switch: new thread 0xc5c7b480 (kse 0xc5c7b5d0, pid 2, g_event) 626 0 1863109459168 sleepq resume: thread 0xc5c7b480 (pid 2, g_event) 627 0 1863109460128 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:581 628 3 1863109463252 _mtx_lock_spin: 0xc0ec1040 spin done 629 3 1863109464156 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 630 0 1863109464676 XLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:157 631 3 1863109469264 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 632 0 1863109470108 LOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:159 633 0 1863109473504 UNLOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:165 634 3 1863109474436 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 635 3 1863109475436 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 636 0 1863109475976 LOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:170 637 0 1863109477612 UNLOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:173 638 1 1863109478420 _mtx_lock_spin: 0xc0ec1040 spin done 639 1 1863109479380 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:753 640 1 1863109480268 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:756 641 0 1863109481064 XUNLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:174 642 2 1863109483100 _mtx_lock_spin: 0xc0ec1040 spin done 643 0 1863109483412 XLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:202 644 1 1863109483752 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 645 2 1863109484348 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 646 1 1863109486144 _mtx_lock_spin: 0xc0ec1040 spinning 647 2 1863109488552 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 648 0 1863109489164 XUNLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:209 649 2 1863109490860 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 650 0 1863109491672 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 651 2 1863109492148 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 652 0 1863109493036 msleep: thread 0xc5c7b480 (pid 2, g_event) on - (0xc08bd37c) 653 1 1863109495048 _mtx_lock_spin: 0xc0ec1040 spin done 654 1 1863109496128 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:759 655 0 1863109496392 _mtx_lock_spin: 0xc0ec1040 spinning 656 1 1863109497260 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:779 657 0 1863109499260 _mtx_lock_spin: 0xc0ec1040 spin done 658 1 1863109499652 _mtx_lock_spin: 0xc0ec1040 spinning 659 0 1863109500584 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:317 660 0 1863109501764 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:322 661 1 1863109503324 _mtx_lock_spin: 0xc0ec1040 spin done 662 0 1863109503672 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 663 1 1863109504456 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:638 664 0 1863109505404 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 665 1 1863109505820 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:641 666 1 1863109507020 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:251 667 0 1863109507356 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:216 668 1 1863109509072 _mtx_lock_spin: 0xc0ec1040 spinning 669 0 1863109509284 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:218 670 1 1863109511044 _mtx_lock_spin: 0xc0ec1040 spin done 671 0 1863109511296 _mtx_lock_spin: 0xc0ec1040 spinning 672 1 1863109512348 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:658 673 1 1863109513248 sleepq_wakeup: thread 0xc5c7b900 (pid 89, yarrow) 674 1 1863109518776 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:668 675 0 1863109520144 _mtx_lock_spin: 0xc0ec1040 spin done 676 1 1863109520372 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 677 2 1863109520846 _mtx_lock_spin: 0xc0ec1040 spinning 678 0 1863109523152 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:433 679 3 1863109523458 _mtx_lock_spin: 0xc0ec1040 spinning 680 1 1863109523800 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 681 0 1863109526116 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:434 682 1 1863109527752 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:247 683 0 1863109532532 mi_switch: old thread 0xc5c7b480 (kse 0xc5c7b5d0, pid 2, g_event) 684 1 1863109532984 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:333 685 1 1863109534312 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:398 686 0 1863109535684 mi_switch: new thread 0xc5c7b900 (kse 0xc5c7ba50, pid 89, yarrow) 687 1 1863109536056 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:439 688 1 1863109537320 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:345 689 0 1863109537616 sleepq resume: thread 0xc5c7b900 (pid 89, yarrow) 690 0 1863109538948 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:581 691 1 1863109541240 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:279 692 2 1863109541446 _mtx_lock_spin: 0xc0ec1040 spin done 693 0 1863109543404 LOCK (spin mutex) entropy harvest mutex r = 0 at ../../../dev/random/randomdev_soft.c:251 694 2 1863109543586 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 695 1 1863109544316 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 696 0 1863109545364 UNLOCK (spin mutex) entropy harvest mutex r = 0 at ../../../dev/random/randomdev_soft.c:283 697 1 1863109545956 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 698 0 1863109548224 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 699 1 1863109548448 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 700 2 1863109550922 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 701 1 1863109551304 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 702 0 1863109551676 msleep: thread 0xc5c7b900 (pid 89, yarrow) on - (0xc08b8720) 703 1 1863109552796 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 704 2 1863109554770 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 705 1 1863109555564 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 706 0 1863109556788 _mtx_lock_spin: 0xc0ec1040 spinning 707 2 1863109557178 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 708 1 1863109559384 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 709 0 1863109562168 _mtx_lock_spin: 0xc0ec1040 spin done 710 0 1863109563116 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:317 711 0 1863109564460 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:322 712 1 1863109565640 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 713 3 1863109566074 _mtx_lock_spin: 0xc0ec1040 spin done 714 0 1863109568340 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 715 3 1863109568842 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 716 1 1863109569264 _mtx_lock_spin: 0xc0ec2b60 spinning 717 0 1863109571912 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 718 1 1863109573548 _mtx_lock_spin: 0xc0ec2b60 spin done 719 0 1863109573784 _mtx_lock_spin: 0xc0ec1040 spinning 720 3 1863109575530 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 721 1 1863109575976 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 722 1 1863109577168 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 723 3 1863109577642 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 724 1 1863109579900 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 725 3 1863109580314 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 726 1 1863109581400 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 727 0 1863109585224 _mtx_lock_spin: 0xc0ec1040 spin done 728 0 1863109586508 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:216 729 1 1863109586812 _mtx_lock_spin: 0xc0ec1040 spinning 730 0 1863109587792 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:218 731 1 1863109589736 _mtx_lock_spin: 0xc0ec1040 spin done 732 0 1863109590060 _mtx_lock_spin: 0xc0ec1040 spinning 733 1 1863109591028 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 734 1 1863109591972 ithread_loop: pid 87: done 735 1 1863109596540 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 736 1 1863109599640 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 737 1 1863109600492 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 738 0 1863109601884 _mtx_lock_spin: 0xc0ec1040 spin done 739 0 1863109602720 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:433 740 0 1863109603660 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:434 741 0 1863109608136 mi_switch: old thread 0xc5c7b900 (kse 0xc5c7ba50, pid 89, yarrow) 742 0 1863109609472 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 743 0 1863109610040 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 744 0 1863128098748 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 745 0 1863139906228 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 746 0 1863139913216 swi_sched pid 87(swi4: clock sio) need=0 747 0 1863139913820 ithread_schedule: pid 87: (swi4: clock sio) need = 0 748 0 1863139914656 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 749 0 1863139915184 ithread_schedule: setrunqueue 87 750 0 1863139915988 maybe_preempt: in critical section 2 751 0 1863139917260 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 752 0 1863139921232 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 753 0 1863139924728 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 754 1 1863139926068 _mtx_lock_spin: 0xc0ec1040 spinning 755 2 1863139927178 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 756 0 1863139930144 _mtx_lock_spin: 0xc0ec1040 spinning 757 3 1863139930686 _mtx_lock_spin: 0xc0ec1040 spinning 758 2 1863139935306 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 759 2 1863139939702 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 760 2 1863139940402 ithread_loop: pid 87: resumed 761 2 1863139941338 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 762 2 1863139943454 ithread_loop: pid 87: (swi4: clock sio) need=1 763 1 1863139944092 _mtx_lock_spin: 0xc0ec1040 spin done 764 2 1863139944930 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 765 1 1863139946092 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 766 2 1863139949702 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 767 2 1863139951650 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 768 2 1863139953154 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 769 1 1863139954636 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 770 2 1863139954986 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 771 2 1863139956334 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 772 1 1863139958032 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 773 2 1863139959854 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 774 1 1863139960140 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 775 2 1863139962874 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 776 3 1863139963622 _mtx_lock_spin: 0xc0ec1040 spin done 777 3 1863139964666 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 778 2 1863139969918 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 779 3 1863139970758 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 780 2 1863139971578 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 781 2 1863139972598 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 782 3 1863139973050 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 783 3 1863139974018 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 784 2 1863139974626 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 785 0 1863139975904 _mtx_lock_spin: 0xc0ec1040 spin done 786 2 1863139976410 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 787 0 1863139976960 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 788 2 1863139980786 _mtx_lock_spin: 0xc0ec1040 spinning 789 0 1863139982908 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 790 0 1863139984772 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 791 0 1863139985692 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 792 2 1863139987318 _mtx_lock_spin: 0xc0ec1040 spin done 793 2 1863139988006 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 794 2 1863139988510 ithread_loop: pid 87: done 795 2 1863139993242 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 796 2 1863139994958 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 797 2 1863139995550 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 798 0 1863151979178 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 799 0 1863170473716 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 800 0 1863170481420 swi_sched pid 87(swi4: clock sio) need=0 801 0 1863170482160 ithread_schedule: pid 87: (swi4: clock sio) need = 0 802 0 1863170482864 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 803 0 1863170483680 ithread_schedule: setrunqueue 87 804 0 1863170485564 maybe_preempt: in critical section 2 805 0 1863170486528 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 806 0 1863170491168 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 807 0 1863170494880 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 808 1 1863170496544 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 809 2 1863170496924 _mtx_lock_spin: 0xc0ec1040 spinning 810 3 1863170499460 _mtx_lock_spin: 0xc0ec1040 spinning 811 0 1863170500212 _mtx_lock_spin: 0xc0ec1040 spinning 812 1 1863170502948 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 813 1 1863170507000 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 814 1 1863170507680 ithread_loop: pid 87: resumed 815 1 1863170508676 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 816 1 1863170510316 ithread_loop: pid 87: (swi4: clock sio) need=1 817 3 1863170510896 _mtx_lock_spin: 0xc0ec1040 spin done 818 1 1863170512336 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 819 3 1863170512852 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 820 1 1863170513704 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 821 1 1863170514968 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 822 1 1863170516860 sleepq_timeout: thread 0xc5c7b780 (pid 4, g_down) 823 1 1863170518664 _mtx_lock_spin: 0xc0ec1040 spinning 824 3 1863170520368 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 825 3 1863170522500 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 826 3 1863170523348 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 827 1 1863170526580 _mtx_lock_spin: 0xc0ec1040 spin done 828 1 1863170527504 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:753 829 1 1863170528428 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:756 830 2 1863170531284 _mtx_lock_spin: 0xc0ec1040 spin done 831 1 1863170531576 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 832 2 1863170532492 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 833 1 1863170535212 _mtx_lock_spin: 0xc0ec1040 spinning 834 2 1863170538836 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 835 2 1863170540452 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 836 2 1863170541260 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 837 1 1863170543456 _mtx_lock_spin: 0xc0ec1040 spin done 838 1 1863170544200 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:759 839 1 1863170545192 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:779 840 0 1863170546640 _mtx_lock_spin: 0xc0ec1040 spin done 841 1 1863170546924 _mtx_lock_spin: 0xc0ec1040 spinning 842 0 1863170548092 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 843 0 1863170554116 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 844 0 1863170556052 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 845 0 1863170556892 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 846 1 1863170558164 _mtx_lock_spin: 0xc0ec1040 spin done 847 1 1863170559660 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:638 848 1 1863170560240 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:641 849 1 1863170561136 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:251 850 1 1863170562116 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:658 851 1 1863170562588 sleepq_wakeup: thread 0xc5c7b780 (pid 4, g_down) 852 1 1863170567768 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:668 853 0 1863170568220 _mtx_lock_spin: 0xc0ec1040 spinning 854 1 1863170570060 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 855 2 1863170570374 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 856 1 1863170572612 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 857 3 1863170573298 _mtx_lock_spin: 0xc0ec1040 spinning 858 1 1863170574752 sleepq_timeout: thread 0xc5c7b600 (pid 3, g_up) 859 2 1863170577758 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 860 1 1863170578464 _mtx_lock_spin: 0xc0ec1040 spinning 861 2 1863170582118 mi_switch: new thread 0xc5c7b780 (kse 0xc5c7b8d0, pid 4, g_down) 862 2 1863170582894 sleepq resume: thread 0xc5c7b780 (pid 4, g_down) 863 2 1863170583874 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:581 864 1 1863170587224 _mtx_lock_spin: 0xc0ec1040 spin done 865 2 1863170588038 LOCK (sleep mutex) bio queue r = 0 at ../../../geom/geom_io.c:66 866 1 1863170588992 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:753 867 1 1863170590036 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:756 868 2 1863170592546 g_down going to sleep 869 3 1863170593674 _mtx_lock_spin: 0xc0ec1040 spin done 870 1 1863170594076 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 871 3 1863170595530 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 872 2 1863170595734 _mtx_lock_spin: 0xc0ec661c spinning 873 1 1863170596776 _mtx_lock_spin: 0xc0ec1040 spinning 874 3 1863170601478 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 875 3 1863170603582 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 876 3 1863170604530 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 877 1 1863170606612 _mtx_lock_spin: 0xc0ec1040 spin done 878 1 1863170607580 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:759 879 1 1863170608584 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:779 880 0 1863170610120 _mtx_lock_spin: 0xc0ec1040 spin done 881 1 1863170610696 _mtx_lock_spin: 0xc0ec1040 spinning 882 0 1863170611296 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 883 0 1863170616296 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 884 0 1863170618352 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 885 0 1863170619308 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 886 1 1863170620732 _mtx_lock_spin: 0xc0ec1040 spin done 887 1 1863170621980 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:638 888 1 1863170622480 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:641 889 1 1863170623352 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:251 890 1 1863170624500 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:658 891 2 1863170624850 _mtx_lock_spin: 0xc0ec661c spin done 892 1 1863170625200 sleepq_wakeup: thread 0xc5c7b600 (pid 3, g_up) 893 2 1863170625930 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 894 2 1863170626938 msleep: thread 0xc5c7b780 (pid 4, g_down) on - (0xc08bd388) 895 2 1863170628690 UNLOCK (sleep mutex) bio queue r = 0 at ../../../kern/kern_synch.c:188 896 1 1863170630348 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:668 897 0 1863170633416 _mtx_lock_spin: 0xc0ec1040 spinning 898 2 1863170634310 _mtx_lock_spin: 0xc0ec1040 spinning 899 1 1863170635988 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 900 3 1863170637066 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 901 1 1863170638772 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 902 1 1863170640324 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 903 1 1863170641352 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 904 1 1863170642604 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 905 1 1863170643992 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 906 3 1863170644726 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 907 1 1863170646152 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 908 3 1863170651362 mi_switch: new thread 0xc5c7b600 (kse 0xc5c7b750, pid 3, g_up) 909 1 1863170653056 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 910 3 1863170653626 sleepq resume: thread 0xc5c7b600 (pid 3, g_up) 911 1 1863170655408 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 912 3 1863170655810 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:581 913 1 1863170656764 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 914 1 1863170658356 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 915 1 1863170659212 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 916 0 1863170659488 _mtx_lock_spin: 0xc0ec1040 spin done 917 3 1863170661006 LOCK (sleep mutex) bio queue r = 0 at ../../../geom/geom_io.c:66 918 0 1863170662548 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 919 1 1863170663000 _mtx_lock_spin: 0xc0ec1040 spinning 920 3 1863170664994 g_up going to sleep 921 3 1863170666546 _mtx_lock_spin: 0xc0ec661c spinning 922 0 1863170669180 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 923 0 1863170671340 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 924 0 1863170672308 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 925 2 1863170674742 _mtx_lock_spin: 0xc0ec1040 spin done 926 2 1863170675946 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:317 927 2 1863170676930 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:322 928 1 1863170678508 _mtx_lock_spin: 0xc0ec1040 spin done 929 2 1863170680606 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 930 1 1863170680748 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 931 1 1863170681452 ithread_loop: pid 87: done 932 2 1863170682074 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 933 2 1863170683586 _mtx_lock_spin: 0xc0ec1040 spinning 934 1 1863170686388 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 935 1 1863170688960 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 936 1 1863170689792 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 937 2 1863170691810 _mtx_lock_spin: 0xc0ec1040 spin done 938 2 1863170692486 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:216 939 2 1863170693466 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:218 940 2 1863170694750 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:433 941 2 1863170695458 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:434 942 3 1863170696762 _mtx_lock_spin: 0xc0ec661c spin done 943 3 1863170697566 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 944 3 1863170698214 msleep: thread 0xc5c7b600 (pid 3, g_up) on - (0xc08bd384) 945 2 1863170700674 mi_switch: old thread 0xc5c7b780 (kse 0xc5c7b8d0, pid 4, g_down) 946 3 1863170700910 UNLOCK (sleep mutex) bio queue r = 0 at ../../../kern/kern_synch.c:188 947 3 1863170703262 _mtx_lock_spin: 0xc0ec1040 spinning 948 2 1863170704146 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 949 2 1863170705102 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 950 3 1863170706398 _mtx_lock_spin: 0xc0ec1040 spin done 951 3 1863170707266 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:317 952 3 1863170708298 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:322 953 3 1863170709402 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 954 3 1863170710066 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 955 3 1863170710854 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:216 956 3 1863170711778 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:218 957 3 1863170712590 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:433 958 3 1863170713090 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:434 959 3 1863170717714 mi_switch: old thread 0xc5c7b600 (kse 0xc5c7b750, pid 3, g_up) 960 3 1863170719214 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 961 3 1863170719806 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 962 0 1863175860852 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 963 0 1863199739216 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 964 0 1863201043554 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 965 0 1863201052062 swi_sched pid 87(swi4: clock sio) need=0 966 0 1863201052682 ithread_schedule: pid 87: (swi4: clock sio) need = 0 967 0 1863201053350 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 968 0 1863201053878 ithread_schedule: setrunqueue 87 969 0 1863201054686 maybe_preempt: in critical section 2 970 0 1863201055694 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 971 0 1863201059722 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 972 0 1863201064346 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 973 1 1863201064702 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 974 3 1863201066610 _mtx_lock_spin: 0xc0ec1040 spinning 975 2 1863201066870 _mtx_lock_spin: 0xc0ec1040 spinning 976 0 1863201070102 _mtx_lock_spin: 0xc0ec1040 spinning 977 1 1863201070342 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 978 1 1863201074562 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 979 1 1863201075234 ithread_loop: pid 87: resumed 980 1 1863201076398 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 981 1 1863201078118 ithread_loop: pid 87: (swi4: clock sio) need=1 982 3 1863201078678 _mtx_lock_spin: 0xc0ec1040 spin done 983 1 1863201080066 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 984 3 1863201080466 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 985 1 1863201082154 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 986 1 1863201083658 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 987 1 1863201085122 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 988 1 1863201086214 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 989 3 1863201087914 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 990 1 1863201088590 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 991 1 1863201089614 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 992 3 1863201091018 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 993 1 1863201093210 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 994 3 1863201093726 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 995 0 1863201097766 _mtx_lock_spin: 0xc0ec1040 spin done 996 0 1863201099154 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 997 1 1863201100822 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 998 1 1863201102290 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 999 1 1863201103506 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1000 1 1863201104734 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1001 0 1863201105058 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1002 1 1863201105830 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 1003 0 1863201108202 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1004 1 1863201108710 _mtx_lock_spin: 0xc0ec1040 spinning 1005 0 1863201109402 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1006 2 1863201113930 _mtx_lock_spin: 0xc0ec1040 spin done 1007 2 1863201114522 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1008 2 1863201119182 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1009 2 1863201120442 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1010 2 1863201121250 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1011 1 1863201122974 _mtx_lock_spin: 0xc0ec1040 spin done 1012 1 1863201123762 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 1013 1 1863201124358 ithread_loop: pid 87: done 1014 1 1863201128434 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1015 1 1863201130150 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1016 1 1863201130738 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1017 0 1863223619292 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1018 0 1863231611168 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 1019 0 1863231617928 swi_sched pid 87(swi4: clock sio) need=0 1020 0 1863231618560 ithread_schedule: pid 87: (swi4: clock sio) need = 0 1021 0 1863231619220 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 1022 0 1863231619772 ithread_schedule: setrunqueue 87 1023 0 1863231620676 maybe_preempt: in critical section 2 1024 0 1863231621712 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 1025 0 1863231625988 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1026 0 1863231629696 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1027 1 1863231630456 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1028 2 1863231631408 _mtx_lock_spin: 0xc0ec1040 spinning 1029 3 1863231632248 _mtx_lock_spin: 0xc0ec1040 spinning 1030 0 1863231635072 _mtx_lock_spin: 0xc0ec1040 spinning 1031 1 1863231636336 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1032 1 1863231639308 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1033 1 1863231640116 ithread_loop: pid 87: resumed 1034 1 1863231641064 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 1035 1 1863231642776 ithread_loop: pid 87: (swi4: clock sio) need=1 1036 1 1863231643724 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 1037 2 1863231644488 _mtx_lock_spin: 0xc0ec1040 spin done 1038 1 1863231645040 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 1039 2 1863231646120 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1040 1 1863231646540 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1041 1 1863231649620 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:247 1042 2 1863231653336 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1043 1 1863231654280 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:333 1044 2 1863231656620 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1045 1 1863231657360 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:398 1046 2 1863231658004 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1047 1 1863231660940 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:439 1048 0 1863231661736 _mtx_lock_spin: 0xc0ec1040 spin done 1049 1 1863231662456 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:345 1050 0 1863231662948 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1051 1 1863231667476 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:279 1052 0 1863231668636 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1053 1 1863231669212 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1054 1 1863231670264 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1055 0 1863231671012 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1056 0 1863231672056 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1057 1 1863231672372 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1058 1 1863231673448 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1059 3 1863231674352 _mtx_lock_spin: 0xc0ec1040 spin done 1060 1 1863231676136 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1061 3 1863231676332 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1062 1 1863231678448 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1063 1 1863231679712 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1064 1 1863231683116 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1065 3 1863231683624 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1066 1 1863231684208 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1067 1 1863231685960 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1068 3 1863231686248 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1069 1 1863231688840 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1070 3 1863231689560 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1071 1 1863231689952 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 1072 1 1863231693220 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 1073 1 1863231693840 ithread_loop: pid 87: done 1074 1 1863231697908 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1075 1 1863231699512 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1076 1 1863231700072 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1077 0 1863247500716 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1078 0 1863262178622 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 1079 0 1863262185490 swi_sched pid 87(swi4: clock sio) need=0 1080 0 1863262186122 ithread_schedule: pid 87: (swi4: clock sio) need = 0 1081 0 1863262186790 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 1082 0 1863262187314 ithread_schedule: setrunqueue 87 1083 0 1863262188122 maybe_preempt: in critical section 2 1084 0 1863262189310 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 1085 0 1863262193378 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1086 0 1863262197398 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1087 1 1863262197698 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1088 2 1863262198660 _mtx_lock_spin: 0xc0ec1040 spinning 1089 3 1863262198812 _mtx_lock_spin: 0xc0ec1040 spinning 1090 0 1863262202946 _mtx_lock_spin: 0xc0ec1040 spinning 1091 1 1863262203610 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1092 1 1863262206402 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1093 1 1863262207046 ithread_loop: pid 87: resumed 1094 1 1863262207918 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 1095 1 1863262210222 ithread_loop: pid 87: (swi4: clock sio) need=1 1096 1 1863262211214 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 1097 2 1863262211804 _mtx_lock_spin: 0xc0ec1040 spin done 1098 1 1863262213386 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 1099 2 1863262214096 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1100 1 1863262214982 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1101 1 1863262216762 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1102 1 1863262217554 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1103 1 1863262219022 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1104 1 1863262219838 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1105 2 1863262221044 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1106 1 1863262222350 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1107 2 1863262223884 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1108 2 1863262224964 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1109 0 1863262227218 _mtx_lock_spin: 0xc0ec1040 spin done 1110 0 1863262228078 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1111 1 1863262228406 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1112 1 1863262230106 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1113 1 1863262231766 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1114 1 1863262233102 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1115 1 1863262233942 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 1116 0 1863262234214 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1117 1 1863262237034 _mtx_lock_spin: 0xc0ec1040 spinning 1118 0 1863262237606 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1119 0 1863262238902 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1120 3 1863262241512 _mtx_lock_spin: 0xc0ec1040 spin done 1121 3 1863262242276 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1122 3 1863262246920 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1123 3 1863262248176 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1124 3 1863262249136 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1125 1 1863262250938 _mtx_lock_spin: 0xc0ec1040 spin done 1126 1 1863262251610 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 1127 1 1863262252238 ithread_loop: pid 87: done 1128 1 1863262256730 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1129 1 1863262258570 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1130 1 1863262259150 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1131 0 1863271380412 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1132 0 1863292748416 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 1133 0 1863292755532 swi_sched pid 87(swi4: clock sio) need=0 1134 0 1863292756148 ithread_schedule: pid 87: (swi4: clock sio) need = 0 1135 0 1863292756820 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 1136 0 1863292757384 ithread_schedule: setrunqueue 87 1137 0 1863292758264 maybe_preempt: in critical section 2 1138 0 1863292759504 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 1139 0 1863292763608 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1140 0 1863292767256 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1141 2 1863292769930 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1142 1 1863292770956 _mtx_lock_spin: 0xc0ec1040 spinning 1143 3 1863292771482 _mtx_lock_spin: 0xc0ec1040 spinning 1144 0 1863292773832 _mtx_lock_spin: 0xc0ec1040 spinning 1145 2 1863292779102 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1146 2 1863292784014 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1147 2 1863292784662 ithread_loop: pid 87: resumed 1148 2 1863292785650 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 1149 2 1863292788034 ithread_loop: pid 87: (swi4: clock sio) need=1 1150 0 1863292788816 _mtx_lock_spin: 0xc0ec1040 spin done 1151 2 1863292790646 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 1152 0 1863292791244 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1153 2 1863292793738 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 1154 2 1863292796662 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1155 2 1863292798054 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1156 2 1863292799382 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1157 0 1863292799716 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1158 2 1863292800954 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1159 2 1863292802330 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1160 0 1863292802740 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1161 0 1863292803752 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1162 2 1863292804514 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1163 3 1863292807662 _mtx_lock_spin: 0xc0ec1040 spin done 1164 3 1863292808914 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1165 2 1863292811002 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1166 2 1863292813650 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1167 3 1863292814178 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1168 2 1863292814870 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1169 2 1863292816382 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1170 3 1863292816718 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1171 2 1863292817498 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 1172 3 1863292818090 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1173 1 1863292820280 _mtx_lock_spin: 0xc0ec1040 spin done 1174 2 1863292821362 _mtx_lock_spin: 0xc0ec1040 spinning 1175 1 1863292821744 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1176 1 1863292827980 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1177 1 1863292829384 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1178 1 1863292830220 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1179 2 1863292831850 _mtx_lock_spin: 0xc0ec1040 spin done 1180 2 1863292832550 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 1181 2 1863292833058 ithread_loop: pid 87: done 1182 2 1863292837702 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1183 2 1863292839318 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1184 2 1863292839858 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1185 0 1863295262938 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1186 0 1863319141220 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1187 0 1863323315912 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 1188 0 1863323324196 swi_sched pid 87(swi4: clock sio) need=0 1189 0 1863323324800 ithread_schedule: pid 87: (swi4: clock sio) need = 0 1190 0 1863323325496 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 1191 0 1863323326732 ithread_schedule: setrunqueue 87 1192 0 1863323328104 maybe_preempt: in critical section 2 1193 0 1863323329028 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 1194 0 1863323333380 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1195 1 1863323336824 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1196 0 1863323337348 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1197 2 1863323338626 _mtx_lock_spin: 0xc0ec1040 spinning 1198 0 1863323342748 _mtx_lock_spin: 0xc0ec1040 spinning 1199 1 1863323342960 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1200 1 1863323347688 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1201 1 1863323348384 ithread_loop: pid 87: resumed 1202 1 1863323349316 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 1203 1 1863323351592 ithread_loop: pid 87: (swi4: clock sio) need=1 1204 1 1863323352808 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 1205 0 1863323353900 _mtx_lock_spin: 0xc0ec1040 spin done 1206 1 1863323354192 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 1207 0 1863323356100 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1208 1 1863323356820 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1209 1 1863323359180 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1210 1 1863323360096 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1211 0 1863323360908 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1212 1 1863323361640 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1213 1 1863323362672 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1214 0 1863323363200 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1215 0 1863323364248 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1216 1 1863323365392 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1217 2 1863323366166 _mtx_lock_spin: 0xc0ec1040 spin done 1218 2 1863323366774 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1219 1 1863323372624 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1220 2 1863323372954 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1221 1 1863323374260 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1222 2 1863323376006 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1223 1 1863323376252 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1224 2 1863323377354 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1225 1 1863323378004 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1226 1 1863323378656 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 1227 1 1863323379944 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 1228 1 1863323380568 ithread_loop: pid 87: done 1229 1 1863323385824 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1230 1 1863323387476 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1231 1 1863323388064 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1232 0 1863343021084 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1233 0 1863353885796 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 1234 0 1863353892748 swi_sched pid 87(swi4: clock sio) need=0 1235 0 1863353893380 ithread_schedule: pid 87: (swi4: clock sio) need = 0 1236 0 1863353894040 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 1237 0 1863353894564 ithread_schedule: setrunqueue 87 1238 0 1863353895372 maybe_preempt: in critical section 2 1239 0 1863353896312 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 1240 0 1863353900488 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1241 0 1863353905312 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1242 1 1863353905648 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1243 2 1863353906660 _mtx_lock_spin: 0xc0ec1040 spinning 1244 3 1863353908024 _mtx_lock_spin: 0xc0ec1040 spinning 1245 0 1863353910960 _mtx_lock_spin: 0xc0ec1040 spinning 1246 1 1863353911252 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1247 1 1863353915004 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1248 1 1863353915648 ithread_loop: pid 87: resumed 1249 1 1863353916700 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 1250 1 1863353918936 ithread_loop: pid 87: (swi4: clock sio) need=1 1251 3 1863353920276 _mtx_lock_spin: 0xc0ec1040 spin done 1252 1 1863353921104 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 1253 3 1863353921516 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1254 1 1863353922444 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 1255 1 1863353923552 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1256 1 1863353925784 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:247 1257 3 1863353929320 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1258 1 1863353930388 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:333 1259 3 1863353932108 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1260 1 1863353932676 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:398 1261 3 1863353934292 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1262 1 1863353935464 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:439 1263 1 1863353937096 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:345 1264 0 1863353937732 _mtx_lock_spin: 0xc0ec1040 spin done 1265 0 1863353939004 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1266 1 1863353940812 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:279 1267 1 1863353942476 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1268 1 1863353943428 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1269 0 1863353944260 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1270 1 1863353944952 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1271 1 1863353945888 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1272 0 1863353946424 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1273 0 1863353947464 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1274 1 1863353947692 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1275 1 1863353948992 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1276 2 1863353949524 _mtx_lock_spin: 0xc0ec1040 spin done 1277 2 1863353950220 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1278 1 1863353952292 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1279 2 1863353956004 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1280 1 1863353956592 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1281 2 1863353958592 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1282 1 1863353959120 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1283 2 1863353959580 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1284 1 1863353960432 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1285 1 1863353963492 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1286 1 1863353964040 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 1287 1 1863353965328 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 1288 1 1863353965824 ithread_loop: pid 87: done 1289 1 1863353970040 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1290 1 1863353971672 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1291 1 1863353972264 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1292 0 1863366902756 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1293 0 1863384453236 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 1294 0 1863384460052 swi_sched pid 87(swi4: clock sio) need=0 1295 0 1863384460728 ithread_schedule: pid 87: (swi4: clock sio) need = 0 1296 0 1863384461384 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 1297 0 1863384461924 ithread_schedule: setrunqueue 87 1298 0 1863384462720 maybe_preempt: in critical section 2 1299 0 1863384464056 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 1300 0 1863384468292 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1301 0 1863384471860 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1302 2 1863384473516 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1303 1 1863384474628 _mtx_lock_spin: 0xc0ec1040 spinning 1304 3 1863384475096 _mtx_lock_spin: 0xc0ec1040 spinning 1305 0 1863384478464 _mtx_lock_spin: 0xc0ec1040 spinning 1306 2 1863384482096 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1307 2 1863384486720 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1308 2 1863384487480 ithread_loop: pid 87: resumed 1309 2 1863384488312 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 1310 0 1863384491316 _mtx_lock_spin: 0xc0ec1040 spin done 1311 2 1863384491716 ithread_loop: pid 87: (swi4: clock sio) need=1 1312 0 1863384492520 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1313 2 1863384493852 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 1314 2 1863384496568 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 1315 2 1863384498468 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1316 2 1863384499916 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1317 0 1863384500272 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1318 2 1863384501700 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1319 0 1863384503424 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1320 2 1863384504296 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1321 0 1863384504764 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1322 2 1863384506168 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1323 3 1863384508164 _mtx_lock_spin: 0xc0ec1040 spin done 1324 2 1863384509036 LOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:811 1325 3 1863384509456 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1326 3 1863384515172 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1327 2 1863384516332 UNLOCK (sleep mutex) nfsd_mtx r = 0 at ../../../nfsserver/nfs_srvsock.c:822 1328 3 1863384517344 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1329 2 1863384517852 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1330 3 1863384518792 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1331 2 1863384519164 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1332 1 1863384521184 _mtx_lock_spin: 0xc0ec1040 spin done 1333 2 1863384521612 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1334 1 1863384522260 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1335 2 1863384523580 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:305 1336 2 1863384525272 _mtx_lock_spin: 0xc0ec1040 spinning 1337 1 1863384528072 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1338 1 1863384530080 mi_switch: new thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1339 1 1863384530876 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1340 2 1863384532724 _mtx_lock_spin: 0xc0ec1040 spin done 1341 2 1863384533300 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:610 1342 2 1863384533796 ithread_loop: pid 87: done 1343 2 1863384538568 mi_switch: old thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1344 2 1863384540220 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1345 2 1863384540840 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1346 0 1863390783212 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1347 0 1863414663692 intr_execute_handlers: executing handler 0xc0796e54(0xe810dca0) 1348 0 1863415022958 intr_execute_handlers: executing handler 0xc0796b14(0xe810dca0) 1349 0 1863415031254 swi_sched pid 87(swi4: clock sio) need=0 1350 0 1863415031858 ithread_schedule: pid 87: (swi4: clock sio) need = 0 1351 0 1863415032562 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:407 1352 0 1863415033382 ithread_schedule: setrunqueue 87 1353 0 1863415035062 maybe_preempt: in critical section 2 1354 0 1863415035922 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:416 1355 0 1863415040342 LOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:268 1356 1 1863415043770 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1357 0 1863415044090 UNLOCK (spin mutex) icu r = 0 at ../../../i386/isa/atpic.c:270 1358 2 1863415045948 _mtx_lock_spin: 0xc0ec1040 spinning 1359 1 1863415049302 mi_switch: old thread 0xc5be5900 (kse 0xc5be5a50, pid 13, idle: cpu1) 1360 0 1863415049522 _mtx_lock_spin: 0xc0ec1040 spinning 1361 1 1863415055018 mi_switch: new thread 0xc5c7b180 (kse 0xc5c7b2d0, pid 87, swi4: clock sio) 1362 1 1863415055698 ithread_loop: pid 87: resumed 1363 1 1863415056542 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_intr.c:617 1364 1 1863415058782 ithread_loop: pid 87: (swi4: clock sio) need=1 1365 1 1863415059794 ithread_loop: pid 87 ih=0xc5c90ac0: 0xc0616360(0x0) flg=80000000 1366 1 1863415060986 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:201 1367 0 1863415061886 _mtx_lock_spin: 0xc0ec1040 spin done 1368 1 1863415062178 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1369 0 1863415063766 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1370 1 1863415065630 LOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:247 1371 0 1863415069186 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1372 0 1863415071462 mi_switch: new thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1373 0 1863415074094 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1374 1 1863415075198 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:333 1375 2 1863415076192 _mtx_lock_spin: 0xc0ec1040 spin done 1376 2 1863415077240 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1377 1 1863415078018 LOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:398 1378 1 1863415078986 UNLOCK (spin mutex) callout r = 1 at ../../../kern/kern_timeout.c:439 1379 1 1863415079810 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:345 1380 2 1863415082804 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1381 1 1863415083398 UNLOCK (sleep mutex) Giant r = 0 at ../../../kern/kern_timeout.c:279 1382 1 1863415084606 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1383 2 1863415084868 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1384 2 1863415086716 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1385 1 1863415087538 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1386 1 1863415089254 LOCK (spin mutex) sched lock r = 0 at ../../../kern/sched_4bsd.c:314 1387 1 1863415091706 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/sched_4bsd.c:316 1388 1 1863415092626 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1389 1 1863415093862 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1390 1 1863415094786 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1391 1 1863415095546 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1392 1 1863415096286 sleepq_timeout: thread 0xc5c7b480 (pid 2, g_event) 1393 1 1863415096930 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:753 1394 1 1863415097446 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:756 1395 1 1863415098682 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 1396 1 1863415099998 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:759 1397 1 1863415100554 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:779 1398 1 1863415101742 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:638 1399 1 1863415102386 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:641 1400 1 1863415103094 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:251 1401 1 1863415104018 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:658 1402 1 1863415104482 sleepq_wakeup: thread 0xc5c7b480 (pid 2, g_event) 1403 1 1863415109454 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:668 1404 0 1863415109898 _mtx_lock_spin: 0xc0ec1040 spinning 1405 1 1863415111370 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:280 1406 0 1863415112306 _mtx_lock_spin: 0xc0ec1040 spin done 1407 2 1863415113630 _mtx_lock_spin: 0xc0ec1040 spinning 1408 0 1863415114130 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1409 1 1863415114350 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:245 1410 3 1863415115134 _mtx_lock_spin: 0xc0ec1040 spinning 1411 1 1863415116390 sleepq_timeout: thread 0xc5c7b900 (pid 89, yarrow) 1412 1 1863415119430 _mtx_lock_spin: 0xc0ec1040 spinning 1413 0 1863415121914 mi_switch: old thread 0xc5be5a80 (kse 0xc5be5bd0, pid 14, idle: cpu0) 1414 0 1863415125098 mi_switch: new thread 0xc5c7b480 (kse 0xc5c7b5d0, pid 2, g_event) 1415 0 1863415125754 sleepq resume: thread 0xc5c7b480 (pid 2, g_event) 1416 0 1863415126666 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:581 1417 3 1863415129614 _mtx_lock_spin: 0xc0ec1040 spin done 1418 0 1863415130930 XLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:157 1419 3 1863415131258 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1420 0 1863415136326 LOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:159 1421 3 1863415137786 mi_switch: old thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1422 0 1863415138682 UNLOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:165 1423 3 1863415141038 mi_switch: new thread 0xc5be5600 (kse 0xc5be5750, pid 11, idle: cpu3) 1424 0 1863415141514 LOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:170 1425 3 1863415142454 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1426 0 1863415144338 UNLOCK (sleep mutex) GEOM orphanage r = 0 at ../../../geom/geom_event.c:173 1427 1 1863415146078 _mtx_lock_spin: 0xc0ec1040 spin done 1428 1 1863415147394 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:753 1429 0 1863415148314 XUNLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:174 1430 1 1863415148942 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:756 1431 2 1863415150974 _mtx_lock_spin: 0xc0ec1040 spin done 1432 0 1863415151502 XLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:202 1433 1 1863415152450 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 1434 2 1863415152742 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:120 1435 1 1863415154726 _mtx_lock_spin: 0xc0ec1040 spinning 1436 2 1863415157042 mi_switch: old thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1437 0 1863415158126 XUNLOCK (sx) GEOM topology r = 0 at ../../../geom/geom_event.c:209 1438 2 1863415159682 mi_switch: new thread 0xc5be5780 (kse 0xc5be58d0, pid 12, idle: cpu2) 1439 0 1863415160610 LOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:219 1440 2 1863415161910 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_idle.c:128 1441 0 1863415162634 msleep: thread 0xc5c7b480 (pid 2, g_event) on - (0xc08bd37c) 1442 1 1863415164910 _mtx_lock_spin: 0xc0ec1040 spin done 1443 0 1863415165858 _mtx_lock_spin: 0xc0ec1040 spinning 1444 1 1863415166546 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:759 1445 1 1863415168158 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:779 1446 0 1863415169926 _mtx_lock_spin: 0xc0ec1040 spin done 1447 1 1863415170374 _mtx_lock_spin: 0xc0ec1040 spinning 1448 0 1863415171162 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:317 1449 0 1863415172358 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:322 1450 1 1863415173758 _mtx_lock_spin: 0xc0ec1040 spin done 1451 0 1863415173982 LOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:398 1452 1 1863415175046 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:638 1453 0 1863415175874 UNLOCK (spin mutex) callout r = 0 at ../../../kern/kern_timeout.c:439 1454 1 1863415176322 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:641 1455 0 1863415178026 LOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:216 1456 1 1863415178358 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:251 1457 0 1863415179578 UNLOCK (spin mutex) sched lock r = 0 at ../../../kern/kern_synch.c:218 1458 1 1863415180386 _mtx_lock_spin: 0xc0ec1040 spinning 1459 0 1863415181490 LOCK (spin mutex) sched lock r = 0 at ../../../kern/subr_sleepqueue.c:433 1460 0 1863415182450 UNLOCK (spin mutex) sleepq chain r = 0 at ../../../kern/subr_sleepqueue.c:434 1461 0 1863415187382 mi_switch: old thread 0xc5c7b480 (kse 0xc5c7b5d0, pid 2, g_event) 1462 0 1863415189630 mi_switch