Hi all
New kernel new problem
rsync over ssh to a usb disk get this scary message
Jan 17 17:39:50 champagne kernel: [30342.675488] 00000000: e8 1f 67 a1
7f 9c 65 fc c7 e9 c7 3f e1 d7 17 a7 ..g...e....?....
Jan 17 17:39:50 champagne kernel: [30342.675504] Filesystem "sdb1": XFS
internal error xfs_da_do_buf(2) at line 2112 of file
fs/xfs/xfs_da_btree.c. Caller 0xffffffffa0196e9f
Jan 17 17:39:50 champagne kernel: [30342.675514] Pid: 3953, comm: rsync
Not tainted 2.6.28 #1
Jan 17 17:39:50 champagne kernel: [30342.675518] Call Trace:
Jan 17 17:39:50 champagne kernel: [30342.675609] [<ffffffffa0196e9f>]
xfs_da_read_buf+0x24/0x29 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.675661] [<ffffffffa0196d6f>]
xfs_da_do_buf+0x560/0x626 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.675712] [<ffffffffa0196e9f>]
xfs_da_read_buf+0x24/0x29 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.675767] [<ffffffffa01c554b>]
xfs_buf_read_flags+0x12/0x81 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.675819] [<ffffffffa0196e9f>]
xfs_da_read_buf+0x24/0x29 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.675871] [<ffffffffa019bd77>]
xfs_dir2_leaf_lookup_int+0x56/0x25f [xfs]
Jan 17 17:39:50 champagne kernel: [30342.675924] [<ffffffffa019bd77>]
xfs_dir2_leaf_lookup_int+0x56/0x25f [xfs]
Jan 17 17:39:50 champagne kernel: [30342.675978] [<ffffffffa019c7e2>]
xfs_dir2_leaf_lookup+0x1f/0xb6 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.676030] [<ffffffffa01990c0>]
xfs_dir2_isleaf+0x19/0x4a [xfs]
Jan 17 17:39:50 champagne kernel: [30342.676107] [<ffffffffa0199839>]
xfs_dir_lookup+0x105/0x14f [xfs]
Jan 17 17:39:50 champagne kernel: [30342.676162] [<ffffffffa01bf6ef>]
xfs_lookup+0x48/0xa5 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.676173] [<ffffffff802a642a>]
d_rehash+0x2a/0x38
Jan 17 17:39:50 champagne kernel: [30342.676226] [<ffffffffa01c7e42>]
xfs_vn_lookup+0x3c/0x78 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.676234] [<ffffffff802a042a>]
do_lookup+0xd3/0x15d
Jan 17 17:39:50 champagne kernel: [30342.676241] [<ffffffff802a194c>]
__link_path_walk+0x5b7/0x705
Jan 17 17:39:50 champagne kernel: [30342.676248] [<ffffffff802a1f1f>]
path_walk+0x5e/0xba
Jan 17 17:39:50 champagne kernel: [30342.676255] [<ffffffff802a216d>]
do_path_lookup+0x162/0x1b9
Jan 17 17:39:50 champagne kernel: [30342.676261] [<ffffffff802a2d79>]
getname+0x162/0x1c4
Jan 17 17:39:50 champagne kernel: [30342.676268] [<ffffffff802a3666>]
user_path_at+0x48/0x79
Jan 17 17:39:50 champagne kernel: [30342.676277] [<ffffffff8029c5f9>]
cp_new_stat+0xe9/0xfc
Jan 17 17:39:50 champagne kernel: [30342.676284] [<ffffffff8029c482>]
vfs_lstat_fd+0x15/0x40
Jan 17 17:39:50 champagne kernel: [30342.676291] [<ffffffff8029c668>]
sys_newlstat+0x19/0x31
Jan 17 17:39:50 champagne kernel: [30342.676300] [<ffffffff8020b49b>]
system_call_fastpath+0x16/0x1b
Jan 17 17:39:50 champagne kernel: [30342.681742] 00000000: e8 1f 67 a1
7f 9c 65 fc c7 e9 c7 3f e1 d7 17 a7 ..g...e....?....
Jan 17 17:39:50 champagne kernel: [30342.681754] Filesystem "sdb1": XFS
internal error xfs_da_do_buf(2) at line 2112 of file
fs/xfs/xfs_da_btree.c. Caller 0xffffffffa0196e9f
Jan 17 17:39:50 champagne kernel: [30342.681763] Pid: 3953, comm: rsync
Not tainted 2.6.28 #1
Jan 17 17:39:50 champagne kernel: [30342.681767] Call Trace:
Jan 17 17:39:50 champagne kernel: [30342.681823] [<ffffffffa0196e9f>]
xfs_da_read_buf+0x24/0x29 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.681875] [<ffffffffa0196d6f>]
xfs_da_do_buf+0x560/0x626 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.681926] [<ffffffffa0196e9f>]
xfs_da_read_buf+0x24/0x29 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.681979] [<ffffffffa01c554b>]
xfs_buf_read_flags+0x12/0x81 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682031] [<ffffffffa0196e9f>]
xfs_da_read_buf+0x24/0x29 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682084] [<ffffffffa019bd77>]
xfs_dir2_leaf_lookup_int+0x56/0x25f [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682136] [<ffffffffa019bd77>]
xfs_dir2_leaf_lookup_int+0x56/0x25f [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682190] [<ffffffffa019c7e2>]
xfs_dir2_leaf_lookup+0x1f/0xb6 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682242] [<ffffffffa01990c0>]
xfs_dir2_isleaf+0x19/0x4a [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682294] [<ffffffffa0199839>]
xfs_dir_lookup+0x105/0x14f [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682348] [<ffffffffa01bf6ef>]
xfs_lookup+0x48/0xa5 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682358] [<ffffffff80294857>]
virt_to_head_page+0x29/0x36
Jan 17 17:39:50 champagne kernel: [30342.682412] [<ffffffffa01c7e42>]
xfs_vn_lookup+0x3c/0x78 [xfs]
Jan 17 17:39:50 champagne kernel: [30342.682419] [<ffffffff802a042a>]
do_lookup+0xd3/0x15d
Jan 17 17:39:50 champagne kernel: [30342.682426] [<ffffffff802a194c>]
__link_path_walk+2<f090ded__ss/pf21
Jan 17 17:39:50 champagne kernel: 6f<f2]s0f8 400x5++f4] 0fd
_sal[3xx34[ b4<1ff]fa+_sal[3x+1[ b4<5ff]fa+_sal[3xx99[30342. b4<5ff]fa
+0x_sal[3034x+7[ b4<4] [<ff]fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x0345ff2>fh_at+es )f
Jan 17 17:39:50 champagne kernel: [30342.687928] Pid: 3
Jan 17 17:39:50 champagne kernel: 4<x02ff2>fh_looku__s2f
Jan 17 17:39:50 champagne kernel: 4<x08ff2>fh__ )f
Jan 17 17:39:50 champagne kernel: 4<xf04ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x02ff>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x07ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x03ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x09ff2>h__s2f
Jan 17 17:39:50 champagne kernel: 4<x05fff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x01ff2>fhe )f
Jan 17 17:39:50 champagne kernel: 4<x07ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x04ff2>fh__ )f
Jan 17 17:39:50 champagne kernel: 4<x00ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: [ 4<x05ff2>f>h_s )f
Jan 17 17:39:50 champagne kernel: [30342
Jan 17 17:39:50 champagne kernel: 4<x01ff2>fh_s2f
Jan 17 17:39:50 champagne kernel: 4<x07ff2>h__s2f
Jan 17 17:39:50 champagne kernel: 4<x06ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x06ff2>fh__ )f
Jan 17 17:39:50 champagne kernel: 4<x08ff2>fh___s2f
Jan 17 17:39:50 champagne kernel: 4<x04ff2>fh__ )f
Jan 17 17:39:50 champagne kernel: <x09ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x07ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x03ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x08ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x08ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x04ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x09ff2>fh__ )f
Jan 17 17:39:50 champagne kernel: 4<x04ff2a>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x00ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x08ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x04ff2>fh__s2f
Jan 17 17:39:50 champagne kernel: 4<x00ff2>h__s2f
Jan 17 17:39:50 champagne kernel: 4<x06ff2a0>fh__s2f
and so on
after restart system mount usb disk clean, just to be sure I tried
xfs_repair but without any errors
root@champagne:/home/sandro# xfs_repair -v /dev/sdb1
Phase 1 - find and verify superblock...
- block cache size set to 358224 entries
Phase 2 - using internal log
- zero log...
zero_log: head block 93301 tail block 93301
- scan filesystem freespace and inode maps...
- found root inode chunk
Phase 3 - for each AG...
- scan and clear agi unlinked lists...
- process known inodes and perform inode discovery...
- agno = 0
- agno = 1
- agno = 2
- agno = 3
- process newly discovered inodes...
Phase 4 - check for duplicate blocks...
- setting up duplicate extent list...
- check for inodes claiming duplicate blocks...
- agno = 0
- agno = 1
- agno = 2
- agno = 3
Phase 5 - rebuild AG headers and trees...
- agno = 0
- agno = 1
- agno = 2
- agno = 3
- reset superblock...
Phase 6 - check inode connectivity...
- resetting contents of realtime bitmap and summary inodes
- traversing filesystem ...
- agno = 0
- agno = 1
- agno = 2
- agno = 3
- traversal finished ...
- moving disconnected inodes to lost+found ...
Phase 7 - verify and correct link counts...
XFS_REPAIR Summary Sat Jan 17 18:48:40 2009
Phase Start End Duration
Phase 1: 01/17 18:47:35 01/17 18:47:36 1 second
Phase 2: 01/17 18:47:36 01/17 18:47:41 5 seconds
Phase 3: 01/17 18:47:41 01/17 18:48:39 58 seconds
Phase 4: 01/17 18:48:39 01/17 18:48:40 1 second
Phase 5: 01/17 18:48:40 01/17 18:48:40
Phase 6: 01/17 18:48:40 01/17 18:48:40
Phase 7: 01/17 18:48:40 01/17 18:48:40
Total run time: 1 minute, 5 seconds
done
Linux champagne 2.6.28 #1 SMP Sat Jan 10 17:53:58 CET 2009 x86_64
GNU/Linux
Gnu C 4.3.2
Gnu make 3.81
binutils 2.18.93.20081009
util-linux 2.14
mount support
module-init-tools 3.3-pre11
e2fsprogs 1.41.3
reiserfsprogs 3.6.19
xfsprogs 2.9.8
pcmciautils 014
PPP 2.4.4
Linux C Library 2.8.90
Dynamic linker (ldd) 2.8.90
Procps 3.2.7
Net-tools 1.60
Console-tools 0.2.3
Sh-utils 6.10
udev 124
wireless-tools 29
Modules Loaded usb_storage libusual af_packet binfmt_misc rfcomm
bnep sco l2cap ipt_MASQUERADE iptable_nat ipt_REJECT bridge stp llc
kvm_intel kvm acpi_cpufreq cpufreq_userspace cpufreq_stats
cpufreq_powersave cpufreq_ondemand freq_table cpufreq_conservative sbs
sbshc pci_slot ipt_LOG xt_limit ipt_addrtype xt_state xt_tcpudp
xt_conntrack ip6table_filter ip6_tables ipv6 nf_nat_irc nf_conntrack_irc
nf_nat_ftp nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_conntrack_ftp
nf_conntrack iptable_filter ip_tables x_tables ext3 jbd mbcache joydev
hp_wmi coretemp sbp2 loop snd_hda_intel snd_hwdep snd_pcm_oss arc4
snd_pcm ecb snd_page_alloc snd_mixer_oss snd_seq_midi snd_seq_dummy
snd_seq_oss snd_rawmidi iwlagn snd_seq_midi_event snd_seq snd_timer
snd_seq_device tpm_infineon iwlcore parport_pc parport btusb pcmcia
sdhci_pcisdhci tpm tpm_bios rfkill video output snd lis3lv02d bluetooth
psmouse mmc_core container ricoh_mmc mac80211 leds_hp_disk led_class ac
pcspkr wmi serio_raw yenta_socket rsrc_nonstatic pcmcia_core battery
button cfg80211 iTCO_wdt iTCO_vendor_support soundcore evdev
dm_multipath xfs sd_mod crc_t10dif sg sr_mod cdrom ahci ata_piix
ohci1394 ieee1394 libata scsi_mod ehci_hcd uhci_hcd e1000e usbcore
dm_crypt dm_mirror dm_region_hash dm_log dm_snapshot dm_mod thermal
processor fan thermal_sys hwmon fuse
--
---
Cordiali Saluti
Alessandro Bono
Alessandro Bono wrote:
> Hi all
>
> New kernel new problem
> rsync over ssh to a usb disk get this scary message
>
> Jan 17 17:39:50 champagne kernel: [30342.675488] 00000000: e8 1f 67 a1
> 7f 9c 65 fc c7 e9 c7 3f e1 d7 17 a7 ..g...e....?....
> Jan 17 17:39:50 champagne kernel: [30342.675504] Filesystem "sdb1": XFS
> internal error xfs_da_do_buf(2) at line 2112 of file
> fs/xfs/xfs_da_btree.c. Caller 0xffffffffa0196e9f
This means that it read metadata with a bad magic number (the message
could be clearer).
Any storage errors in dmesg prior to this?
-Eric
On Sat, 2009-01-17 at 15:06 -0600, Eric Sandeen wrote:
> Alessandro Bono wrote:
> > Hi all
> >
> > New kernel new problem
> > rsync over ssh to a usb disk get this scary message
> >
> > Jan 17 17:39:50 champagne kernel: [30342.675488] 00000000: e8 1f 67 a1
> > 7f 9c 65 fc c7 e9 c7 3f e1 d7 17 a7 ..g...e....?....
> > Jan 17 17:39:50 champagne kernel: [30342.675504] Filesystem "sdb1": XFS
> > internal error xfs_da_do_buf(2) at line 2112 of file
> > fs/xfs/xfs_da_btree.c. Caller 0xffffffffa0196e9f
>
> This means that it read metadata with a bad magic number (the message
> could be clearer).
>
> Any storage errors in dmesg prior to this?
In effect there is this message
Jan 17 17:39:49 champagne kernel: [30342.273029] usb 4-3: reset high
speed USB device using ehci_hcd and address 4
I was using a usb pen that I disconnected just a second prior errors
appear, for some reason plugging out usb pen caused a reset on usb disk
(two port located on two side on my notebook, I didn't touch usb disk
while disconnecting usb pen)
Jan 17 17:34:47 champagne kernel: [30039.760046] usb 4-5: new high speed
USB device using ehci_hcd and address 5
Jan 17 17:34:47 champagne kernel: [30039.902828] usb 4-5: configuration
#1 chosen from 1 choice
Jan 17 17:34:47 champagne kernel: [30039.955770] scsi7 : SCSI emulation
for USB Mass Storage devices
Jan 17 17:34:47 champagne kernel: [30039.959464] usb-storage: device
found at 5
Jan 17 17:34:47 champagne kernel: [30039.959470] usb-storage: waiting
for device to settle before scanning
Jan 17 17:34:52 champagne kernel: [30044.956409] usb-storage: device
scan complete
Jan 17 17:34:52 champagne kernel: [30044.957497] scsi 7:0:0:0:
Direct-Access USB Driver 0.00 PQ: 0 ANSI: 0 CCS
Jan 17 17:34:52 champagne kernel: [30045.242137] sd 7:0:0:0: [sdc]
31318016 512-byte hardware sectors: (16.0 GB/14.9 GiB)
Jan 17 17:34:52 champagne kernel: [30045.242644] sd 7:0:0:0: [sdc] Write
Protect is off
Jan 17 17:34:52 champagne kernel: [30045.242650] sd 7:0:0:0: [sdc] Mode
Sense: 23 00 00 00
Jan 17 17:34:52 champagne kernel: [30045.242655] sd 7:0:0:0: [sdc]
Assuming drive cache: write through
Jan 17 17:34:52 champagne kernel: [30045.249520] sd 7:0:0:0: [sdc]
31318016 512-byte hardware sectors: (16.0 GB/14.9 GiB)
Jan 17 17:34:52 champagne kernel: [30045.250007] sd 7:0:0:0: [sdc] Write
Protect is off
Jan 17 17:34:52 champagne kernel: [30045.250013] sd 7:0:0:0: [sdc] Mode
Sense: 23 00 00 00
Jan 17 17:34:52 champagne kernel: [30045.250018] sd 7:0:0:0: [sdc]
Assuming drive cache: write through
Jan 17 17:34:52 champagne kernel: [30045.250028] sdc: sdc1
Jan 17 17:34:52 champagne kernel: [30045.250863] sd 7:0:0:0: [sdc]
Attached SCSI removable disk
Jan 17 17:34:52 champagne kernel: [30045.251074] sd 7:0:0:0: Attached
scsi generic sg3 type 0
Jan 17 17:39:49 champagne kernel: [30342.166347] usb 4-5: USB
disconnect, address 5
Jan 17 17:39:49 champagne kernel: [30342.273029] usb 4-3: reset high
speed USB device using ehci_hcd and address 4
Is this a usb problem?
thanks Eric
>
> -Eric
--
---
Cordiali Saluti
Alessandro Bono