Script started on Sat Mar 8 18:00:59 2008 foo# lockstat sysbench --num-threads=${i} --max-requests=0 --max-time=30 --test= oltp --db-driver=mysql --mysql-user=root --mysql-db=test --oltp-table-size=10000 00 --mysql-socket=/tmp/mysql.sock --oltp-read-only runlockstat sysbench --num-threads={i} --max-requests=0 --max-time=30 --test=ooltp --db-driver=mysql --mysql-user=root --mysql-db=test --oltp-table-size=1000000 --mysql-socket=/tmp/mysql.sock --oltp-read-only runi} --max-requests=0 --max-time=30 --test=olltp --db-driver=mysql --mysql-user=root --mysql-db=test --oltp-table-size=10000000 --mysql-socket=/tmp/mysql.sock --oltp-read-only run} --max-requests=0 --max-time=30 --test=olttp --db-driver=mysql --mysql-user=root --mysql-db=test --oltp-table-size=1000000 --mysql-socket=/tmp/mysql.sock --oltp-read-only run --max-requests=0 --max-time=30 --test=oltpp --db-driver=mysql --mysql-user=root --mysql-db=test --oltp-table-size=1000000 --mysql-socket=/tmp/mysql.sock --oltp-read-only runp --foo# lockstat sysbench --num-threads= --max-requests=0 --max-time=30 --test=oltp --db-driver=mysql --mysql-user=root --mysql-db=test --oltp-table-size=1000000 - -mysql-socket=/tmp/mysql.sock --oltp-read-only runp --foo# lockstat sysbench --num-threads= --max-requests=0 --max-time=30 --test=oltp --db-driver=mysql --mysql-user=root --mysql-db=test --oltp-table-size=1000000 - -mysql-socket=/tmp/mysql.sock --oltp-read-only run 8 --max-requests=0 --max-time=30 --test=olt p --db-driver=mysql --mysql-user=root --mysql-db=test --oltp-table-size=1000000 --mysql-socket=/tmp/mysql.sock --oltp-read-only runtp - sysbench v0.4.8: multi-threaded system evaluation benchmark WARNING: Preparing of "BEGIN" is unsupported, using emulation (last message repeated 7 times) Running the test with following options: Number of threads: 8 Doing OLTP test. Running mixed OLTP test Doing read-only test Using Special distribution (12 iterations, 1 pct of values are returned in 75 pct cases) Using "BEGIN" for starting transactions Using auto_inc on the id column Threads started! Time limit exceeded, exiting... (last message repeated 7 times) Done. OLTP test statistics: queries performed: read: 1797432 write: 0 other: 256776 total: 2054208 transactions: 128388 (4279.43 per sec.) deadlocks: 0 (0.00 per sec.) read/write requests: 1797432 (59911.96 per sec.) other operations: 256776 (8558.85 per sec.) Test execution summary: total time: 30.0012s total number of events: 128388 total time taken by event execution: 238.6539 per-request statistics: min: 0.0014s avg: 0.0019s max: 0.0819s approx. 95 percentile: 0.0021s Threads fairness: events (avg/stddev): 16048.5000/209.41 execution time (avg/stddev): 29.8317/0.00 Elapsed time: 30.10 seconds. -- Adaptive mutex spin Total% Count Time/ms Lock Caller ------ ------- --------- ---------------------- ------------------------------ 61.90 3 99.21 ffff800067616578 uvm_aio_aiodone_pages+305 38.09 13 61.04 ffff800067616368 uvm_aio_aiodone_pages+305 0.01 12 0.02 ffff800067564c40 pmap_map_ptes+19a 0.00 1 0.00 uvm_pageqlock ubc_fault+21c -- Adaptive mutex sleep Total% Count Time/ms Lock Caller ------ ------- --------- ---------------------- ------------------------------ 100.00 28 0.33 sme_mtx sme_events_worker+2a -- Spin mutex spin Total% Count Time/ms Lock Caller ------ ------- --------- ---------------------- ------------------------------ 95.85 146790 88.31 select_lock 49.64 70776 45.73 select_lock pollcommon+1b2 21.69 30328 19.99 select_lock pollcommon+315 19.87 35917 18.31 select_lock selrecord+23 4.65 9767 4.29 select_lock selnotify+2d 0.00 1 0.00 select_lock selcommon+2c4 0.00 1 0.00 select_lock selcommon+685 3.42 2963 3.15 lwp_park_tab+940 1.97 1776 1.81 lwp_park_tab+940 lwp_unpark+45 0.87 698 0.81 lwp_park_tab+940 lwp_park+d6 0.57 476 0.52 lwp_park_tab+940 sys__lwp_unpark_all+112 0.00 7 0.00 lwp_park_tab+940 sys__lwp_unpark_all+20f 0.00 6 0.00 lwp_park_tab+940 sys__lwp_unpark_all+24a 0.22 273 0.21 ffff8000674287f8 0.20 232 0.18 ffff8000674287f8 sys___sigprocmask14+57 0.02 32 0.02 ffff8000674287f8 __sigtimedwait1+174 0.00 6 0.00 ffff8000674287f8 cv_timedwait_sig+12f 0.00 2 0.00 ffff8000674287f8 sys__lwp_kill+a4 0.00 1 0.00 ffff8000674287f8 sleepq_block+135 0.08 21 0.07 turnstile_tab+600 0.08 20 0.07 turnstile_tab+600 turnstile_lookup+24 0.00 1 0.00 turnstile_tab+600 turnstile_block+238 0.07 45 0.07 lwp_park_tab+1c40 0.04 30 0.03 lwp_park_tab+1c40 lwp_unpark+45 0.03 12 0.03 lwp_park_tab+1c40 lwp_park+d6 0.00 3 0.00 lwp_park_tab+1c40 sys__lwp_unpark_all+112 0.06 53 0.06 ffff800065383cc0 0.05 35 0.04 ffff800065383cc0 sleepq_remove+cd 0.01 12 0.01 ffff800065383cc0 mi_switch+20d 0.00 4 0.00 ffff800065383cc0 mi_switch+74 0.00 2 0.00 ffff800065383cc0 sched_nextlwp+1f9 0.06 33 0.05 lwp_park_tab+1a40 0.05 31 0.05 lwp_park_tab+1a40 lwp_park+d6 0.00 2 0.00 lwp_park_tab+1a40 sys__lwp_unpark_all+112 0.05 3 0.05 ffff800022443a98 comintr+53 0.04 29 0.04 lwp_park_tab+a00 0.04 26 0.03 lwp_park_tab+a00 lwp_park+d6 0.00 3 0.00 lwp_park_tab+a00 sys__lwp_unpark_all+112 0.02 16 0.02 ffff800065383fc0 0.01 11 0.01 ffff800065383fc0 sleepq_remove+cd 0.01 4 0.00 ffff800065383fc0 mi_switch+20d 0.00 1 0.00 ffff800065383fc0 mi_switch+74 0.02 10 0.02 lwp_park_tab+980 0.01 7 0.01 lwp_park_tab+980 lwp_park+d6 0.00 2 0.00 lwp_park_tab+980 sys__lwp_unpark_all+112 0.00 1 0.00 lwp_park_tab+980 lwp_unpark+45 0.01 7 0.01 lwp_park_tab+cc0 0.01 4 0.01 lwp_park_tab+cc0 lwp_park+d6 0.00 2 0.00 lwp_park_tab+cc0 sys__lwp_unpark_all+112 0.00 1 0.00 lwp_park_tab+cc0 lwp_unpark+45 0.01 4 0.01 lwp_park_tab+1a00 0.01 3 0.01 lwp_park_tab+1a00 lwp_park+d6 0.00 1 0.00 lwp_park_tab+1a00 sys__lwp_unpark_all+112 0.01 7 0.01 ffff800065383f40 0.01 5 0.01 ffff800065383f40 sleepq_remove+cd 0.00 1 0.00 ffff800065383f40 mi_switch+20d 0.00 1 0.00 ffff800065383f40 sched_nextlwp+1f9 0.01 5 0.01 lwp_park_tab+11c0 0.01 4 0.01 lwp_park_tab+11c0 lwp_park+d6 0.00 1 0.00 lwp_park_tab+11c0 sys__lwp_unpark_all+112 0.01 7 0.01 ffff800065383d40 0.01 4 0.01 ffff800065383d40 sleepq_remove+cd 0.00 2 0.00 ffff800065383d40 mi_switch+20d 0.00 1 0.00 ffff800065383d40 mi_switch+74 0.01 5 0.01 lwp_park_tab+14c0 0.00 3 0.00 lwp_park_tab+14c0 lwp_unpark+45 0.00 1 0.00 lwp_park_tab+14c0 lwp_park+d6 0.00 1 0.00 lwp_park_tab+14c0 sys__lwp_unpark_all+112 0.00 2 0.00 lwp_park_tab+1680 lwp_park+d6 0.00 4 0.00 ffff800065383dc0 0.00 3 0.00 ffff800065383dc0 sleepq_remove+cd 0.00 1 0.00 ffff800065383dc0 sched_pstats+e2 0.00 3 0.00 sleeptab+1240 0.00 2 0.00 sleeptab+1240 sched_pstats+e2 0.00 1 0.00 sleeptab+1240 ltsleep+d4 0.00 4 0.00 sleeptab+1640 0.00 1 0.00 sleeptab+1640 cv_wait+bc 0.00 2 0.00 sleeptab+1640 wakeup+53 0.00 1 0.00 sleeptab+1640 cv_signal+55 0.00 5 0.00 ffff800065383c00 sleepq_remove+cd 0.00 4 0.00 ffff800065383e40 0.00 2 0.00 ffff800065383e40 sleepq_remove+cd 0.00 1 0.00 ffff800065383e40 mi_switch+20d 0.00 1 0.00 ffff800065383e40 sched_pstats+e2 0.00 3 0.00 ffff800065383d80 sched_pstats+e2 0.00 3 0.00 ffff800065383f80 0.00 1 0.00 ffff800065383f80 sched_pstats+e2 0.00 1 0.00 ffff800065383f80 ltsleep+e0 0.00 1 0.00 ffff800065383f80 preempt+2a 0.00 4 0.00 uvm_fpageqlock uvm_pagealloc_strat+3a 0.00 1 0.00 lwp_park_tab+1480 lwp_unpark+45 0.00 4 0.00 sleeptab+1300 wakeup+53 0.00 2 0.00 sleeptab+10c0 sched_pstats+e2 0.00 1 0.00 lwp_park_tab+7c0 lwp_park+d6 0.00 1 0.00 lwp_park_tab+1780 lwp_park+d6 0.00 2 0.00 ffff800065383e00 0.00 1 0.00 ffff800065383e00 sched_pstats+e2 0.00 1 0.00 ffff800065383e00 ltsleep+e0 0.00 1 0.00 lwp_park_tab+17c0 lwp_park+d6 0.00 2 0.00 ffff800065383c80 0.00 1 0.00 ffff800065383c80 ltsleep+e0 0.00 1 0.00 ffff800065383c80 lwp_park+e3 0.00 1 0.00 sleeptab+1200 wakeup+53 0.00 1 0.00 ksiginfo_pool+b0 pool_get+22 0.00 1 0.00 ffff8000653839b8 lf_free+2e 0.00 1 0.00 sleeptab+1440 wakeup+53 0.00 1 0.00 sleeptab+12c0 lwp_lock_retry+2b 0.00 1 0.00 sleeptab+1940 cv_broadcast+55 0.00 1 0.00 ffff800065383f00 sched_pstats+e2 0.00 1 0.00 sleeptab+1500 sched_pstats+e2 -- RW lock sleep (writer) Total% Count Time/ms Lock Caller ------ ------- --------- ---------------------- ------------------------------ 100.00 20 0.43 ffff800067514580 vm_map_lock+67 -- RW lock sleep (reader) Total% Count Time/ms Lock Caller ------ ------- --------- ---------------------- ------------------------------ 100.00 24 0.50 ffff800067514580 uvm_fault_internal+c8 -- Kernel lock spin Total% Count Time/ms Lock Caller ------ ------- --------- ---------------------- ------------------------------ 100.00 9632719 30148.03 kernel_lock 38.53 3824275 11615.71 kernel_lock soo_read+21 24.07 2229781 7257.89 kernel_lock soo_poll+3d 23.10 2220065 6963.11 kernel_lock soo_write+25 14.28 1357006 4304.71 kernel_lock sleepq_block+f5 0.01 1071 4.46 kernel_lock syscall+13d 0.00 332 0.97 kernel_lock callout_softclock+23c 0.00 32 0.72 kernel_lock softint_dispatch+c3 0.00 90 0.28 kernel_lock intr_biglock_wrapper+18 0.00 48 0.14 kernel_lock bdev_strategy+76 0.00 5 0.01 kernel_lock VOP_LOCK+34 0.00 2 0.01 kernel_lock VOP_UNLOCK+34 0.00 7 0.01 kernel_lock soo_close+2a 0.00 1 0.00 kernel_lock VOP_FSYNC+40 0.00 2 0.00 kernel_lock knote_fdclose+3a 0.00 1 0.00 kernel_lock VOP_READ+3c 0.00 1 0.00 kernel_lock cdev_write+9a foo# exit Script done on Sat Mar 8 18:02:43 2008