Hello everyone,
sorry, I'm two days late, but here, as I promised, are the results of a
rerun of the diagnostic commands I initially run as well as the dtrace
commands you guys send me to troubleshoot my misbehaving system (see
attachements).
These are the results after I did
#eeprom acpi-user-options=0x8
and a subsequent reboot of my OI box. CPU load now is where it should
be, and has remained there through several reboots of the system. The
original problem never showed up again.
I'm posting this just in case anyone is interested in comparing some of
the numbers/infos.
Once again, thanks to all who have contributed their help. I'm still
positively surprised how quickly you guys reacted and solved my problem.
You've been great! :)
Regards,
Gernot Wolf
gernot@tintenfass:/root# ./dtrace_script
dtrace: script './dtrace_script' matched 4 probes
^C
CPU ID FUNCTION:NAME
0 2 :END DEVICE TIME (ns)
heci0 27006
i9151 27429
uhci1 52216
hci13940 64814
uhci0 73509
uhci3 80426
ehci0 126846
ehci1 126999
uhci4 129084
uhci2 131898
e1000g0 220297
pci-ide0 2304660
gernot@tintenfass:~# dtrace -n 'sched:::off-cpu { @[execname]=count()}'
dtrace: description 'sched:::off-cpu ' matched 3 probes
^C
idmapd 1
in.routed 1
inetd 1
intrd 1
ipmgmtd 1
miniserv.pl 1
sendmail 1
sshd 1
svc.startd 1
ttymon 1
automountd 2
fmd 2
mdnsd 2
sac 2
gnome-power-mana 4
devfsadm 5
fsflush 9
ntpd 9
smbd 10
gdm-simple-greet 17
Xorg 18
dtrace 20
svc.configd 82
nscd 84
sched 2530
gernot@tintenfass:~# dtrace -n 'syscall:::entry { @[execname]=count()}'
dtrace: description 'syscall:::entry ' matched 234 probes
^C
idmapd 1
inetd 1
nscd 1
svc.configd 1
svc.startd 1
fmd 2
gconfd-2 3
at-spi-registryd 5
mdnsd 6
devfsadm 8
sendmail 10
smbd 10
gnome-power-mana 14
sshd 19
metacity 23
ntpd 45
gdm-simple-greet 116
Xorg 323
dtrace 2633
gernot@tintenfass:~# dtrace -n 'profile-1001 { @[stack()] = count() }'
dtrace: description 'profile-1001 ' matched 1 probe
^C
unix`i86_monitor+0x10
unix`cpu_idle_mwait+0xbe
unix`idle+0x114
unix`thread_start+0x8
1
unix`mutex_enter+0x10
genunix`taskq_thread_wait+0x84
genunix`taskq_thread+0x308
unix`thread_start+0x8
1
unix`mutex_enter+0x10
genunix`cyclic_softint+0xdc
unix`cbe_softclock+0x1a
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
unix`mutex_enter+0x10
genunix`cdev_ioctl+0x45
specfs`spec_ioctl+0x5a
genunix`fop_ioctl+0x7b
genunix`ioctl+0x18e
unix`sys_syscall+0x17a
1
unix`mutex_enter+0x10
unix`page_free+0x10d
unix`page_destroy+0x104
unix`segkmem_free_vn+0xe6
unix`segkmem_free+0x27
genunix`vmem_xfree+0x104
genunix`vmem_free+0x29
unix`kfreea+0x54
unix`i_ddi_mem_free+0x5d
rootnex`rootnex_teardown_copybuf+0x24
rootnex`rootnex_coredma_unbindhdl+0x90
rootnex`rootnex_dma_unbindhdl+0x35
genunix`ddi_dma_unbind_handle+0x41
ata`ghd_dmafree_attr+0x2b
ata`ata_disk_memfree+0x20
gda`gda_free+0x39
dadk`dadk_iodone+0xbf
dadk`dadk_pktcb+0xc6
ata`ata_disk_complete+0x119
ata`ata_hba_complete+0x38
1
i915`i915_gem_retire_requests+0xc
i915`i915_gem_retire_work_handler+0x3d
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_execute+0x1e
genunix`taskq_thread+0x285
unix`thread_start+0x8
1
SDC`sysdc_update+0x1f5
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_execute+0x1e
genunix`taskq_thread+0x285
unix`thread_start+0x8
1
genunix`fsflush_do_pages+0xb3
genunix`fsflush+0x39a
unix`thread_start+0x8
1
unix`cpu_idle_exit+0x1fc
unix`cpu_idle_mwait+0xfb
unix`idle+0x114
unix`thread_start+0x8
1
unix`tsc_read+0x5
genunix`gethrtime_unscaled+0xd
genunix`syscall_mstate+0x4a
unix`sys_syscall+0x10e
1
unix`tsc_read+0x5
genunix`gethrtime_unscaled+0xd
genunix`new_mstate+0x4b
unix`trap+0x1fc
unix`0xfffffffffb8001d6
1
unix`page_nextn+0xfe
genunix`fsflush_do_pages+0x104
genunix`fsflush+0x39a
unix`thread_start+0x8
1
unix`tsc_read+0x9
genunix`gethrtime_unscaled+0xd
genunix`syscall_mstate+0x4a
unix`0xfffffffffb800c86
1
unix`sys_syscall32+0x102
1
genunix`ioctl+0x4a
unix`sys_syscall+0x17a
1
unix`do_splx+0x8d
unix`xc_common+0x231
unix`xc_call+0x46
unix`hat_tlb_inval+0x283
unix`x86pte_inval+0xaa
unix`hat_pte_unmap+0xed
unix`hat_unload_callback+0x193
unix`hat_unload+0x41
unix`segkmem_free_vn+0x6f
unix`segkmem_free+0x27
genunix`vmem_xfree+0x104
genunix`vmem_free+0x29
unix`kfreea+0x54
unix`i_ddi_mem_free+0x5d
rootnex`rootnex_teardown_copybuf+0x24
rootnex`rootnex_coredma_unbindhdl+0x90
rootnex`rootnex_dma_unbindhdl+0x35
genunix`ddi_dma_unbind_handle+0x41
ata`ghd_dmafree_attr+0x2b
ata`ata_disk_memfree+0x20
1
unix`page_nextn+0x12
genunix`fsflush_do_pages+0x104
genunix`fsflush+0x39a
unix`thread_start+0x8
1
unix`page_nextn+0x14
genunix`fsflush_do_pages+0x104
genunix`fsflush+0x39a
unix`thread_start+0x8
1
genunix`callout_downheap+0x7e
genunix`callout_heap_delete+0x18b
genunix`callout_normal+0x27
genunix`cyclic_softint+0xdc
unix`cbe_softclock+0x1a
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
genunix`fsflush_do_pages+0x104
genunix`fsflush+0x39a
unix`thread_start+0x8
1
genunix`syscall_mstate+0x3e
unix`sys_syscall+0x1a1
1
unix`tsc_gethrtime+0x65
genunix`gethrtime+0xd
genunix`timeout_generic+0x46
genunix`timeout+0x5b
SDC`sysdc_update+0x311
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_execute+0x1e
genunix`taskq_thread+0x285
unix`thread_start+0x8
1
unix`page_nextn+0x46
genunix`fsflush_do_pages+0x104
genunix`fsflush+0x39a
unix`thread_start+0x8
1
sha1`sha1_block_data_order+0xdff
1
unix`ddi_get16+0x10
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_execute+0x1e
genunix`taskq_thread+0x285
unix`thread_start+0x8
1
unix`cpu_idle_enter+0x109
unix`cpu_idle_mwait+0xdc
unix`idle+0x114
unix`thread_start+0x8
1
unix`page_lookup_create+0xd0
unix`page_lookup+0x26
genunix`swap_getapage+0xaf
genunix`swap_getpage+0x85
genunix`fop_getpage+0x9a
genunix`anon_zero+0xa3
genunix`segvn_faultpage+0x2a4
genunix`segvn_fault+0xc13
genunix`as_fault+0x5ee
unix`pagefault+0x99
unix`trap+0xe63
unix`0xfffffffffb8001d6
1
genunix`fsflush_do_pages+0x31d
genunix`fsflush+0x39a
unix`thread_start+0x8
1
genunix`cv_unsleep+0x64
genunix`setrun_locked+0x96
genunix`setrun+0x1e
genunix`cv_wakeup+0x36
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_realtime+0x26
genunix`cyclic_softint+0xdc
unix`cbe_low_level+0x17
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
unix`tsc_gethrtime+0x8d
genunix`gethrtime+0xd
genunix`lbolt_event_driven+0x18
genunix`ddi_get_lbolt+0xd
unix`setbackdq+0x122
genunix`sleepq_wakeone_chan+0x89
genunix`cv_signal+0x8e
genunix`taskq_dispatch+0x37c
genunix`callout_normal+0x121
genunix`cyclic_softint+0xdc
unix`cbe_softclock+0x1a
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
dtrace`dtrace_dynvar_clean+0x31
dtrace`dtrace_state_clean+0x23
genunix`cyclic_softint+0xdc
unix`cbe_low_level+0x17
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
genunix`pcache_poll+0x480
genunix`poll_common+0x427
genunix`pollsys+0xea
unix`sys_syscall+0x17a
1
genunix`syscall_mstate+0x81
unix`sys_syscall+0x1a1
1
TS`ts_setrun+0x1d
genunix`cv_unsleep+0x8c
genunix`setrun_locked+0x96
genunix`setrun+0x1e
genunix`cv_wakeup+0x36
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_realtime+0x26
genunix`cyclic_softint+0xdc
unix`cbe_low_level+0x17
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
rootnex`rootnex_get_sgl+0x1
rootnex`rootnex_dma_bindhdl+0x4c
genunix`ddi_dma_buf_bind_handle+0x117
ata`ghd_dma_buf_bind_attr+0x98
ata`ata_disk_memsetup+0xc3
gda`gda_pktprep+0xab
dadk`dadk_pktprep+0x3a
dadk`dadk_pkt+0x4d
strategy`dmult_enque+0x7d
dadk`dadk_strategy+0x7d
cmdk`cmdkstrategy+0x16c
genunix`bdev_strategy+0x75
genunix`ldi_strategy+0x59
zfs`vdev_disk_io_start+0xd0
zfs`zio_vdev_io_start+0x1ea
zfs`zio_execute+0x8d
genunix`taskq_thread+0x285
unix`thread_start+0x8
1
genunix`fsflush_do_pages+0x358
genunix`fsflush+0x39a
unix`thread_start+0x8
1
genunix`read
1
unix`kcopy+0x1b
genunix`copyin_nowatch+0x48
genunix`copyin_args32+0x3a
genunix`syscall_entry+0xb3
unix`_sys_sysenter_post_swapgs+0x12a
1
unix`cmt_ev_thread_swtch+0x2c
unix`pg_ev_thread_swtch+0x10d
unix`swtch+0xdb
genunix`cv_wait_sig_swap_core+0x174
genunix`cv_wait_sig_swap+0x18
genunix`cv_waituntil_sig+0x13c
genunix`poll_common+0x47f
genunix`pollsys+0xea
unix`sys_syscall+0x17a
1
unix`page_nextn+0x9a
genunix`fsflush_do_pages+0x104
genunix`fsflush+0x39a
unix`thread_start+0x8
1
genunix`cpu_decay+0x27
genunix`cpu_update_pct+0x89
unix`setbackdq+0x2b3
genunix`sleepq_wakeone_chan+0x89
genunix`cv_signal+0x8e
genunix`taskq_dispatch+0x37c
genunix`callout_normal+0x121
genunix`cyclic_softint+0xdc
unix`cbe_softclock+0x1a
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
unix`ddi_io_put8+0xf
ata`ata_disk_start_dma_out+0x88
ata`ata_ctlr_fsm+0x1fb
ata`ata_hba_start+0x84
ata`ghd_waitq_process_and_mutex_hold+0xdf
ata`ghd_intr+0x8d
ata`ata_intr+0x27
unix`av_dispatch_autovect+0x7c
unix`dispatch_hardint+0x33
unix`switch_sp_and_call+0x13
1
unix`ddi_io_put8+0xf
ata`ata_disk_start_common+0xda
ata`ata_disk_start_dma_out+0x32
ata`ata_ctlr_fsm+0x1fb
ata`ata_hba_start+0x84
ata`ghd_waitq_process_and_mutex_hold+0xdf
ata`ghd_intr+0x8d
ata`ata_intr+0x27
unix`av_dispatch_autovect+0x7c
unix`dispatch_hardint+0x33
unix`switch_sp_and_call+0x13
1
unix`rw_exit+0xf
genunix`callout_heap_delete+0x1e3
genunix`callout_realtime+0x1e
genunix`cyclic_softint+0xdc
unix`cbe_low_level+0x17
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
unix`setbackdq+0x328
genunix`cv_unsleep+0x8c
genunix`setrun_locked+0x96
genunix`setrun+0x1e
genunix`cv_wakeup+0x36
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_realtime+0x26
genunix`cyclic_softint+0xdc
unix`cbe_low_level+0x17
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
unix`av_check_softint_pending+0x8
unix`av_dispatch_softvect+0x48
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
genunix`thread_lock+0x41
genunix`setrun+0x16
genunix`cv_wakeup+0x36
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_realtime+0x26
genunix`cyclic_softint+0xdc
unix`cbe_low_level+0x17
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
unix`splr+0x92
genunix`thread_lock+0x1d
genunix`post_syscall+0x669
unix`0xfffffffffb800c91
1
genunix`fsflush_do_pages+0x395
genunix`fsflush+0x39a
unix`thread_start+0x8
1
unix`plcnt_inc_dec+0xa3
unix`page_ctr_sub_internal+0x5d
unix`page_ctr_sub+0x78
unix`page_get_mnode_freelist+0x346
unix`page_get_anylist+0x200
unix`page_create_io+0x1e8
unix`page_create_io_wrapper+0x57
unix`segkmem_xalloc+0xc0
unix`segkmem_alloc_io_4G+0x3b
genunix`vmem_xalloc+0x546
genunix`vmem_alloc+0x161
unix`kalloca+0x203
unix`i_ddi_mem_alloc+0x173
rootnex`rootnex_setup_copybuf+0x11f
rootnex`rootnex_bind_slowpath+0x70
rootnex`rootnex_coredma_bindhdl+0x334
rootnex`rootnex_dma_bindhdl+0x4c
genunix`ddi_dma_buf_bind_handle+0x117
ata`ghd_dma_buf_bind_attr+0x98
ata`ata_disk_memsetup+0xc3
1
unix`mul32+0xd
genunix`scalehrtime+0x19
genunix`cpu_update_pct+0x76
unix`setbackdq+0x2b3
genunix`sleepq_wakeone_chan+0x89
genunix`cv_signal+0x8e
genunix`taskq_dispatch+0x37c
genunix`callout_normal+0x121
genunix`cyclic_softint+0xdc
unix`cbe_softclock+0x1a
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
1
genunix`cyclic_timer+0x89
genunix`cyclic_softint+0xdc
unix`cbe_softclock+0x1a
unix`av_dispatch_softvect+0x5f
unix`dispatch_softint+0x34
unix`switch_sp_and_call+0x13
2
unix`atomic_swap_64+0x7
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_execute+0x1e
genunix`taskq_thread+0x285
unix`thread_start+0x8
2
unix`page_nextn
genunix`fsflush+0x39a
unix`thread_start+0x8
2
unix`ddi_io_put16+0x10
genunix`callout_list_expire+0x77
genunix`callout_expire+0x31
genunix`callout_execute+0x1e
genunix`taskq_thread+0x285
unix`thread_start+0x8
2
genunix`fsflush_do_pages+0x2fa
genunix`fsflush+0x39a
unix`thread_start+0x8
3
genunix`fsflush_do_pages+0x13e
genunix`fsflush+0x39a
unix`thread_start+0x8
4
unix`dispatch_softint+0x27
unix`switch_sp_and_call+0x13
5
genunix`fsflush_do_pages+0x124
genunix`fsflush+0x39a
unix`thread_start+0x8
7
18
unix`i86_mwait+0xd
unix`cpu_idle_mwait+0xf1
unix`idle+0x114
unix`thread_start+0x8
16862
gernot@tintenfass:~# dtrace -n 'sched:::off-cpu { @[stack()]=count()}'
dtrace: description 'sched:::off-cpu ' matched 3 probes
^C
unix`swtch_from_zombie+0x96
genunix`lwp_exit+0x3cd
genunix`syslwp_exit+0x22
unix`_sys_sysenter_post_swapgs+0x149
1
unix`swtch+0x145
genunix`cv_wait+0x61
mac`mac_rx_srs_poll_ring+0xa0
unix`thread_start+0x8
1
unix`swtch+0x145
unix`preempt+0xd7
genunix`post_syscall+0x651
genunix`syscall_exit+0x59
unix`0xfffffffffb800ec9
1
unix`swtch+0x145
unix`preempt+0xd7
unix`trap+0x1503
unix`sys_rtt_common+0x68
unix`_sys_rtt_ints_disabled+0x8
1
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_reltimedwait+0x4f
genunix`taskq_thread_wait+0x74
genunix`taskq_d_thread+0x144
unix`thread_start+0x8
2
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_reltimedwait+0x4f
idm`idm_wd_thread+0x1d7
unix`thread_start+0x8
2
unix`swtch+0x145
unix`preempt+0xd7
genunix`post_syscall+0x651
unix`0xfffffffffb800c91
2
unix`swtch+0x145
genunix`cv_timedwait_sig_hires+0x1e9
genunix`cv_waituntil_sig+0xba
genunix`lwp_park+0x157
genunix`syslwp_park+0x31
unix`sys_syscall32+0xff
3
unix`swtch+0x145
genunix`cv_wait+0x61
zfs`txg_thread_wait+0x5f
zfs`txg_sync_thread+0x1de
unix`thread_start+0x8
3
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_reltimedwait+0x4f
scsi`scsi_watch_thread+0x330
unix`thread_start+0x8
3
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_timedwait+0x5a
zfs`txg_thread_wait+0x7c
zfs`txg_sync_thread+0x118
unix`thread_start+0x8
3
unix`swtch+0x145
genunix`cv_wait_sig_swap_core+0x174
genunix`cv_wait_sig_swap+0x18
genunix`cv_waituntil_sig+0x13c
genunix`poll_common+0x47f
genunix`pollsys+0xea
unix`_sys_sysenter_post_swapgs+0x149
3
unix`swtch+0x145
genunix`cv_wait+0x61
zfs`txg_thread_wait+0x5f
zfs`txg_quiesce_thread+0x94
unix`thread_start+0x8
6
unix`swtch+0x145
genunix`cv_wait+0x61
genunix`fsflush+0x201
unix`thread_start+0x8
9
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_reltimedwait+0x4f
smbsrv`smb_thread_continue_timedwait_locked+0x45
smbsrv`smb_thread_continue_timedwait+0x3c
smbsrv`smb_server_timers+0x5d
smbsrv`smb_thread_entry_point+0x69
unix`thread_start+0x8
9
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_reltimedwait+0x4f
genunix`seg_pasync_thread+0xcb
unix`thread_start+0x8
9
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_timedwait+0x5a
zfs`l2arc_feed_thread+0xa1
unix`thread_start+0x8
9
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_timedwait+0x5a
zfs`arc_reclaim_thread+0x13d
unix`thread_start+0x8
9
unix`swtch+0x145
genunix`cv_wait_sig_swap_core+0x174
genunix`cv_wait_sig_swap+0x18
genunix`sigsuspend+0x107
unix`_sys_sysenter_post_swapgs+0x149
9
unix`swtch+0x145
genunix`cv_timedwait_sig_hires+0x1e9
genunix`cv_waituntil_sig+0xba
genunix`lwp_park+0x157
genunix`syslwp_park+0x31
unix`_sys_sysenter_post_swapgs+0x149
10
unix`swtch+0x145
genunix`cv_timedwait_sig_hires+0x1e9
genunix`cv_waituntil_sig+0xba
genunix`nanosleep+0x120
unix`_sys_sysenter_post_swapgs+0x149
10
unix`swtch+0x145
genunix`cv_wait_sig_swap_core+0x174
genunix`cv_wait_sig_swap+0x18
genunix`cv_waituntil_sig+0x13c
genunix`poll_common+0x47f
genunix`pollsys+0xea
unix`sys_syscall+0x17a
18
unix`swtch+0x145
genunix`cv_timedwait_sig_hires+0x1e9
genunix`cv_waituntil_sig+0xba
genunix`lwp_park+0x157
genunix`syslwp_park+0x31
unix`sys_syscall+0x17a
19
unix`swtch_to+0xe6
unix`idle+0xb8
unix`thread_start+0x8
24
unix`swtch+0x145
genunix`cv_timedwait_sig_hires+0x1e9
genunix`cv_waituntil_sig+0xba
genunix`poll_common+0x47f
genunix`pollsys+0xea
unix`_sys_sysenter_post_swapgs+0x149
25
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_reltimedwait+0x4f
sata`sata_event_daemon+0xfe
unix`thread_start+0x8
182
unix`swtch+0x145
genunix`cv_timedwait_hires+0xe0
genunix`cv_reltimedwait+0x4f
stmf`stmf_svc_timeout+0x23a
stmf`stmf_svc+0x129
genunix`taskq_thread+0x285
unix`thread_start+0x8
453
unix`swtch+0x145
genunix`cv_wait+0x61
genunix`taskq_thread_wait+0x84
genunix`taskq_thread+0x308
unix`thread_start+0x8
690
unix`swtch+0x145
unix`idle+0xc4
unix`thread_start+0x8
1064
gernot@tintenfass:~# intrstat 30
device | cpu0 %tim cpu1 %tim
-------------+------------------------------
e1000g#0 | 0 0,0 3 0,0
ehci#0 | 3 0,0 0 0,0
ehci#1 | 0 0,0 3 0,0
hci1394#0 | 1 0,0 0 0,0
heci#0 | 1 0,0 0 0,0
i915#1 | 1 0,0 0 0,0
pci-ide#0 | 0 0,0 2 0,0
uhci#0 | 1 0,0 0 0,0
uhci#1 | 0 0,0 0 0,0
uhci#2 | 0 0,0 3 0,0
uhci#3 | 1 0,0 0 0,0
uhci#4 | 3 0,0 0 0,0
gernot@tintenfass:~# iostat 5 10
tty cmdk0 sd0 sd1 sd2 cpu
tin tout kps tps serv kps tps serv kps tps serv kps tps serv us sy wt id
0 0 2 0 15 164 2 11 169 2 10 164 2 11 0 1 0 99
0 47 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
0 16 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
0 16 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
0 16 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
0 16 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
0 16 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
0 16 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
0 16 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
0 16 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 100
gernot@tintenfass:~# lockstat -kIW -D 20 sleep 30
Profiling interrupt: 5840 events in 30.107 seconds (194 events/sec)
Count indv cuml rcnt nsec Hottest CPU+PIL Caller
-------------------------------------------------------------------------------
5795 99% 99% 0.00 1464 cpu[0] i86_mwait
23 0% 100% 0.00 1754 cpu[1] fsflush_do_pages
9 0% 100% 0.00 3766 cpu[0] (usermode)
7 0% 100% 0.00 1619 cpu[1] page_nextn
1 0% 100% 0.00 2717 cpu[1] pcacheset_resolve
1 0% 100% 0.00 2365 cpu[0]+11 restore_mstate
1 0% 100% 0.00 4830 cpu[1] page_unlock
1 0% 100% 0.00 1157 cpu[1] do_splx
1 0% 100% 0.00 3023 cpu[1] htable_release
1 0% 100% 0.00 2268 cpu[1] hment_compare
-------------------------------------------------------------------------------
gernot@tintenfass:~# mpstat 5 10
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 2 0 2 389 178 462 6 21 11 0 74 0 1 0 99
1 1 0 2 225 139 417 4 21 15 0 64 0 1 0 99
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 0 0 1 309 106 168 0 8 4 0 102 0 0 0 100
1 0 0 1 103 50 121 0 8 6 0 44 0 0 0 100
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 0 0 1 310 106 134 0 6 1 0 85 0 0 0 100
1 0 0 2 138 62 158 0 5 0 0 49 0 0 0 99
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 0 0 0 307 106 135 0 7 1 0 79 0 0 0 100
1 0 0 0 138 61 151 0 8 1 0 27 0 1 0 99
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 1 0 0 307 106 144 0 5 1 0 109 0 0 0 100
1 0 0 0 128 59 145 0 6 2 0 36 0 0 0 100
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 0 0 0 306 105 137 0 4 1 0 79 0 0 0 100
1 0 0 0 130 63 141 0 4 0 0 23 0 1 0 99
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 0 0 0 306 105 144 0 6 3 0 82 0 0 0 100
1 0 0 0 134 61 162 0 6 0 0 71 0 0 0 99
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 0 0 1 308 106 164 0 5 1 0 75 0 0 0 100
1 0 0 1 101 43 106 0 5 0 0 29 0 0 0 100
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 0 0 0 309 107 133 0 8 1 0 81 0 0 0 100
1 0 0 0 139 55 149 0 8 1 0 45 0 1 0 99
CPU minf mjf xcal intr ithr csw icsw migr smtx srw syscl usr sys wt idl
0 0 0 1 311 107 149 0 6 1 0 69 0 0 0 100
1 0 0 1 116 51 125 0 6 0 0 40 0 0 0 100
gernot@tintenfass:~# powertop -t 30 -d 1
OpenIndiana PowerTOP version 1.2 (C) 2009 Intel Corporation
Collecting data for 30.00 second(s)
C-states (idle power) Avg Residency
C0 (cpu running) (0.0%)
C1 4.8ms (100.0%)
P-states (frequencies)
1200 Mhz 88.9%
1800 Mhz 11.1%
Wakeups-from-idle per second: 484.2 interval: 30.0s
Top causes for wakeups:
37.9% (183.6) sched :<xcalls> unix`dtrace_xcall_func
20.7% (100.0) <kernel> :genunix`clock
16.6% ( 80.2) <kernel> :genunix`cv_wakeup
10.3% ( 50.0) <kernel> :SDC`sysdc_update
4.0% ( 19.3) <kernel> :uhci`uhci_handle_root_hub_status_change
2.1% ( 10.0) <kernel> :ata`ghd_timeout
1.6% ( 7.7) <kernel> :ehci`ehci_handle_root_hub_status_change
1.0% ( 5.0) <kernel> :uhci`uhci_cmd_timeout_hdlr
0.9% ( 4.3) <interrupt> :pci-ide#0
0.8% ( 4.0) <kernel> :genunix`schedpaging
0.4% ( 2.0) <kernel> :cpudrv`cpudrv_monitor_disp
0.3% ( 1.7) <kernel> :i915`i915_gem_retire_work_handler
0.3% ( 1.7) <interrupt> :e1000g#0
0.3% ( 1.6) intrd :<xcalls> unix`hati_demap_func
0.2% ( 1.0) <kernel> :TS`ts_update
0.2% ( 1.0) <kernel> :e1000g`e1000g_local_timer
0.2% ( 1.0) <kernel> :genunix`clock_realtime_fire
0.2% ( 1.0) <interrupt> :uhci#0
0.2% ( 1.0) <interrupt> :uhci#1
0.2% ( 1.0) <interrupt> :ehci#0
0.2% ( 1.0) <interrupt> :uhci#3
0.2% ( 1.0) <interrupt> :uhci#2
0.2% ( 1.0) <interrupt> :ehci#1
0.2% ( 1.0) <interrupt> :uhci#4
0.2% ( 0.7) sched :<xcalls> unix`hati_demap_func
0.1% ( 0.6) sched :<xcalls> unix`speedstep_pstate_transition
0.1% ( 0.5) <kernel> :heci`heci_wd_timer
0.1% ( 0.3) <kernel> :kcf`rnd_handler
0.0% ( 0.2) <kernel> :ahci`ahci_watchdog_handler
0.0% ( 0.2) zpool-rpool :<xcalls> unix`speedstep_pstate_transition
0.0% ( 0.1) <kernel> :swrand`rnd_handler
0.0% ( 0.1) <kernel> :ip`igmp_slowtimo
0.0% ( 0.1) <kernel> :ip`squeue_fire
0.0% ( 0.1) <kernel> :genunix`vmem_update
0.0% ( 0.1) smbd :<xcalls> unix`speedstep_pstate_transition
0.0% ( 0.1) <kernel> :ip`tcp_timer_callback
0.0% ( 0.1) <kernel> :genunix`kmem_update
0.0% ( 0.0) <kernel> :genunix`realitexpire
powertop: battery kstat not found (-1)
no ACPI power usage estimate available
PID USERNAME SIZE RSS STATE PRI NICE TIME CPU PROCESS/NLWP
1777 gdm 187M 35M sleep 59 0 0:00:16 0,1% gdm-simple-gree/1
1909 root 4568K 3364K cpu0 59 0 0:00:00 0,0% prstat/1
1713 root 395M 84M sleep 59 0 0:00:10 0,0% Xorg/1
389 root 6768K 4280K sleep 59 0 0:00:05 0,0% nscd/30
721 root 1984K 1120K sleep 59 0 0:00:00 0,0% smbiod-svc/2
738 root 14M 9228K sleep 59 0 0:00:13 0,0% smbd/16
692 root 19M 12M sleep 59 0 0:00:05 0,0% fmd/27
678 root 4168K 2320K sleep 59 0 0:00:00 0,0% rmvolmgr/1
644 daemon 5452K 3284K sleep 59 0 0:00:01 0,0% idmapd/5
626 root 1620K 980K sleep 59 0 0:00:00 0,0% utmpd/1
622 root 4600K 3484K sleep 59 0 0:00:01 0,0% inetd/4
693 root 4216K 2188K sleep 59 0 0:00:00 0,0% syslogd/11
635 root 2880K 1640K sleep 59 0 0:00:00 0,0% automountd/2
636 root 2952K 1548K sleep 59 0 0:00:00 0,0% automountd/4
612 root 2164K 1352K sleep 59 0 0:00:00 0,0% sac/1
615 root 1988K 1440K sleep 59 0 0:00:00 0,0% ttymon/1
1776 gdm 113M 13M sleep 59 0 0:00:00 0,0% metacity/1
603 daemon 3204K 1304K sleep 59 0 0:00:00 0,0% rpcbind/1
599 root 4400K 3348K sleep 59 0 0:00:00 0,0% console-kit-dae/2
1769 gdm 7772K 5872K sleep 59 0 0:00:00 0,0% at-spi-registry/1
698 root 4388K 1920K sleep 59 0 0:00:00 0,0% sshd/1
796 root 7252K 3660K sleep 59 0 0:00:02 0,0% miniserv.pl/1
700 noaccess 2520K 1624K sleep 59 0 0:00:01 0,0% mdnsd/1
1863 gernot 7748K 4852K sleep 59 0 0:00:00 0,0% sshd/1
235 root 2164K 1612K sleep 59 0 0:00:00 0,0% powerd/4
561 root 2728K 1692K sleep 59 0 0:00:03 0,0% in.routed/1
330 root 3408K 2092K sleep 59 0 0:00:00 0,0% dbus-daemon/1
764 root 6012K 1492K sleep 59 0 0:00:00 0,0% cnid_metad/1
555 root 3496K 2128K sleep 59 0 0:00:03 0,0% hald-addon-acpi/1
502 root 2388K 1184K sleep 59 0 0:00:00 0,0% in.ndpd/1
715 root 2164K 1344K sleep 59 0 0:00:00 0,0% dns-sd/1
1755 gdm 15M 10M sleep 59 0 0:00:00 0,0% gnome-session/2
249 root 4616K 3308K sleep 59 0 0:00:03 0,0% devfsadm/6
316 root 1980K 956K sleep 59 0 0:00:00 0,0% iscsid/2
551 root 4292K 2736K sleep 59 0 0:00:00 0,0% hald-addon-cpuf/1
714 root 2164K 1344K sleep 59 0 0:00:00 0,0% dns-sd/1
766 root 8008K 3144K sleep 59 0 0:00:00 0,0% afpd/1
563 root 2832K 2076K sleep 59 0 0:00:00 0,0% hald-addon-stor/3
459 root 3784K 2308K sleep 59 0 0:00:00 0,0% hald-runner/1
456 root 7436K 6080K sleep 59 0 0:00:04 0,0% hald/4
543 root 3896K 2340K sleep 59 0 0:00:00 0,0% hald-addon-netw/1
1712 root 6196K 4344K sleep 59 0 0:00:00 0,0% gdm-simple-slav/2
168 root 6020K 3184K sleep 59 0 0:00:00 0,0% syseventd/18
1779 root 4236K 3100K sleep 59 0 0:00:00 0,0% gdm-session-wor/1
684 root 2340K 1456K sleep 59 0 0:00:00 0,0% ttymon/1
346 root 6264K 3592K sleep 59 0 0:00:11 0,0% ntpd/1
252 root 3800K 2784K sleep 59 0 0:00:00 0,0% picld/4
124 root 0K 0K sleep 99 -20 0:13:39 0,0% zpool-tank/138
46 netcfg 3440K 2540K sleep 59 0 0:00:00 0,0% netcfgd/3
178 root 2484K 1500K sleep 60 -20 0:00:00 0,0% zonestatd/5
579 root 2256K 1392K sleep 59 0 0:00:00 0,0% cron/1
777 root 6668K 5564K sleep 59 0 0:00:16 0,0% intrd/1
49 netadm 3860K 2744K sleep 59 0 0:00:01 0,0% ipmgmtd/4
47 root 2980K 2024K sleep 59 0 0:00:00 0,0% dlmgmtd/6
12 root 14M 13M sleep 59 0 0:00:19 0,0% svc.configd/17
10 root 13M 11M sleep 59 0 0:00:05 0,0% svc.startd/13
143 root 2548K 1628K sleep 59 0 0:00:00 0,0% pfexecd/3
1 root 2720K 1852K sleep 59 0 0:00:00 0,0% init/1
6 root 0K 0K sleep 99 -20 0:00:02 0,0% zpool-rpool/138
Total: 77 processes, 528 lwps, load averages: 0,01, 0,10, 0,11
last pid: 1917; load avg: 0.01, 0.06, 0.10; up 2+18:06:01
17:59:17
79 processes: 78 sleeping, 1 on cpu
CPU states: 99.7% idle, 0.0% user, 0.3% kernel, 0.0% iowait, 0.0% swap
Kernel: 300 ctxsw, 1 trap, 457 intr, 122 syscall
Memory: 8118M phys mem, 1110M free mem, 4058M total swap, 4058M free swap
PID USERNAME NLWP PRI NICE SIZE RES STATE TIME CPU COMMAND
3 root 1 60 -20 0K 0K sleep 12:05 0.14% fsflush
1777 gdm 1 59 0 187M 35M sleep 0:16 0.06% gdm-simple-gree
561 root 1 59 0 2728K 1692K sleep 0:03 0.04% in.routed
1916 root 1 59 0 3884K 2208K cpu/0 0:00 0.03% top
1713 root 1 59 0 395M 84M sleep 0:10 0.03% Xorg
346 root 1 59 0 6264K 3592K sleep 0:11 0.00% ntpd
1778 gdm 1 59 0 172M 18M sleep 0:00 0.00% gnome-power-man
1863 gernot 1 59 0 7788K 4868K sleep 0:00 0.00% sshd
738 root 16 59 0 14M 9228K sleep 0:13 0.00% smbd
10 root 13 59 0 13M 11M sleep 0:05 0.00% svc.startd
622 root 4 59 0 4600K 3484K sleep 0:01 0.00% inetd
692 root 26 59 0 19M 12M sleep 0:05 0.00% fmd
747 root 1 59 0 6020K 2168K sleep 0:02 0.00% sendmail
249 root 6 59 0 4616K 3308K sleep 0:03 0.00% devfsadm
700 noaccess 1 59 0 2520K 1624K sleep 0:01 0.00% mdnsd
124 root 138 99 -20 0K 0K sleep 13:39 0.00% zpool-tank
12 root 17 59 0 14M 13M sleep 0:19 0.00% svc.configd
777 root 1 59 0 6668K 5564K sleep 0:16 0.00% intrd
389 root 30 59 0 6768K 4280K sleep 0:05 0.00% nscd
456 root 4 59 0 7436K 6080K sleep 0:04 0.00% hald
555 root 1 59 0 3496K 2128K sleep 0:03 0.00% hald-addon-acpi
6 root 138 99 -20 0K 0K sleep 0:02 0.00% zpool-rpool
796 root 1 59 0 7252K 3660K sleep 0:02 0.00% miniserv.pl
644 daemon 5 59 0 5452K 3284K sleep 0:01 0.00% idmapd
49 netadm 4 59 0 3860K 2744K sleep 0:01 0.00% ipmgmtd
2 root 2 98 -20 0K 0K sleep 0:00 0.00% pageout
1859 root 1 60 0 9796K 2384K sleep 0:00 0.00% afpd
178 root 5 60 -20 2484K 1500K sleep 0:00 0.00% zonestatd
4 root 3 60 -20 0K 0K sleep 0:00 0.00% kcfpoold
1771 gdm 1 59 0 202M 49M sleep 0:00 0.00% gnome-settings-
1776 gdm 1 59 0 113M 13M sleep 0:00 0.00% metacity
1755 gdm 2 59 0 15M 10M sleep 0:00 0.00% gnome-session
1768 gdm 1 59 0 8196K 6992K sleep 0:00 0.00% gconfd-2
1769 gdm 1 59 0 7772K 5872K sleep 0:00 0.00% at-spi-registry
1856 root 1 59 0 19M 5464K sleep 0:00 0.00% cnid_dbd
1900 root 1 59 0 19M 5156K sleep 0:00 0.00% cnid_dbd
1773 gdm 2 59 0 7164K 5152K sleep 0:00 0.00% bonobo-activati
1712 root 2 59 0 6196K 4344K sleep 0:00 0.00% gdm-simple-slav
811 root 2 59 0 4684K 3524K sleep 0:00 0.00% gdm-binary
599 root 2 59 0 4400K 3348K sleep 0:00 0.00% console-kit-dae
1862 root 1 59 0 5804K 3192K sleep 0:00 0.00% sshd
168 root 17 59 0 6020K 3184K sleep 0:00 0.00% syseventd
766 root 1 59 0 8008K 3144K sleep 0:00 0.00% afpd
1779 root 1 59 0 4236K 3100K sleep 0:00 0.00% gdm-session-wor
1775 gdm 1 59 0 4116K 2968K sleep 0:00 0.00% gvfsd
252 root 4 59 0 3800K 2784K sleep 0:00 0.00% picld
551 root 1 59 0 4292K 2736K sleep 0:00 0.00% hald-addon-cpuf
46 netcfg 3 59 0 3440K 2540K sleep 0:00 0.00% netcfgd
1878 root 1 59 0 3816K 2520K sleep 0:00 0.00% bash
1866 gernot 1 59 0 3816K 2516K sleep 0:00 0.00% bash
543 root 1 59 0 3896K 2340K sleep 0:00 0.00% hald-addon-netw
678 root 1 59 0 4168K 2320K sleep 0:00 0.00% rmvolmgr
459 root 1 59 0 3784K 2308K sleep 0:00 0.00% hald-runner
693 root 11 59 0 4216K 2188K sleep 0:00 0.00% syslogd
330 root 1 59 0 3408K 2092K sleep 0:00 0.00% dbus-daemon
gernot@tintenfass:~# vmstat 5 10
kthr memory page disk faults cpu
r b w swap free re mf pi po fr de sr cd s0 s1 s2 in sy cs us sy id
0 0 0 5195360 2094636 1 4 0 0 0 0 1 0 2 2 2 616 140 882 0 1 99
0 0 0 4236680 1137564 4 11 0 0 0 0 0 0 0 0 0 434 183 323 0 0 100
0 0 0 4236600 1137512 0 0 0 0 0 0 0 0 0 0 0 433 120 289 0 0 100
0 0 0 4236600 1137512 0 0 0 0 0 0 0 0 0 0 0 434 106 288 0 0 100
0 0 0 4236600 1137512 0 0 0 0 0 0 0 0 0 0 0 434 99 284 0 0 100
0 0 0 4236600 1137528 0 0 0 0 0 0 0 0 0 0 0 417 109 280 0 0 100
0 0 0 4236600 1137560 0 0 0 0 0 0 0 0 0 0 0 432 149 306 0 0 100
0 0 0 4236600 1137560 0 0 0 0 0 0 0 0 0 0 0 451 124 305 0 0 100
0 0 0 4236600 1137560 0 0 0 0 0 0 0 0 0 0 0 453 104 294 0 0 100
0 0 0 4236584 1137544 0 1 0 0 0 0 0 0 0 0 0 439 140 298 0 0 100
gernot@tintenfass:~# vmstat -i
interrupt total rate
--------------------------------
clock 23805444 100
audiohd 0 0
ecppc0 0 0
--------------------------------
Total 23805444 100
gernot@tintenfass:~# vmstat -s
0 swap ins
0 swap outs
0 pages swapped in
0 pages swapped out
836130 total address trans. faults taken
3 page ins
0 page outs
3 pages paged in
0 pages paged out
168367 total reclaims
168367 reclaims from free list
0 micro (hat) faults
836130 minor (as) faults
3 major faults
137112 copy-on-write faults
384370 zero fill page faults
145404 pages examined by the clock daemon
0 revolutions of the clock hand
0 pages freed by the clock daemon
1482 forks
435 vforks
1365 execs
209981025 cpu context switches
146541748 device interrupts
1697366 traps
33376874 system calls
3003291 total name lookups (cache hits 92%)
48690 user cpu
430826 system cpu
47131950 idle cpu
0 wait cpu
_______________________________________________
OpenIndiana-discuss mailing list
OpenIndiana-discuss@openindiana.org
http://openindiana.org/mailman/listinfo/openindiana-discuss
