Re: BUG: Null pointer dereference in fs/open.c
From: William Heimbigner
Date: Wed Apr 25 2007 - 18:53:30 EST
On Wed, 25 Apr 2007, Andrew Morton wrote:
<snip>
OK. I am able to use the pktcdvd driver OK in mainline with a piix/sata
drive. It could be that something is going wrong at the IDE level for you.
Perhaps; I'll try an external usb cd burner, and see where that goes.
Are you able to identify the most recent kernel which actually worked?
No, because I haven't set packet writing up in Linux before - however, I do know
that I've successfully set up packet writing (using 2 of the 3 cd burners I
have) in another operating system before. I'll try 2.6.18 and see if that gets
me anywhere different, though.
dmesg.1.txt is the dmesg output from immediately after system finishes booting
(the unusually large printk times are due to kexec)
# pktsetup 0 /dev/hdc
[19861.831160] pktcdvd: writer pktcdvd0 mapped to hdc
[19861.837138]
[19861.837142] =============================================
[19861.844343] [ INFO: possible recursive locking detected ]
[19861.849738] 2.6.21-rc7 #2
[19861.852361] ---------------------------------------------
[19861.857750] vol_id/4433 is trying to acquire lock:
[19861.862533] (&bdev->bd_mutex){--..}, at: [<c019bb8f>] do_open+0x4f/0x2c0
[19861.869386]
[19861.869387] but task is already holding lock:
[19861.875225] (&bdev->bd_mutex){--..}, at: [<c019bb8f>] do_open+0x4f/0x2c0
[19861.882070]
[19861.882071] other info that might help us debug this:
[19861.888602] 2 locks held by vol_id/4433:
[19861.892518] #0: (&bdev->bd_mutex){--..}, at: [<c019bb8f>]
do_open+0x4f/0x2c0
[19861.899813] #1: (&ctl_mutex#2){--..}, at: [<c04c615c>] mutex_lock+0x1c/0x20
[19861.907046]
[19861.907047] stack backtrace:
[19861.911415] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19861.916569] [<c0105952>] show_trace+0x12/0x20
[19861.921021] [<c0105a46>] dump_stack+0x16/0x20
[19861.925475] [<c013ede0>] __lock_acquire+0xbc0/0x1040
[19861.930542] [<c013f2d0>] lock_acquire+0x70/0x90
[19861.935169] [<c04c61de>] mutex_lock_nested+0x7e/0x2e0
[19861.940315] [<c019bb8f>] do_open+0x4f/0x2c0
[19861.944595] [<c019be79>] __blkdev_get+0x79/0x90
[19861.949222] [<c019bea5>] blkdev_get+0x15/0x20
[19861.953674] [<c032a987>] pkt_open+0xb7/0xd80
[19861.958050] [<c019bbc5>] do_open+0x85/0x2c0
[19861.962330] [<c019c023>] blkdev_open+0x33/0x70
[19861.966870] [<c0175084>] __dentry_open+0xf4/0x220
[19861.971678] [<c0175255>] nameidata_to_filp+0x35/0x40
[19861.976738] [<c01752a9>] do_filp_open+0x49/0x50
[19861.981365] [<c01752f7>] do_sys_open+0x47/0xd0
[19861.985904] [<c01753bc>] sys_open+0x1c/0x20
[19861.990184] [<c01041c6>] sysenter_past_esp+0x5f/0x99
[19861.995243] =======================
# pktsetup 1 /dev/hdd
[19909.635795] cdrom: This disc doesn't have any tracks I recognize!
[19909.689394] pktcdvd: writer pktcdvd1 mapped to hdd
[19909.820337] drivers/ide/ide-cd.c:729: setting error to 2
[19909.825649] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19909.830810] [<c0105952>] show_trace+0x12/0x20
[19909.835263] [<c0105a46>] dump_stack+0x16/0x20
[19909.839716] [<c033f6e4>] cdrom_decode_status+0x1f4/0x3b0
[19909.845131] [<c033fae8>] cdrom_newpc_intr+0x38/0x320
[19909.850190] [<c0331106>] ide_intr+0x96/0x200
[19909.854557] [<c0150cf8>] handle_IRQ_event+0x28/0x60
[19909.859538] [<c0151f96>] handle_edge_irq+0xa6/0x130
[19909.864511] [<c0106449>] do_IRQ+0x49/0xa0
[19909.868618] [<c0104c3a>] common_interrupt+0x2e/0x34
[19909.873591] [<c01022d2>] mwait_idle+0x12/0x20
[19909.878044] [<c01023ca>] cpu_idle+0x4a/0x80
[19909.882324] [<c0101147>] rest_init+0x37/0x40
[19909.886690] [<c068ac7b>] start_kernel+0x34b/0x420
[19909.891499] [<00000000>] 0x0
[19909.894488] =======================
[19909.921518] pktcdvd: pkt_get_last_written failed
# pktsetup 2 /dev/sr0
[19982.934793] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[19982.941070] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19982.946256] [<c0105952>] show_trace+0x12/0x20
[19982.950744] [<c0105a46>] dump_stack+0x16/0x20
[19982.955232] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19982.960586] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19982.965594] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19982.970861] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19982.975955] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19982.980962] [<c01243a2>] __do_softirq+0x62/0xc0
[19982.985624] [<c0124455>] do_softirq+0x55/0x60
[19982.990112] [<c0124be5>] ksoftirqd+0x65/0x100
[19982.994599] [<c0132963>] kthread+0xa3/0xd0
[19982.998827] [<c0104e17>] kernel_thread_helper+0x7/0x10
[19983.004095] =======================
[19983.009065] cdrom: This disc doesn't have any tracks I recognize!
[19983.017405] pktcdvd: writer pktcdvd2 mapped to sr0
[19983.032012] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[19983.038295] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19983.043481] [<c0105952>] show_trace+0x12/0x20
[19983.047977] [<c0105a46>] dump_stack+0x16/0x20
[19983.052465] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19983.057819] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19983.062826] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19983.068093] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19983.073188] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19983.078196] [<c01243a2>] __do_softirq+0x62/0xc0
[19983.082857] [<c0124455>] do_softirq+0x55/0x60
[19983.087345] [<c0124be5>] ksoftirqd+0x65/0x100
[19983.091832] [<c0132963>] kthread+0xa3/0xd0
[19983.096060] [<c0104e17>] kernel_thread_helper+0x7/0x10
[19983.101328] =======================
[19983.124101] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[19983.130380] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19983.135558] [<c0105952>] show_trace+0x12/0x20
[19983.140045] [<c0105a46>] dump_stack+0x16/0x20
[19983.144534] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19983.149887] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19983.154895] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19983.160163] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19983.165257] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19983.170265] [<c01243a2>] __do_softirq+0x62/0xc0
[19983.174934] [<c0124455>] do_softirq+0x55/0x60
[19983.179422] [<c0124be5>] ksoftirqd+0x65/0x100
[19983.183911] [<c0132963>] kthread+0xa3/0xd0
[19983.188138] [<c0104e17>] kernel_thread_helper+0x7/0x10
[19983.193405] =======================
[19983.198099] pktcdvd: pkt_get_last_written failed
# mkudffs /dev/pktcdvd/0
[20417.848314] drivers/ide/ide-cd.c:729: setting error to 2
[20417.853630] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[20417.858791] [<c0105952>] show_trace+0x12/0x20
[20417.863243] [<c0105a46>] dump_stack+0x16/0x20
[20417.867696] [<c033f6e4>] cdrom_decode_status+0x1f4/0x3b0
[20417.873112] [<c033fae8>] cdrom_newpc_intr+0x38/0x320
[20417.878171] [<c0331106>] ide_intr+0x96/0x200
[20417.882546] [<c0150cf8>] handle_IRQ_event+0x28/0x60
[20417.887528] [<c0151f96>] handle_edge_irq+0xa6/0x130
[20417.892501] [<c0106449>] do_IRQ+0x49/0xa0
[20417.896607] [<c0104c3a>] common_interrupt+0x2e/0x34
[20417.901580] [<c01022d2>] mwait_idle+0x12/0x20
[20417.906034] [<c01023ca>] cpu_idle+0x4a/0x80
[20417.910313] [<c0101147>] rest_init+0x37/0x40
[20417.914680] [<c068ac7b>] start_kernel+0x34b/0x420
[20417.919488] [<00000000>] 0x0
[20417.922468] =======================
[20417.928941] pktcdvd: write speed 5540kB/s
[20424.114292] pktcdvd: 2816kB available on disc
start=0, blocks=16, type=RESERVED
start=16, blocks=3, type=VRS
start=19, blocks=237, type=USPACE
start=256, blocks=1, type=ANCHOR
start=257, blocks=16, type=PVDS
start=273, blocks=1, type=LVID
start=274, blocks=877, type=PSPACE
start=1151, blocks=1, type=ANCHOR
start=1152, blocks=239, type=USPACE
start=1391, blocks=16, type=RVDS
start=1407, blocks=1, type=ANCHOR
^ This was on a dvd, and it's reporting 2816kB ?
# mkudffs /dev/pktcdvd/1
[20712.696994] drivers/ide/ide-cd.c:729: setting error to 2
[20712.702306] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[20712.707467] [<c0105952>] show_trace+0x12/0x20
[20712.711919] [<c0105a46>] dump_stack+0x16/0x20
[20712.716373] [<c033f6e4>] cdrom_decode_status+0x1f4/0x3b0
[20712.721788] [<c033fae8>] cdrom_newpc_intr+0x38/0x320
[20712.726848] [<c0331106>] ide_intr+0x96/0x200
[20712.731214] [<c0150cf8>] handle_IRQ_event+0x28/0x60
[20712.736195] [<c0151f96>] handle_edge_irq+0xa6/0x130
[20712.741168] [<c0106449>] do_IRQ+0x49/0xa0
[20712.745275] [<c0104c3a>] common_interrupt+0x2e/0x34
[20712.750248] [<c01022d2>] mwait_idle+0x12/0x20
[20712.754701] [<c01023ca>] cpu_idle+0x4a/0x80
[20712.758981] [<c0101147>] rest_init+0x37/0x40
[20712.763348] [<c068ac7b>] start_kernel+0x34b/0x420
[20712.768156] [<00000000>] 0x0
[20712.771136] =======================
[20712.798160] pktcdvd: pkt_get_last_written failed
trying to change type of multiple extents
# mkudffs /dev/pktcdvd/2
[20742.293998] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[20742.300273] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[20742.305460] [<c0105952>] show_trace+0x12/0x20
[20742.309947] [<c0105a46>] dump_stack+0x16/0x20
[20742.314436] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[20742.319790] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[20742.324797] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[20742.330065] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[20742.335158] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[20742.340167] [<c01243a2>] __do_softirq+0x62/0xc0
[20742.344827] [<c0124455>] do_softirq+0x55/0x60
[20742.349314] [<c0124be5>] ksoftirqd+0x65/0x100
[20742.353802] [<c0132963>] kthread+0xa3/0xd0
[20742.358030] [<c0104e17>] kernel_thread_helper+0x7/0x10
[20742.363298] =======================
[20742.385965] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[20742.392247] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[20742.397425] [<c0105952>] show_trace+0x12/0x20
[20742.401912] [<c0105a46>] dump_stack+0x16/0x20
[20742.406400] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[20742.411754] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[20742.416761] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[20742.422029] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[20742.427124] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[20742.432131] [<c01243a2>] __do_softirq+0x62/0xc0
[20742.436792] [<c0124455>] do_softirq+0x55/0x60
[20742.441280] [<c0124be5>] ksoftirqd+0x65/0x100
[20742.445767] [<c0132963>] kthread+0xa3/0xd0
[20742.449996] [<c0104e17>] kernel_thread_helper+0x7/0x10
[20742.455263] =======================
[20742.460038] pktcdvd: pkt_get_last_written failed
trying to change type of multiple extents
# mount -t udf /dev/pktcdvd/0 /mnt/pkt0
[20912.824545] drivers/ide/ide-cd.c:729: setting error to 2
[20912.829857] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[20912.835019] [<c0105952>] show_trace+0x12/0x20
[20912.839472] [<c0105a46>] dump_stack+0x16/0x20
[20912.843924] [<c033f6e4>] cdrom_decode_status+0x1f4/0x3b0
[20912.849338] [<c033fae8>] cdrom_newpc_intr+0x38/0x320
[20912.854399] [<c0331106>] ide_intr+0x96/0x200
[20912.858765] [<c0150cf8>] handle_IRQ_event+0x28/0x60
[20912.863746] [<c0151f96>] handle_edge_irq+0xa6/0x130
[20912.868720] [<c0106449>] do_IRQ+0x49/0xa0
[20912.872826] [<c0104c3a>] common_interrupt+0x2e/0x34
[20912.877799] [<c01022d2>] mwait_idle+0x12/0x20
[20912.882252] [<c01023ca>] cpu_idle+0x4a/0x80
[20912.886532] [<c0101147>] rest_init+0x37/0x40
[20912.890899] [<c068ac7b>] start_kernel+0x34b/0x420
[20912.895707] [<00000000>] 0x0
[20912.898696] =======================
[20912.905058] pktcdvd: write speed 5540kB/s
[20919.178571] pktcdvd: 2816kB available on disc
[20920.682973] UDF-fs INFO UDF 0.9.8.1 (2004/29/09) Mounting volume 'LinuxUDF',
timestamp 2007/04/26 17:34 (1ed4)
# mount -t udf /dev/pktcdvd/1 /mnt/pkt1
[21007.153670] drivers/ide/ide-cd.c:729: setting error to 2
[21007.158985] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[21007.164146] [<c0105952>] show_trace+0x12/0x20
[21007.168599] [<c0105a46>] dump_stack+0x16/0x20
[21007.173052] [<c033f6e4>] cdrom_decode_status+0x1f4/0x3b0
[21007.178466] [<c033fae8>] cdrom_newpc_intr+0x38/0x320
[21007.183527] [<c0331106>] ide_intr+0x96/0x200
[21007.187892] [<c0150cf8>] handle_IRQ_event+0x28/0x60
[21007.192874] [<c0151f96>] handle_edge_irq+0xa6/0x130
[21007.197848] [<c0106449>] do_IRQ+0x49/0xa0
[21007.201954] [<c0104c3a>] common_interrupt+0x2e/0x34
[21007.206927] [<c01022d2>] mwait_idle+0x12/0x20
[21007.211380] [<c01023ca>] cpu_idle+0x4a/0x80
[21007.215660] [<c0101147>] rest_init+0x37/0x40
[21007.220026] [<c068ac7b>] start_kernel+0x34b/0x420
[21007.224835] [<00000000>] 0x0
[21007.227823] =======================
[21007.254866] pktcdvd: pkt_get_last_written failed
mount: /dev/pktcdvd/1: can't read superblock
# mount -t udf /dev/pktcdvd/2 /mnt/pkt2
[21025.193414] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[21025.199694] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[21025.204880] [<c0105952>] show_trace+0x12/0x20
[21025.209368] [<c0105a46>] dump_stack+0x16/0x20
[21025.213856] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[21025.219210] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[21025.224217] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[21025.229486] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[21025.234579] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[21025.239586] [<c01243a2>] __do_softirq+0x62/0xc0
[21025.244248] [<c0124455>] do_softirq+0x55/0x60
[21025.248735] [<c0124be5>] ksoftirqd+0x65/0x100
[21025.253223] [<c0132963>] kthread+0xa3/0xd0
[21025.257452] [<c0104e17>] kernel_thread_helper+0x7/0x10
[21025.262719] =======================
[21025.285506] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[21025.291781] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[21025.296959] [<c0105952>] show_trace+0x12/0x20
[21025.301446] [<c0105a46>] dump_stack+0x16/0x20
[21025.305933] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[21025.311287] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[21025.316295] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[21025.321563] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[21025.326657] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[21025.331665] [<c01243a2>] __do_softirq+0x62/0xc0
[21025.336325] [<c0124455>] do_softirq+0x55/0x60
[21025.340814] [<c0124be5>] ksoftirqd+0x65/0x100
[21025.345301] [<c0132963>] kthread+0xa3/0xd0
[21025.349529] [<c0104e17>] kernel_thread_helper+0x7/0x10
[21025.354797] =======================
[21025.359503] pktcdvd: pkt_get_last_written failed
mount: /dev/pktcdvd/2: can't read superblock
/dev/sr0 is the USB cd-rw burner.
William Heimbigner
icxcnika@xxxxxxxxxx[ 0.000000] Linux version 2.6.21-rc7 (root@toothfairy) (gcc version 4.1.1 (Gentoo 4.1.1-r3)) #2 PREEMPT Thu Apr 26 15:13:20 CDT 2007
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] sanitize start
[ 0.000000] sanitize end
[ 0.000000] copy_e820_map() start: 0000000000000100 size: 000000000009fb00 end: 000000000009fc00 type: 1
[ 0.000000] copy_e820_map() type is E820_RAM
[ 0.000000] copy_e820_map() start: 000000000009fc00 size: 0000000000000400 end: 00000000000a0000 type: 2
[ 0.000000] copy_e820_map() start: 0000000000100000 size: 000000001f6f0000 end: 000000001f7f0000 type: 1
[ 0.000000] copy_e820_map() type is E820_RAM
[ 0.000000] copy_e820_map() start: 000000001f7f0000 size: 0000000000003000 end: 000000001f7f3000 type: 4
[ 0.000000] copy_e820_map() start: 000000001f7f3000 size: 000000000000d000 end: 000000001f800000 type: 3
[ 0.000000] copy_e820_map() start: 00000000fec00000 size: 0000000001400000 end: 0000000100000000 type: 2
[ 0.000000] BIOS-e820: 0000000000000100 - 000000000009fc00 (usable)
[ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 000000001f7f0000 (usable)
[ 0.000000] BIOS-e820: 000000001f7f0000 - 000000001f7f3000 (ACPI NVS)
[ 0.000000] BIOS-e820: 000000001f7f3000 - 000000001f800000 (ACPI data)
[ 0.000000] BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
[ 0.000000] 503MB LOWMEM available.
[ 0.000000] found SMP MP-table at 000f61b0
[ 0.000000] Entering add_active_range(0, 0, 129008) 0 entries of 256 used
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0 -> 4096
[ 0.000000] Normal 4096 -> 129008
[ 0.000000] early_node_map[1] active PFN ranges
[ 0.000000] 0: 0 -> 129008
[ 0.000000] On node 0 totalpages: 129008
[ 0.000000] DMA zone: 32 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 4064 pages, LIFO batch:0
[ 0.000000] Normal zone: 975 pages used for memmap
[ 0.000000] Normal zone: 123937 pages, LIFO batch:31
[ 0.000000] DMI 2.4 present.
[ 0.000000] Intel MultiProcessor Specification v1.4
[ 0.000000] Virtual Wire compatibility mode.
[ 0.000000] OEM ID: OEM00000 Product ID: PROD00000000 APIC at: 0xFEE00000
[ 0.000000] Processor #0 15:3 APIC version 17
[ 0.000000] I/O APIC #2 Version 17 at 0xFEC00000.
[ 0.000000] Enabling APIC mode: Flat. Using 1 I/O APICs
[ 0.000000] Processors: 1
[ 0.000000] Allocating PCI resources starting at 20000000 (gap: 1f800000:df400000)
[ 0.000000] Built 1 zonelists. Total pages: 128001
[ 0.000000] Kernel command line: root=/dev/hda3 console=ttyS0,115200,8,n,1 rootfstype=ext3 acpi=off noacpi panic=15
[ 0.000000] mapped APIC to ffffd000 (fee00000)
[ 0.000000] mapped IOAPIC to ffffc000 (fec00000)
[ 0.000000] Enabling fast FPU save and restore... done.
[ 0.000000] Enabling unmasked SIMD FPU exception support... done.
[ 0.000000] Initializing CPU#0
[ 0.000000] PID hash table entries: 2048 (order: 11, 8192 bytes)
[ 0.000000] Detected 2666.399 MHz processor.
[19477.912597] Console: colour dummy device 80x25
[19478.153250] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[19478.160978] ... MAX_LOCKDEP_SUBCLASSES: 8
[19478.165240] ... MAX_LOCK_DEPTH: 30
[19478.169416] ... MAX_LOCKDEP_KEYS: 2048
[19478.173765] ... CLASSHASH_SIZE: 1024
[19478.178201] ... MAX_LOCKDEP_ENTRIES: 8192
[19478.182550] ... MAX_LOCKDEP_CHAINS: 16384
[19478.186985] ... CHAINHASH_SIZE: 8192
[19478.191335] memory used by lock dependency info: 1096 kB
[19478.196724] per task-struct memory footprint: 1200 bytes
[19478.202113] ------------------------
[19478.205682] | Locking API testsuite:
[19478.209251] ----------------------------------------------------------------------------
[19478.217325] | spin |wlock |rlock |mutex | wsem | rsem |
[19478.225401] --------------------------------------------------------------------------
[19478.233482] A-A deadlock: ok | ok | ok | ok | ok | ok |
[19478.242243] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok |
[19478.250959] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok |
[19478.259675] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok |
[19478.268390] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok |
[19478.277115] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok |
[19478.285838] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok |
[19478.294590] double unlock: ok | ok | ok | ok | ok | ok |
[19478.303278] initialize held: ok | ok | ok | ok | ok | ok |
[19478.311959] bad unlock order: ok | ok | ok | ok | ok | ok |
[19478.320676] --------------------------------------------------------------------------
[19478.328750] recursive read-lock: | ok | | ok |
[19478.337067] recursive read-lock #2: | ok | | ok |
[19478.345385] mixed read-write-lock: | ok | | ok |
[19478.353701] mixed write-read-lock: | ok | | ok |
[19478.362018] --------------------------------------------------------------------------
[19478.370093] hard-irqs-on + irq-safe-A/12: ok | ok | ok |
[19478.376678] soft-irqs-on + irq-safe-A/12: ok | ok | ok |
[19478.383244] hard-irqs-on + irq-safe-A/21: ok | ok | ok |
[19478.389803] soft-irqs-on + irq-safe-A/21: ok | ok | ok |
[19478.396362] sirq-safe-A => hirqs-on/12: ok | ok | ok |
[19478.402928] sirq-safe-A => hirqs-on/21: ok | ok | ok |
[19478.409512] hard-safe-A + irqs-on/12: ok | ok | ok |
[19478.416097] soft-safe-A + irqs-on/12: ok | ok | ok |
[19478.422681] hard-safe-A + irqs-on/21: ok | ok | ok |
[19478.429266] soft-safe-A + irqs-on/21: ok | ok | ok |
[19478.435850] hard-safe-A + unsafe-B #1/123: ok | ok | ok |
[19478.442435] soft-safe-A + unsafe-B #1/123: ok | ok | ok |
[19478.449019] hard-safe-A + unsafe-B #1/132: ok | ok | ok |
[19478.455603] soft-safe-A + unsafe-B #1/132: ok | ok | ok |
[19478.462188] hard-safe-A + unsafe-B #1/213: ok | ok | ok |
[19478.468773] soft-safe-A + unsafe-B #1/213: ok | ok | ok |
[19478.475357] hard-safe-A + unsafe-B #1/231: ok | ok | ok |
[19478.481942] soft-safe-A + unsafe-B #1/231: ok | ok | ok |
[19478.488526] hard-safe-A + unsafe-B #1/312: ok | ok | ok |
[19478.495111] soft-safe-A + unsafe-B #1/312: ok | ok | ok |
[19478.501694] hard-safe-A + unsafe-B #1/321: ok | ok | ok |
[19478.508296] soft-safe-A + unsafe-B #1/321: ok | ok | ok |
[19478.514881] hard-safe-A + unsafe-B #2/123: ok | ok | ok |
[19478.521465] soft-safe-A + unsafe-B #2/123: ok | ok | ok |
[19478.528050] hard-safe-A + unsafe-B #2/132: ok | ok | ok |
[19478.534634] soft-safe-A + unsafe-B #2/132: ok | ok | ok |
[19478.541244] hard-safe-A + unsafe-B #2/213: ok | ok | ok |
[19478.547846] soft-safe-A + unsafe-B #2/213: ok | ok | ok |
[19478.554431] hard-safe-A + unsafe-B #2/231: ok | ok | ok |
[19478.561015] soft-safe-A + unsafe-B #2/231: ok | ok | ok |
[19478.567599] hard-safe-A + unsafe-B #2/312: ok | ok | ok |
[19478.574183] soft-safe-A + unsafe-B #2/312: ok | ok | ok |
[19478.580768] hard-safe-A + unsafe-B #2/321: ok | ok | ok |
[19478.587352] soft-safe-A + unsafe-B #2/321: ok | ok | ok |
[19478.593936] hard-irq lock-inversion/123: ok | ok | ok |
[19478.600521] soft-irq lock-inversion/123: ok | ok | ok |
[19478.607106] hard-irq lock-inversion/132: ok | ok | ok |
[19478.613691] soft-irq lock-inversion/132: ok | ok | ok |
[19478.620275] hard-irq lock-inversion/213: ok | ok | ok |
[19478.626859] soft-irq lock-inversion/213: ok | ok | ok |
[19478.633443] hard-irq lock-inversion/231: ok | ok | ok |
[19478.640028] soft-irq lock-inversion/231: ok | ok | ok |
[19478.646612] hard-irq lock-inversion/312: ok | ok | ok |
[19478.653197] soft-irq lock-inversion/312: ok | ok | ok |
[19478.659781] hard-irq lock-inversion/321: ok | ok | ok |
[19478.666365] soft-irq lock-inversion/321: ok | ok | ok |
[19478.672959] hard-irq read-recursion/123: ok |
[19478.678122] soft-irq read-recursion/123: ok |
[19478.683286] hard-irq read-recursion/132: ok |
[19478.688448] soft-irq read-recursion/132: ok |
[19478.693613] hard-irq read-recursion/213: ok |
[19478.698776] soft-irq read-recursion/213: ok |
[19478.703939] hard-irq read-recursion/231: ok |
[19478.709103] soft-irq read-recursion/231: ok |
[19478.714267] hard-irq read-recursion/312: ok |
[19478.719431] soft-irq read-recursion/312: ok |
[19478.724594] hard-irq read-recursion/321: ok |
[19478.729757] soft-irq read-recursion/321: ok |
[19478.734921] -------------------------------------------------------
[19478.741177] Good, all 218 testcases passed! |
[19478.745525] ---------------------------------
[19478.750200] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[19478.757542] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[19478.774121] Memory: 501716k/516032k available (3886k kernel code, 13844k reserved, 1773k data, 260k init, 0k highmem)
[19478.784719] virtual kernel memory layout:
[19478.784720] fixmap : 0xfffb7000 - 0xfffff000 ( 288 kB)
[19478.784722] vmalloc : 0xe0000000 - 0xfffb5000 ( 511 MB)
[19478.784723] lowmem : 0xc0000000 - 0xdf7f0000 ( 503 MB)
[19478.784725] .init : 0xc068a000 - 0xc06cb000 ( 260 kB)
[19478.784726] .data : 0xc04cb98f - 0xc0686eb0 (1773 kB)
[19478.784728] .text : 0xc0100000 - 0xc04cb98f (3886 kB)
[19478.823143] Checking if this processor honours the WP bit even in supervisor mode... Ok.
[19478.891678] Calibrating delay using timer specific routine.. 5337.07 BogoMIPS (lpj=2668538)
[19478.900208] Mount-cache hash table entries: 512
[19478.905275] CPU: After generic identify, caps: bfebfbff 00000000 00000000 00000000 0000441d 00000000 00000000
[19478.905287] monitor/mwait feature present.
[19478.909398] using mwait in idle threads.
[19478.913328] CPU: Trace cache: 12K uops, L1 D cache: 16K
[19478.918582] CPU: L2 cache: 256K
[19478.921728] CPU: After all inits, caps: bfebfbff 00000000 00000000 00003180 0000441d 00000000 00000000
[19478.921737] Intel machine check architecture supported.
[19478.926965] Intel machine check reporting enabled on CPU#0.
[19478.932543] CPU0: Intel P4/Xeon Extended MCE MSRs (12) available
[19478.938555] CPU0: Thermal monitoring enabled
[19478.942854] CPU: Intel(R) Celeron(R) CPU 2.66GHz stepping 04
[19478.948571] Checking 'hlt' instruction... OK.
[19478.956658] ENABLING IO-APIC IRQs
[19478.960205] ..TIMER: vector=0x31 apic1=0 pin1=2 apic2=0 pin2=0
[19479.180382] NET: Registered protocol family 16
[19479.213236] PCI: PCI BIOS revision 2.10 entry at 0xfbc60, last bus=1
[19479.219596] PCI: Using configuration type 1
[19479.223787] Setting up standard PCI resources
[19479.267588] ACPI: Interpreter disabled.
[19479.271680] Linux Plug and Play Support v0.97 (c) Adam Belay
[19479.277401] pnp: PnP ACPI: disabled
[19479.282128] SCSI subsystem initialized
[19479.286100] libata version 2.20 loaded.
[19479.286547] usbcore: registered new interface driver usbfs
[19479.292313] usbcore: registered new interface driver hub
[19479.297945] usbcore: registered new device driver usb
[19479.303579] PCI: Probing PCI hardware
[19479.307314] PCI: Probing PCI hardware (bus 00)
[19479.307823] Boot video device is 0000:00:02.0
[19479.308321] * The chipset may have PM-Timer Bug. Due to workarounds for a bug,
[19479.308323] * this clock source is slow. If you are sure your timer does not have
[19479.308325] * this bug, please use "acpi_pm_good" to disable the workaround
[19479.330052] PCI quirk: region 0400-047f claimed by ICH4 ACPI/GPIO/TCO
[19479.336497] PCI quirk: region 0480-04bf claimed by ICH4 GPIO
[19479.342255] 0000:00:1f.1: trying to change BAR0 from 01F0 to 01F0
[19479.348348] 0000:00:1f.1: trying to change BAR1 from 03F4 to 03F4
[19479.354446] 0000:00:1f.1: trying to change BAR2 from 0170 to 0170
[19479.360544] 0000:00:1f.1: trying to change BAR3 from 0374 to 0374
[19479.367215] PCI: Transparent bridge - 0000:00:1e.0
[19479.375379] PCI: Using IRQ router PIIX/ICH [8086/24c0] at 0000:00:1f.0
[19479.381932] PCI->APIC IRQ transform: 0000:00:02.0[A] -> IRQ 16
[19479.387886] PCI->APIC IRQ transform: 0000:00:1d.0[A] -> IRQ 16
[19479.393722] PCI->APIC IRQ transform: 0000:00:1d.1[B] -> IRQ 19
[19479.399558] PCI->APIC IRQ transform: 0000:00:1d.2[C] -> IRQ 18
[19479.405398] PCI->APIC IRQ transform: 0000:00:1d.7[D] -> IRQ 23
[19479.411242] PCI->APIC IRQ transform: 0000:00:1f.1[A] -> IRQ 16
[19479.417076] PCI->APIC IRQ transform: 0000:00:1f.3[B] -> IRQ 17
[19479.422916] PCI->APIC IRQ transform: 0000:01:09.0[A] -> IRQ 21
[19479.428754] PCI->APIC IRQ transform: 0000:01:0c.0[A] -> IRQ 23
[19479.445494] Time: tsc clocksource has been installed.
[19479.452132] PCI: Ignore bogus resource 6 [0:0] of 0000:00:02.0
[19479.457987] PCI: Bridge: 0000:00:1e.0
[19479.461760] IO window: c000-cfff
[19479.465174] MEM window: ec000000-edffffff
[19479.469363] PREFETCH window: 20000000-200fffff
[19479.474006] PCI: Setting latency timer of device 0000:00:1e.0 to 64
[19479.474091] NET: Registered protocol family 2
[19479.488585] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[19479.495854] TCP established hash table entries: 16384 (order: 7, 720896 bytes)
[19479.505329] TCP bind hash table entries: 16384 (order: 7, 720896 bytes)
[19479.514091] TCP: Hash tables configured (established 16384 bind 16384)
[19479.520680] TCP reno registered
[19479.528509] Machine check exception polling timer started.
[19479.535082] IA-32 Microcode Update Driver: v1.14a <tigran@xxxxxxxxxxxxxxxxxxxx>
[19479.542513] apm: BIOS not found.
[19479.549631] Initializing RT-Tester: OK
[19479.553433] audit: initializing netlink socket (disabled)
[19479.558985] audit(1177625944.309:1): initialized
[19479.563987] Total HugeTLB memory allocated, 0
[19479.569369] Installing knfsd (copyright (C) 1996 okir@xxxxxxxxxxxx).
[19479.576992] io scheduler noop registered
[19479.580932] io scheduler anticipatory registered
[19479.585548] io scheduler deadline registered
[19479.589855] io scheduler cfq registered (default)
[19479.596113] vga16fb: initializing
[19479.596125] vga16fb: mapped to 0xc00a0000
[19479.709449] Console: switching to colour frame buffer device 80x30
[19479.720099] fb0: VGA16 VGA frame buffer device
[19479.761064] Real Time Clock Driver v1.12ac
[19479.765583] Non-volatile memory driver v1.2
[19479.769811] intel_rng: FWH not detected
[19479.774014] Software Watchdog Timer: 0.07 initialized. soft_noboot=0 soft_margin=60 sec (nowayout= 0)
[19479.783237] Linux agpgart interface v0.102 (c) Dave Jones
[19479.788907] agpgart: Detected an Intel 845G Chipset.
[19479.794044] agpgart: Detected 8060K stolen memory.
[19479.812253] agpgart: AGP aperture is 128M @ 0xe0000000
[19479.817507] [drm] Initialized drm 1.1.0 20060810
[19479.822555] [drm] Initialized i830 1.3.2 20021108 on minor 0
[19479.828462] ipmi message handler version 39.1
[19479.832841] ipmi device interface
[19479.836472] IPMI System Interface driver.
[19479.850783] ipmi_si: Unable to find any System Interface(s)
[19479.856375] IPMI Watchdog: driver initialized
[19479.860733] Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot.
[19479.868387] Hangcheck: starting hangcheck timer 0.9.0 (tick is 180 seconds, margin is 60 seconds).
[19479.877340] Hangcheck: Using get_cycles().
[19479.882056] intelfb: Framebuffer driver for Intel(R) 830M/845G/852GM/855GM/865G/915G/915GM/945G/945GM chipsets
[19479.892062] intelfb: Version 0.9.4
[19479.896199] intelfb: 00:02.0: Intel(R) 845G, aperture size 128MB, stolen memory 8060kB
[19479.904488] intelfb: Mode is interlaced.
[19479.908425] intelfb: Initial video mode is 1024x768-32@70.
[19479.915353] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
[19479.923718] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[19479.936201] RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
[19479.945955] loop: loaded (max 8 devices)
[19479.950949] netconsole: not configured, aborting
[19479.955590] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[19479.962058] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[19479.970165] ICH4: IDE controller at PCI slot 0000:00:1f.1
[19479.975590] ICH4: chipset revision 2
[19479.979174] ICH4: not 100% native mode: will probe irqs later
[19479.984930] ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA
[19479.992216] ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA
[19479.999498] Probing IDE interface ide0...
[19480.263326] hda: Maxtor 91024U3, ATA DISK drive
[19480.522220] hdb: Maxtor 6L100P0, ATA DISK drive
[19480.580651] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[19480.585782] Probing IDE interface ide1...
[19481.256928] hdc: PLEXTOR DVDR PX-740A, ATAPI CD/DVD-ROM drive
[19481.976642] hdd: DV-W22E, ATAPI CD/DVD-ROM drive
[19482.033055] ide1 at 0x170-0x177,0x376 on irq 15
[19482.039285] hda: max request size: 128KiB
[19482.050092] hda: 19541088 sectors (10005 MB) w/2048KiB Cache, CHS=19386/16/63, UDMA(66)
[19482.058191] hda: cache flushes not supported
[19482.062652] hda: hda1 hda2 hda3
[19482.071875] hdb: max request size: 512KiB
[19482.075905] hdb: 195371568 sectors (100030 MB) w/8192KiB Cache, CHS=16383/255/63, UDMA(100)
[19482.116714] hdb: cache flushes supported
[19482.120797] hdb: hdb1
[19482.133445] drivers/ide/ide-io.c:71: setting error to -5
[19482.138756] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19482.143910] [<c0105952>] show_trace+0x12/0x20
[19482.148363] [<c0105a46>] dump_stack+0x16/0x20
[19482.152816] [<c03304fa>] ide_end_request+0x12a/0x130
[19482.157875] [<c033ed85>] cdrom_end_request+0x55/0x130
[19482.163021] [<c033fe68>] cdrom_pc_intr+0x98/0x290
[19482.167821] [<c0331106>] ide_intr+0x96/0x200
[19482.172188] [<c0150cf8>] handle_IRQ_event+0x28/0x60
[19482.177169] [<c0151f96>] handle_edge_irq+0xa6/0x130
[19482.182143] [<c0106449>] do_IRQ+0x49/0xa0
[19482.186250] [<c0104c3a>] common_interrupt+0x2e/0x34
[19482.191222] [<c01022d2>] mwait_idle+0x12/0x20
[19482.195675] [<c01023ca>] cpu_idle+0x4a/0x80
[19482.199955] [<c0101147>] rest_init+0x37/0x40
[19482.204322] [<c068ac7b>] start_kernel+0x34b/0x420
[19482.209129] [<00000000>] 0x0
[19482.212111] =======================
[19482.215711] Clocksource tsc unstable (delta = 106845200 ns)
[19482.221391] Time: pit clocksource has been installed.
[19482.226769] hdc: ATAPI 94X DVD-ROM DVD-R CD-R/RW drive, 2048kB Cache, UDMA(33)
[19482.234291] Uniform CD-ROM driver Revision: 3.20
[19482.254141] hdd: ATAPI 24X DVD-ROM DVD-R CD-R/RW drive, 1419kB Cache, UDMA(33)
[19502.687658] PCI: Setting latency timer of device 0000:00:1d.7 to 64
[19502.687667] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[19502.693450] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 1
[19502.701050] PCI: cache line size of 128 is not supported by device 0000:00:1d.7
[19502.701072] ehci_hcd 0000:00:1d.7: irq 23, io mem 0xee080000
[19502.710635] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[19502.719086] usb usb1: configuration #1 chosen from 1 choice
[19502.725050] hub 1-0:1.0: USB hub found
[19502.728868] hub 1-0:1.0: 6 ports detected
[19502.834025] ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver
[19502.834394] USB Universal Host Controller Interface driver v3.0
[19502.840647] PCI: Setting latency timer of device 0000:00:1d.0 to 64
[19502.840656] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[19502.846260] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[19502.853746] uhci_hcd 0000:00:1d.0: irq 16, io base 0x0000d800
[19502.860350] usb usb2: configuration #1 chosen from 1 choice
[19502.866275] hub 2-0:1.0: USB hub found
[19502.870169] hub 2-0:1.0: 2 ports detected
[19502.975858] PCI: Setting latency timer of device 0000:00:1d.1 to 64
[19502.975866] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[19502.981450] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[19502.988911] uhci_hcd 0000:00:1d.1: irq 19, io base 0x0000d000
[19502.995485] usb usb3: configuration #1 chosen from 1 choice
[19503.001444] hub 3-0:1.0: USB hub found
[19503.005248] hub 3-0:1.0: 2 ports detected
[19503.110750] PCI: Setting latency timer of device 0000:00:1d.2 to 64
[19503.110757] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[19503.116336] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[19503.123794] uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000d400
[19503.130394] usb usb4: configuration #1 chosen from 1 choice
[19503.136314] hub 4-0:1.0: USB hub found
[19503.140116] hub 4-0:1.0: 2 ports detected
[19503.273996] usb 1-4: new high speed USB device using ehci_hcd and address 3
[19503.459552] usb 1-4: configuration #1 chosen from 1 choice
[19503.671827] usb 2-2: new low speed USB device using uhci_hcd and address 2
[19503.843931] usb 2-2: configuration #1 chosen from 1 choice
[19503.957798] usbcore: registered new interface driver usblp
[19503.963313] drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
[19503.970463] Initializing USB Mass Storage driver...
[19503.975958] scsi0 : SCSI emulation for USB Mass Storage devices
[19503.982328] usb-storage: device found at 3
[19503.982333] usb-storage: waiting for device to settle before scanning
[19503.982401] usbcore: registered new interface driver usb-storage
[19503.988450] USB Mass Storage support registered.
[19504.007896] input: Logitech USB Receiver as /class/input/input0
[19504.013952] input: USB HID v1.10 Keyboard [Logitech USB Receiver] on usb-0000:00:1d.0-2
[19504.052120] input: Logitech USB Receiver as /class/input/input1
[19504.058122] input: USB HID v1.10 Mouse [Logitech USB Receiver] on usb-0000:00:1d.0-2
[19504.066053] usbcore: registered new interface driver usbhid
[19504.071669] drivers/usb/input/hid-core.c: v2.6:USB HID core driver
[19504.078795] PNP: No PS/2 controller found. Probing ports directly.
[19504.087675] serio: i8042 KBD port at 0x60,0x64 irq 1
[19504.092865] serio: i8042 AUX port at 0x60,0x64 irq 12
[19504.099189] mice: PS/2 mouse device common for all mice
[19504.105569] input: PC Speaker as /class/input/input2
[19504.111192] I2O subsystem v1.325
[19504.114455] i2o: max drivers = 8
[19504.118478] I2O Configuration OSM v1.323
[19504.122960] I2O Bus Adapter OSM v1.317
[19504.126948] I2O Block Device OSM v1.325
[19504.131589] I2O SCSI Peripheral OSM v1.316
[19504.135926] I2O ProcFS OSM v1.316
[19504.157657] rtc-test rtc-test.0: rtc core: registered test as rtc0
[19504.164725] rtc-test rtc-test.1: rtc core: registered test as rtc1
[19504.188876] i2c /dev entries driver
[19504.199116] i2c-parport: adapter type unspecified
[19504.465520] abituguru: no Abit uGuru found, data = 0xFF, cmd = 0xFF
[19504.545480] i2c_adapter i2c-3: detect fail: address match, 0x2f
[19504.583482] hdaps: supported laptop not found!
[19504.587951] hdaps: driver init failed (ret=-19)!
[19505.074276] pc87360: PC8736x not detected, module not inserted.
[19505.084830] w83627ehf: unsupported chip ID: 0x8283
[19505.089660] w83627ehf: unsupported chip ID: 0xffff
[19505.096284] EDAC MC: Ver: 2.0.1 Apr 26 2007
[19505.103502] Advanced Linux Sound Architecture Driver Version 1.0.14rc3 (Wed Mar 14 07:25:50 2007 UTC).
[19505.115984] no UART detected at 0x1
[19505.121053] ALSA device list:
[19505.124061] No soundcards found.
[19505.127764] oprofile: using NMI interrupt.
[19505.131979] TCP cubic registered
[19505.135343] NET: Registered protocol family 1
[19505.139845] NET: Registered protocol family 10
[19505.144713] NET: Registered protocol family 17
[19505.149913] p4-clockmod: P4/Xeon(TM) CPU On-Demand Clock Modulation available
[19505.157080] Using IPI Shortcut mode
[19505.161249] rtc-test rtc-test.0: setting the system clock to 2007-04-26 22:19:29 (1177625969)
[19505.189749] kjournald starting. Commit interval 5 seconds
[19505.195358] EXT3-fs: mounted filesystem with ordered data mode.
[19505.201590] VFS: Mounted root (ext3 filesystem) readonly.
[19505.207405] Freeing unused kernel memory: 260k freed
[19505.212536] Write protecting the kernel read-only data: 1338k
[19509.172734] scsi 0:0:0:0: CD-ROM CDWRITER IDE5232 0009 PQ: 0 ANSI: 0 CCS
[19509.174566] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[19509.174573] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.174586] [<c0105952>] show_trace+0x12/0x20
[19509.174590] [<c0105a46>] dump_stack+0x16/0x20
[19509.174610] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.174615] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.174620] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.174625] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.174629] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.174636] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.174641] [<c0124455>] do_softirq+0x55/0x60
[19509.174645] [<c0124713>] irq_exit+0x83/0x90
[19509.174649] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.174653] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.174658] =======================
[19509.177228] sr0: scsi3-mmc drive: 32x/32x writer cd/rw xa/form2 cdda tray
[19509.177486] sr 0:0:0:0: Attached scsi CD-ROM sr0
[19509.177818] sr 0:0:0:0: Attached scsi generic sg0 type 5
[19509.178568] drivers/scsi/scsi_lib.c:838: setting error to 262144
[19509.178574] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.178585] [<c0105952>] show_trace+0x12/0x20
[19509.178589] [<c0105a46>] dump_stack+0x16/0x20
[19509.178611] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.178617] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.178622] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.178627] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.178631] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.178637] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.178642] [<c0124455>] do_softirq+0x55/0x60
[19509.178646] [<c0124713>] irq_exit+0x83/0x90
[19509.178650] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.178654] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.178658] =======================
[19509.179571] drivers/scsi/scsi_lib.c:838: setting error to 262144
[19509.179577] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.179587] [<c0105952>] show_trace+0x12/0x20
[19509.179610] [<c0105a46>] dump_stack+0x16/0x20
[19509.179614] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.179620] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.179624] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.179629] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.179633] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.179640] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.179644] [<c0124455>] do_softirq+0x55/0x60
[19509.179648] [<c0124713>] irq_exit+0x83/0x90
[19509.179652] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.179656] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.179660] =======================
[19509.180565] drivers/scsi/scsi_lib.c:838: setting error to 262144
[19509.180571] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.180581] [<c0105952>] show_trace+0x12/0x20
[19509.180586] [<c0105a46>] dump_stack+0x16/0x20
[19509.180608] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.180614] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.180618] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.180624] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.180628] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.180634] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.180639] [<c0124455>] do_softirq+0x55/0x60
[19509.180643] [<c0124713>] irq_exit+0x83/0x90
[19509.180647] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.180651] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.180655] =======================
[19509.181564] drivers/scsi/scsi_lib.c:838: setting error to 262144
[19509.181570] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.181580] [<c0105952>] show_trace+0x12/0x20
[19509.181584] [<c0105a46>] dump_stack+0x16/0x20
[19509.181588] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.181611] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.181615] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.181620] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.181624] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.181630] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.181635] [<c0124455>] do_softirq+0x55/0x60
[19509.181638] [<c0124713>] irq_exit+0x83/0x90
[19509.181642] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.181647] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.181651] =======================
[19509.182564] drivers/scsi/scsi_lib.c:838: setting error to 262144
[19509.182571] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.182581] [<c0105952>] show_trace+0x12/0x20
[19509.182585] [<c0105a46>] dump_stack+0x16/0x20
[19509.182606] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.182612] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.182616] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.182621] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.182625] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.182631] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.182636] [<c0124455>] do_softirq+0x55/0x60
[19509.182640] [<c0124713>] irq_exit+0x83/0x90
[19509.182643] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.182648] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.182652] =======================
[19509.183564] drivers/scsi/scsi_lib.c:838: setting error to 262144
[19509.183571] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.183580] [<c0105952>] show_trace+0x12/0x20
[19509.183585] [<c0105a46>] dump_stack+0x16/0x20
[19509.183606] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.183613] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.183617] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.183623] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.183627] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.183633] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.183637] [<c0124455>] do_softirq+0x55/0x60
[19509.183641] [<c0124713>] irq_exit+0x83/0x90
[19509.183645] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.183649] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.183653] =======================
[19509.184562] drivers/scsi/scsi_lib.c:838: setting error to 262144
[19509.184569] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.184578] [<c0105952>] show_trace+0x12/0x20
[19509.184582] [<c0105a46>] dump_stack+0x16/0x20
[19509.184586] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.184609] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.184613] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.184618] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.184622] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.184629] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.184633] [<c0124455>] do_softirq+0x55/0x60
[19509.184637] [<c0124713>] irq_exit+0x83/0x90
[19509.184641] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.184645] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.184650] =======================
[19509.185563] drivers/scsi/scsi_lib.c:838: setting error to 262144
[19509.185570] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19509.185580] [<c0105952>] show_trace+0x12/0x20
[19509.185585] [<c0105a46>] dump_stack+0x16/0x20
[19509.185605] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19509.185611] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19509.185615] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19509.185621] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19509.185625] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19509.185631] [<c01243a2>] __do_softirq+0x62/0xc0
[19509.185636] [<c0124455>] do_softirq+0x55/0x60
[19509.185639] [<c0124713>] irq_exit+0x83/0x90
[19509.185643] [<c010644e>] do_IRQ+0x4e/0xa0
[19509.185648] [<c0104c3a>] common_interrupt+0x2e/0x34
[19509.185652] =======================
[19509.185775] usb-storage: device scan complete
[19510.133841] i8xx TCO timer: cannot register miscdev on minor=130 (err=-16)
[19510.140087] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.01 (21-Jan-2007)
[19510.140447] iTCO_wdt: Found a ICH4 TCO device (Version=1, TCOBASE=0x0460)
[19510.140528] iTCO_wdt: cannot register miscdev on minor=130 (err=-16)
[19510.140538] iTCO_wdt: No card detected
[19510.212389] 8139too Fast Ethernet driver 0.9.28
[19510.213290] eth0: RealTek RTL8139 at 0xe0046000, 00:11:09:3c:07:c2, IRQ 23
[19510.213296] eth0: Identified 8139 chip type 'RTL-8101'
[19510.217011] 8139cp: 10/100 PCI Ethernet driver v1.3 (Mar 22, 2004)
[19510.229231] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[19510.229239] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19510.229251] [<c0105952>] show_trace+0x12/0x20
[19510.229256] [<c0105a46>] dump_stack+0x16/0x20
[19510.229260] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19510.229266] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19510.229270] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19510.229276] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19510.229280] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19510.229286] [<c01243a2>] __do_softirq+0x62/0xc0
[19510.229292] [<c0124455>] do_softirq+0x55/0x60
[19510.229296] [<c0124713>] irq_exit+0x83/0x90
[19510.229300] [<c01148ca>] smp_apic_timer_interrupt+0x4a/0x80
[19510.229306] [<c0104c73>] apic_timer_interrupt+0x33/0x38
[19510.229311] [<c016299e>] __handle_mm_fault+0x7ae/0x9b0
[19510.229317] [<c04c9737>] do_page_fault+0x117/0x610
[19510.229324] [<c04c7fdc>] error_code+0x74/0x7c
[19510.229328] =======================
[19510.236218] drivers/scsi/scsi_lib.c:838: setting error to 134217730
[19510.236226] [<c010521a>] show_trace_log_lvl+0x1a/0x30
[19510.236237] [<c0105952>] show_trace+0x12/0x20
[19510.236242] [<c0105a46>] dump_stack+0x16/0x20
[19510.236246] [<c034543a>] scsi_io_completion+0x28a/0x3a0
[19510.236252] [<c034556b>] scsi_blk_pc_done+0x1b/0x30
[19510.236256] [<c0340d0c>] scsi_finish_command+0x4c/0x60
[19510.236262] [<c0345c07>] scsi_softirq_done+0x77/0xe0
[19510.236266] [<c0257f8b>] blk_done_softirq+0x6b/0x80
[19510.236273] [<c01243a2>] __do_softirq+0x62/0xc0
[19510.236278] [<c0124455>] do_softirq+0x55/0x60
[19510.236282] [<c0124713>] irq_exit+0x83/0x90
[19510.236286] [<c01148ca>] smp_apic_timer_interrupt+0x4a/0x80
[19510.236292] [<c0104c73>] apic_timer_interrupt+0x33/0x38
[19510.236297] [<c0164a36>] exit_mmap+0x66/0x100
[19510.236302] [<c011d1f7>] mmput+0x47/0xb0
[19510.236307] [<c0120f87>] exit_mm+0x67/0xc0
[19510.236311] [<c01224ca>] do_exit+0x12a/0x840
[19510.236316] [<c0122c09>] do_group_exit+0x29/0x70
[19510.236320] [<c0122c5f>] sys_exit_group+0xf/0x20
[19510.236325] [<c01041c6>] sysenter_past_esp+0x5f/0x99
[19510.236329] =======================
[19510.545779] snd-ca0106: Model 1006 Rev 00000000 Serial 10061102
[19511.069534] EXT3-fs warning: maximal mount count reached, running e2fsck is recommended
[19511.069820] EXT3 FS on hda3, internal journal
[19511.591169] kjournald starting. Commit interval 5 seconds
[19511.591426] EXT3 FS on hda1, internal journal
[19511.591440] EXT3-fs: mounted filesystem with ordered data mode.
[19511.645321] kjournald2 starting. Commit interval 5 seconds
[19511.645378] EXT4-fs warning: maximal mount count reached, running e2fsck is recommended
[19511.645585] EXT4 FS on hdb1, internal journal
[19511.645697] EXT4-fs: mounted filesystem with ordered data mode.
[19511.645700] EXT4-fs: file extents enabled
[19511.766517] Adding 750448k swap on /dev/hda2. Priority:-1 extents:1 across:750448k
[19521.930371] eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
[19524.564290] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[19524.576088] NFSD: starting 90-second grace period
[19532.228389] eth0: no IPv6 routers present