Re: xen 4.0.1-rc4 + 2.6.32.16 kernel bug?

[Jeremy Fitzhardinge <jeremy@goop.org>]

  On 08/01/2010 10:49 AM, benco wrote:
> Hello,
>
> i'm getting attached error message with 4.0.1-rc4 and 2.6.32.16 kernel.

Which version of 2.6.32.16?  Is it most recent git?

> i will test again with rc5.

This looks like a kernel issue rather than anything related to Xen itself.

> thanks,
>
> roman
>
> [   14.679611] device-mapper: multipath: version 1.1.0 loaded
> [   14.750818] sd 1:0:0:31: rdac: LUN 31 (owned)
> [   14.751903] sd 2:0:0:31: rdac: LUN 31 (owned)
> [   14.753277] sd 1:0:1:31: rdac: LUN 31 (unowned)
> [   14.754336] sd 2:0:1:31: rdac: LUN 31 (unowned)
> [   14.755925] rdac: device handler registered
> [   14.772166] device-mapper: multipath round-robin: version 1.0.0 loaded
> [   16.196307] 1 multicall(s) failed: cpu 2
> [   16.196453] Pid: 2342, comm: dmsetup_env Not tainted 2.6.32.16 #1
> [   16.196593] Call Trace:
> [   16.196728]  [<c10032c9>] ? xen_mc_flush+0x9e/0x150
> [   16.196862]  [<c1004538>] ? __xen_pgd_pin+0xc8/0xdb
> [   16.197010]  [<c1004563>] ? xen_dup_mmap+0x18/0x1e
> [   16.197048]  [<c1033b76>] ? dup_mm+0x28f/0x397
> [   16.197048]  [<c1005930>] ? check_events+0x8/0xc
> [   16.197048]  [<c1034618>] ? copy_process+0x945/0xfa0
> [   16.197048]  [<c1034d88>] ? do_fork+0x115/0x27c
> [   16.197048]  [<c1040cb6>] ? recalc_sigpending+0xf/0x2e
> [   16.197048]  [<c1040fe5>] ? sigprocmask+0xb5/0xbc
> [   16.197048]  [<c10064ce>] ? sys_clone+0x21/0x27
> [   16.197048]  [<c10078a9>] ? syscall_call+0x7/0xb
> [   16.197048]   call  1/16: op=14 arg=[cb462000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call  2/16: op=14 arg=[ca7bb000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call  3/16: op=26 arg=[c21fc8d0] result=0 xen_do_pin+0x12/0x56
> [   16.197048]   call  4/16: op=14 arg=[cb469000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call  5/16: op=26 arg=[c21fc8e0] result=0 xen_do_pin+0x12/0x56
> [   16.197048]   call  6/16: op=14 arg=[cb5c5000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call  7/16: op=14 arg=[cb612000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call  8/16: op=14 arg=[cab19000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call  9/16: op=26 arg=[c21fc8f0] result=0 xen_do_pin+0x12/0x56
> [   16.197048]   call 10/16: op=14 arg=[cb6d7000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call 11/16: op=26 arg=[c21fc900] result=0 xen_do_pin+0x12/0x56
> [   16.197048]   call 12/16: op=14 arg=[cbf12000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call 13/16: op=26 arg=[c21fc910] result=0 xen_do_pin+0x12/0x56
> [   16.197048]   call 14/16: op=14 arg=[cbec9000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call 15/16: op=14 arg=[cbf10000] result=0 xen_pin_page+0x108/0x118
> [   16.197048]   call 16/16: op=26 arg=[c21fc920] result=-22 xen_do_pin+0x12/0x56

Are there any messages on the Xen console?  Is this really only 16 
seconds into boot?  Has it tried to start any domUs at that point?

Can you post full boot logs?

Thanks,
     J

> [   16.197048] ------------[ cut here ]------------
> [   16.197048] WARNING: at arch/x86/xen/multicalls.c:182 __xen_pgd_pin+0xc8/0xdb()
> [   16.197048] Hardware name: eserver xSeries 335 -[8676M1X]-
> [   16.197048] Modules linked in: dm_round_robin scsi_dh_rdac dm_multipath scsi_dh loop tpm_tis tpm tpm_bios serio_raw pcspkr i2c_piix4 i2c_core sworks_agp button processor acpi_processor evdev dm_mirror dm_region_hash dm_log dm_snapshot ata_generic ohci_hcd qla2xxx tg3 floppy scsi_transport_fc libphy libata usbcore mptspi mptscsih mptbase scsi_transport_spi thermal fan thermal_sys [last unloaded: scsi_wait_scan]
> [   16.200402] Pid: 2342, comm: dmsetup_env Not tainted 2.6.32.16 #1
> [   16.200402] Call Trace:
> [   16.200402]  [<c10354a9>] ? warn_slowpath_common+0x5e/0x8a
> [   16.200402]  [<c10354df>] ? warn_slowpath_null+0xa/0xc
> [   16.200402]  [<c1004538>] ? __xen_pgd_pin+0xc8/0xdb
> [   16.200402]  [<c1004563>] ? xen_dup_mmap+0x18/0x1e
> [   16.200402]  [<c1033b76>] ? dup_mm+0x28f/0x397
> [   16.200402]  [<c1005930>] ? check_events+0x8/0xc
> [   16.200402]  [<c1034618>] ? copy_process+0x945/0xfa0
> [   16.200402]  [<c1034d88>] ? do_fork+0x115/0x27c
> [   16.200402]  [<c1040cb6>] ? recalc_sigpending+0xf/0x2e
> [   16.200402]  [<c1040fe5>] ? sigprocmask+0xb5/0xbc
> [   16.200402]  [<c10064ce>] ? sys_clone+0x21/0x27
> [   16.200402]  [<c10078a9>] ? syscall_call+0x7/0xb
> [   16.200402] ---[ end trace da601d15d0db46a7 ]---
> [   16.208538] 1 multicall(s) failed: cpu 0
> [   16.208643] Pid: 2352, comm: dmsetup_env Tainted: G        W  2.6.32.16 #1
> [   16.208744] Call Trace:
> [   16.208846]  [<c10032c9>] ? xen_mc_flush+0x9e/0x150
> [   16.208948]  [<c100431a>] ? __xen_pgd_unpin+0x8b/0x9e
> [   16.209065]  [<c100468c>] ? xen_exit_mmap+0xcd/0xd5
> [   16.209179]  [<c109f1cb>] ? exit_mmap+0x3b/0x10e
> [   16.209283]  [<c10335fa>] ? mmput+0x1e/0x90
> [   16.209383]  [<c10b47bd>] ? flush_old_exec+0x3dc/0x4a2
> [   16.209487]  [<c10dc9aa>] ? load_elf_binary+0x31f/0x10b5
> [   16.209588]  [<c109c90b>] ? __get_user_pages+0x2e4/0x365
> [   16.209687]  [<c10b3d3b>] ? get_arg_page+0x2f/0x7a
> [   16.209785]  [<c10b400f>] ? search_binary_handler+0xef/0x25e
> [   16.209885]  [<c10dc68b>] ? load_elf_binary+0x0/0x10b5
> [   16.209994]  [<c10b5219>] ? do_execve+0x1f7/0x2ba
> [   16.210100]  [<c1006489>] ? sys_execve+0x23/0x47
> [   16.210211]  [<c10078a9>] ? syscall_call+0x7/0xb
> [   16.210363]   call  1/16: op=26 arg=[c21dc8d0] result=-22 xen_do_pin+0x12/0x56
> [   16.210378]   call  2/16: op=14 arg=[cbf10000] result=0 xen_unpin_page+0x79/0x111
> [   16.210393]   call  3/16: op=14 arg=[cb462000] result=0 xen_unpin_page+0x79/0x111
> [   16.210406]   call  4/16: op=26 arg=[c21dc8e0] result=0 xen_do_pin+0x12/0x56
> [   16.210423]   call  5/16: op=14 arg=[ca7bb000] result=0 xen_unpin_page+0x79/0x111
> [   16.210443]   call  6/16: op=26 arg=[c21dc8f0] result=0 xen_do_pin+0x12/0x56
> [   16.210458]   call  7/16: op=14 arg=[cb469000] result=0 xen_unpin_page+0x79/0x111
> [   16.210473]   call  8/16: op=14 arg=[cb5c5000] result=0 xen_unpin_page+0x79/0x111
> [   16.210486]   call  9/16: op=14 arg=[cb612000] result=0 xen_unpin_page+0x79/0x111
> [   16.210503]   call 10/16: op=26 arg=[c21dc900] result=0 xen_do_pin+0x12/0x56
> [   16.210517]   call 11/16: op=14 arg=[cab19000] result=0 xen_unpin_page+0x79/0x111
> [   16.210531]   call 12/16: op=26 arg=[c21dc910] result=0 xen_do_pin+0x12/0x56
> [   16.210545]   call 13/16: op=14 arg=[cb6d7000] result=0 xen_unpin_page+0x79/0x111
> [   16.210559]   call 14/16: op=26 arg=[c21dc920] result=0 xen_do_pin+0x12/0x56
> [   16.210574]   call 15/16: op=14 arg=[cbf12000] result=0 xen_unpin_page+0x79/0x111
> [   16.210588]   call 16/16: op=14 arg=[cbec9000] result=0 xen_unpin_page+0x79/0x111
> [   16.210598] ------------[ cut here ]------------
> [   16.210746] WARNING: at arch/x86/xen/multicalls.c:182 __xen_pgd_unpin+0x8b/0x9e()
> [   16.210915] Hardware name: eserver xSeries 335 -[8676M1X]-
> [   16.211063] Modules linked in: dm_round_robin scsi_dh_rdac dm_multipath scsi_dh loop tpm_tis tpm tpm_bios serio_raw pcspkr i2c_piix4 i2c_core sworks_agp button processor acpi_processor evdev dm_mirror dm_region_hash dm_log dm_snapshot ata_generic ohci_hcd qla2xxx tg3 floppy scsi_transport_fc libphy libata usbcore mptspi mptscsih mptbase scsi_transport_spi thermal fan thermal_sys [last unloaded: scsi_wait_scan]
> [   16.212026] Pid: 2352, comm: dmsetup_env Tainted: G        W  2.6.32.16 #1
> [   16.212026] Call Trace:
> [   16.212026]  [<c10354a9>] ? warn_slowpath_common+0x5e/0x8a
> [   16.212026]  [<c10354df>] ? warn_slowpath_null+0xa/0xc
> [   16.212026]  [<c100431a>] ? __xen_pgd_unpin+0x8b/0x9e
> [   16.212026]  [<c100468c>] ? xen_exit_mmap+0xcd/0xd5
> [   16.212026]  [<c109f1cb>] ? exit_mmap+0x3b/0x10e
> [   16.212026]  [<c10335fa>] ? mmput+0x1e/0x90
> [   16.212026]  [<c10b47bd>] ? flush_old_exec+0x3dc/0x4a2
> [   16.212026]  [<c10dc9aa>] ? load_elf_binary+0x31f/0x10b5
> [   16.212026]  [<c109c90b>] ? __get_user_pages+0x2e4/0x365
> [   16.212026]  [<c10b3d3b>] ? get_arg_page+0x2f/0x7a
> [   16.212026]  [<c10b400f>] ? search_binary_handler+0xef/0x25e
> [   16.212026]  [<c10dc68b>] ? load_elf_binary+0x0/0x10b5
> [   16.212026]  [<c10b5219>] ? do_execve+0x1f7/0x2ba
> [   16.212026]  [<c1006489>] ? sys_execve+0x23/0x47
> [   16.212026]  [<c10078a9>] ? syscall_call+0x7/0xb
> [   16.212026] ---[ end trace da601d15d0db46a8 ]---
>
>
>