#1855 new defect

Potential interaction problem between tahoe backup and virtualbox shared drives on OSX host / debian guest.

Reported by: nejucomo Owned by: davidsarah
Priority: normal Milestone: undecided
Component: code Version: 1.9.2
Keywords: virtualbox mac tahoe-backup hang Cc:
Launchpad Bug:

Description

Concise Symptoms:

On debian inside a virtualbox instance on top of osx, I ran a long tahoe backup --verbose foo tahoe:path/in/grid command overnight. In the morning the process appears to be hanging mid-line in the output. Dmesg shows stack traces in the kernel.

Detail:

The output of the backup process has hundreds of lines about uploading and processing various paths. The final line is incomplete and the process appears to be hanging. I know the line is incomplete because it says:

uploading '<path omitted>'..
  <path omitted> -> URI:CHK:<letters_and_digits>:<letters_and_digits>:1:1:

-The final field of the URI is missing (which I believe is the file size).

When I examine dmesg I see:

[456046.974585] vboxsf: Successfully loaded version 3.2.10_OSE (interface 0x00010004)
[460007.478870] ------------[ cut here ]------------
[460007.478877] WARNING: at /build/buildd-linux-2.6_2.6.32-41squeeze2-amd64-NDo8b7/linux-2.6-2.6.32/debian/build/source_amd64_none/fs/sysfs/dir.c:491 sysfs_add_one+0xcc/0xe4()
[460007.478879] Hardware name: VirtualBox
[460007.478880] sysfs: cannot create duplicate filename '/devices/virtual/bdi/vboxsf-Music'
[460007.478882] Modules linked in: vboxsf vboxvideo drm fuse loop snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm psmouse i2c_piix4 i2c_core snd_timer serio_raw evdev battery snd joydev vboxguest parport_pc parport processor button ac soundcore snd_page_alloc pcspkr ext3 jbd mbcache dm_mod usbhid hid sg sr_mod cdrom sd_mod crc_t10dif ata_generic ohci_hcd ehci_hcd ata_piix ahci usbcore libata scsi_mod thermal thermal_sys e1000 nls_base [last unloaded: scsi_wait_scan]
[460007.478909] Pid: 14833, comm: mount.vboxsf Not tainted 2.6.32-5-amd64 #1
[460007.478910] Call Trace:
[460007.478913]  [<ffffffff8114037f>] ? sysfs_add_one+0xcc/0xe4
[460007.478915]  [<ffffffff8114037f>] ? sysfs_add_one+0xcc/0xe4
[460007.478918]  [<ffffffff8104df9c>] ? warn_slowpath_common+0x77/0xa3
[460007.478921]  [<ffffffff8104e024>] ? warn_slowpath_fmt+0x51/0x59
[460007.478923]  [<ffffffff811402ab>] ? sysfs_pathname+0x35/0x3d
[460007.478925]  [<ffffffff811402ab>] ? sysfs_pathname+0x35/0x3d
[460007.478926]  [<ffffffff811402ab>] ? sysfs_pathname+0x35/0x3d
[460007.478928]  [<ffffffff811402ab>] ? sysfs_pathname+0x35/0x3d
[460007.478930]  [<ffffffff8114037f>] ? sysfs_add_one+0xcc/0xe4
[460007.478932]  [<ffffffff811408fb>] ? create_dir+0x4f/0x7c
[460007.478934]  [<ffffffff8114095d>] ? sysfs_create_dir+0x35/0x4a
[460007.478937]  [<ffffffff8118f9c3>] ? kobject_get+0x12/0x17
[460007.478939]  [<ffffffff8118faf9>] ? kobject_add_internal+0xcb/0x181
[460007.478942]  [<ffffffff8118fd5b>] ? kobject_add+0x74/0x7c
[460007.478944]  [<ffffffff81195755>] ? vsnprintf+0x1ed/0x449
[460007.478948]  [<ffffffff812fc266>] ? mutex_lock+0xd/0x31
[460007.478950]  [<ffffffff8118f9c3>] ? kobject_get+0x12/0x17
[460007.478953]  [<ffffffff8121e350>] ? get_device_parent+0x105/0x17f
[460007.478955]  [<ffffffff8121f1e2>] ? device_add+0xce/0x537
[460007.478958]  [<ffffffff8121f6f3>] ? device_create_vargs+0x96/0xc2
[460007.478961]  [<ffffffff810c8cdc>] ? bdi_register+0x6a/0x175
[460007.478963]  [<ffffffff812fc266>] ? mutex_lock+0xd/0x31
[460007.478966]  [<ffffffff8119bad7>] ? __percpu_counter_init+0x72/0x7b
[460007.478969]  [<ffffffff810c89fd>] ? bdi_init+0x14f/0x17b
[460007.478973]  [<ffffffffa0099e5b>] ? sf_init_backing_dev+0x24/0x44 [vboxsf]
[460007.478975]  [<ffffffffa0098595>] ? sf_read_super_26+0x506/0x5b1 [vboxsf]
[460007.478978]  [<ffffffffa009808f>] ? sf_read_super_26+0x0/0x5b1 [vboxsf]
[460007.478982]  [<ffffffff810f134d>] ? get_sb_nodev+0x4f/0x83
[460007.478984]  [<ffffffff810f10b7>] ? vfs_kern_mount+0x99/0x14b
[460007.478986]  [<ffffffff810f11bc>] ? do_kern_mount+0x43/0xe2
[460007.478989]  [<ffffffff811052ab>] ? do_mount+0x72a/0x792
[460007.478992]  [<ffffffff81105393>] ? sys_mount+0x80/0xbd
[460007.478994]  [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
[460007.478996] ---[ end trace a6619f2d830bc124 ]---
[460007.478998] kobject_add_internal failed for vboxsf-Music with -EEXIST, don't try to register things with the same name in the same directory.
[460007.479001] Pid: 14833, comm: mount.vboxsf Tainted: G        W  2.6.32-5-amd64 #1
[460007.479003] Call Trace:
[460007.479004]  [<ffffffff8118fb9c>] ? kobject_add_internal+0x16e/0x181
[460007.479006]  [<ffffffff8118fd5b>] ? kobject_add+0x74/0x7c
[460007.479009]  [<ffffffff81195755>] ? vsnprintf+0x1ed/0x449
[460007.479011]  [<ffffffff812fc266>] ? mutex_lock+0xd/0x31
[460007.479013]  [<ffffffff8118f9c3>] ? kobject_get+0x12/0x17
[460007.479014]  [<ffffffff8121e350>] ? get_device_parent+0x105/0x17f
[460007.479016]  [<ffffffff8121f1e2>] ? device_add+0xce/0x537
[460007.479019]  [<ffffffff8121f6f3>] ? device_create_vargs+0x96/0xc2
[460007.479021]  [<ffffffff810c8cdc>] ? bdi_register+0x6a/0x175
[460007.479023]  [<ffffffff812fc266>] ? mutex_lock+0xd/0x31
[460007.479025]  [<ffffffff8119bad7>] ? __percpu_counter_init+0x72/0x7b
[460007.479028]  [<ffffffff810c89fd>] ? bdi_init+0x14f/0x17b
[460007.479030]  [<ffffffffa0099e5b>] ? sf_init_backing_dev+0x24/0x44 [vboxsf]
[460007.479033]  [<ffffffffa0098595>] ? sf_read_super_26+0x506/0x5b1 [vboxsf]
[460007.479035]  [<ffffffffa009808f>] ? sf_read_super_26+0x0/0x5b1 [vboxsf]
[460007.479038]  [<ffffffff810f134d>] ? get_sb_nodev+0x4f/0x83
[460007.479040]  [<ffffffff810f10b7>] ? vfs_kern_mount+0x99/0x14b
[460007.479042]  [<ffffffff810f11bc>] ? do_kern_mount+0x43/0xe2
[460007.479045]  [<ffffffff811052ab>] ? do_mount+0x72a/0x792
[460007.479047]  [<ffffffff81105393>] ? sys_mount+0x80/0xbd
[460007.479049]  [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
[460007.479051] ------------[ cut here ]------------
[460007.479054] WARNING: at /build/buildd-linux-2.6_2.6.32-41squeeze2-amd64-NDo8b7/linux-2.6-2.6.32/debian/build/source_amd64_none/fs/inode.c:1272 generic_detach_inode+0x88/0x16b()
[460007.479056] Hardware name: VirtualBox
[460007.479057] Modules linked in: vboxsf vboxvideo drm fuse loop snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm psmouse i2c_piix4 i2c_core snd_timer serio_raw evdev battery snd joydev vboxguest parport_pc parport processor button ac soundcore snd_page_alloc pcspkr ext3 jbd mbcache dm_mod usbhid hid sg sr_mod cdrom sd_mod crc_t10dif ata_generic ohci_hcd ehci_hcd ata_piix ahci usbcore libata scsi_mod thermal thermal_sys e1000 nls_base [last unloaded: scsi_wait_scan]
[460007.479076] Pid: 14833, comm: mount.vboxsf Tainted: G        W  2.6.32-5-amd64 #1
[460007.479077] Call Trace:
[460007.479079]  [<ffffffff810fff41>] ? generic_detach_inode+0x88/0x16b
[460007.479081]  [<ffffffff810fff41>] ? generic_detach_inode+0x88/0x16b
[460007.479083]  [<ffffffff8104df9c>] ? warn_slowpath_common+0x77/0xa3
[460007.479086]  [<ffffffff810fff41>] ? generic_detach_inode+0x88/0x16b
[460007.479088]  [<ffffffff81100d9d>] ? generic_drop_inode+0x15/0x58
[460007.479090]  [<ffffffffa00985fa>] ? sf_read_super_26+0x56b/0x5b1 [vboxsf]
[460007.479093]  [<ffffffffa009808f>] ? sf_read_super_26+0x0/0x5b1 [vboxsf]
[460007.479095]  [<ffffffff810f134d>] ? get_sb_nodev+0x4f/0x83
[460007.479097]  [<ffffffff810f10b7>] ? vfs_kern_mount+0x99/0x14b
[460007.479100]  [<ffffffff810f11bc>] ? do_kern_mount+0x43/0xe2
[460007.479102]  [<ffffffff811052ab>] ? do_mount+0x72a/0x792
[460007.479104]  [<ffffffff81105393>] ? sys_mount+0x80/0xbd
[460007.479106]  [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
[460007.479107] ---[ end trace a6619f2d830bc125 ]---
[460007.479109] ------------[ cut here ]------------
[460007.479111] WARNING: at /build/buildd-linux-2.6_2.6.32-41squeeze2-amd64-NDo8b7/linux-2.6-2.6.32/debian/build/source_amd64_none/fs/inode.c:1277 generic_detach_inode+0xca/0x16b()
[460007.479113] Hardware name: VirtualBox
[460007.479114] Modules linked in: vboxsf vboxvideo drm fuse loop snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm psmouse i2c_piix4 i2c_core snd_timer serio_raw evdev battery snd joydev vboxguest parport_pc parport processor button ac soundcore snd_page_alloc pcspkr ext3 jbd mbcache dm_mod usbhid hid sg sr_mod cdrom sd_mod crc_t10dif ata_generic ohci_hcd ehci_hcd ata_piix ahci usbcore libata scsi_mod thermal thermal_sys e1000 nls_base [last unloaded: scsi_wait_scan]
[460007.479132] Pid: 14833, comm: mount.vboxsf Tainted: G        W  2.6.32-5-amd64 #1
[460007.479133] Call Trace:
[460007.479136]  [<ffffffff810fff83>] ? generic_detach_inode+0xca/0x16b
[460007.479138]  [<ffffffff810fff83>] ? generic_detach_inode+0xca/0x16b
[460007.479140]  [<ffffffff8104df9c>] ? warn_slowpath_common+0x77/0xa3
[460007.479142]  [<ffffffff810fff83>] ? generic_detach_inode+0xca/0x16b
[460007.479144]  [<ffffffff81100d9d>] ? generic_drop_inode+0x15/0x58
[460007.479147]  [<ffffffffa00985fa>] ? sf_read_super_26+0x56b/0x5b1 [vboxsf]
[460007.479149]  [<ffffffffa009808f>] ? sf_read_super_26+0x0/0x5b1 [vboxsf]
[460007.479151]  [<ffffffff810f134d>] ? get_sb_nodev+0x4f/0x83
[460007.479154]  [<ffffffff810f10b7>] ? vfs_kern_mount+0x99/0x14b
[460007.479156]  [<ffffffff810f11bc>] ? do_kern_mount+0x43/0xe2
[460007.479158]  [<ffffffff811052ab>] ? do_mount+0x72a/0x792
[460007.479160]  [<ffffffff81105393>] ? sys_mount+0x80/0xbd
[460007.479162]  [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
[460007.479163] ---[ end trace a6619f2d830bc126 ]---
[460007.479164] ------------[ cut here ]------------
[460007.479167] WARNING: at /build/buildd-linux-2.6_2.6.32-41squeeze2-amd64-NDo8b7/linux-2.6-2.6.32/debian/build/source_amd64_none/fs/inode.c:1284 generic_detach_inode+0x14f/0x16b()
[460007.479169] Hardware name: VirtualBox
[460007.479169] Modules linked in: vboxsf vboxvideo drm fuse loop snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm psmouse i2c_piix4 i2c_core snd_timer serio_raw evdev battery snd joydev vboxguest parport_pc parport processor button ac soundcore snd_page_alloc pcspkr ext3 jbd mbcache dm_mod usbhid hid sg sr_mod cdrom sd_mod crc_t10dif ata_generic ohci_hcd ehci_hcd ata_piix ahci usbcore libata scsi_mod thermal thermal_sys e1000 nls_base [last unloaded: scsi_wait_scan]
[460007.479188] Pid: 14833, comm: mount.vboxsf Tainted: G        W  2.6.32-5-amd64 #1
[460007.479189] Call Trace:
[460007.479191]  [<ffffffff81100008>] ? generic_detach_inode+0x14f/0x16b
[460007.479193]  [<ffffffff81100008>] ? generic_detach_inode+0x14f/0x16b
[460007.479195]  [<ffffffff8104df9c>] ? warn_slowpath_common+0x77/0xa3
[460007.479198]  [<ffffffff81100008>] ? generic_detach_inode+0x14f/0x16b
[460007.479200]  [<ffffffff81100d9d>] ? generic_drop_inode+0x15/0x58
[460007.479202]  [<ffffffffa00985fa>] ? sf_read_super_26+0x56b/0x5b1 [vboxsf]
[460007.479205]  [<ffffffffa009808f>] ? sf_read_super_26+0x0/0x5b1 [vboxsf]
[460007.479207]  [<ffffffff810f134d>] ? get_sb_nodev+0x4f/0x83
[460007.479209]  [<ffffffff810f10b7>] ? vfs_kern_mount+0x99/0x14b
[460007.479211]  [<ffffffff810f11bc>] ? do_kern_mount+0x43/0xe2
[460007.479214]  [<ffffffff811052ab>] ? do_mount+0x72a/0x792
[460007.479216]  [<ffffffff81105393>] ? sys_mount+0x80/0xbd
[460007.479218]  [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
[460007.479219] ---[ end trace a6619f2d830bc127 ]---
[460007.479522] sf_read_super_aux err=-22

Note:

I'm filing this for completeness or posterity, but there are several things I should test: upgrading to the latest virtual box & upgrading my kernel, for example.

This may be very specific to debian+virtualbox+osx all at particular versions.

Change History (2)

comment:1 Changed at 2012-11-18T06:48:31Z by nejucomo

I asserted the cause of the exception was a database lock, but actually the database is owned by a different user. When I killed the suspended backup and re-tried the secondary (aka "new") backup, it failed with the same exception details.

comment:2 Changed at 2012-11-22T01:13:37Z by davidsarah

  • Component changed from unknown to code
  • Keywords virtualbox mac tahoe-backup hang added
Note: See TracTickets for help on using tickets.