Firewire oddity..

Daniel O'Connor doconnor at gsoft.com.au
Tue Aug 9 10:27:45 GMT 2005


I got the following while trying to rsync a large number of files over 
the network to a firewire HD (FAT32 FS) on a 6.0-BETA1 system.

Aug  9 12:48:17 inchoate kernel: firewire0: split transaction timeout dst=0xffc0 tl=0x36 state=3
Aug  9 12:48:17 inchoate kernel: sbp_orb_pointer_callback: xfer->resp = 60
Aug  9 12:48:17 inchoate kernel: fw_rcv: unknown response WRES(2) src=0xffc0 tl=0x36 rt=1 data=0x84513864
Aug  9 12:48:17 inchoate kernel: try ad-hoc work around!!
Aug  9 12:48:17 inchoate kernel: no use...
Aug  9 12:48:19 inchoate kernel: firewire0: split transaction timeout dst=0xffc0 tl=0x8 state=3
Aug  9 12:48:19 inchoate kernel: sbp_orb_pointer_callback: xfer->resp = 60
Aug  9 12:48:19 inchoate kernel: fw_rcv: unknown response WRES(2) src=0xffc0 tl=0x8 rt=1 data=0x84518aad
Aug  9 12:48:19 inchoate kernel: try ad-hoc work around!!
Aug  9 12:48:19 inchoate kernel: no use...

da0 at sbp0 bus 0 target 0 lun 0
da0: <Oxford 911D 0037> Fixed Simplified Direct Access SCSI-4 device
da0: 50.000MB/s transfers
da0: 114473MB (234441648 512 byte sectors: 255H 63S/T 14593C)

My interactive performance has gone down the tube too :(

here is a systat -vmstat snapshot
    2 users    Load 13.14  7.58  4.75                  Aug  9 16:06

Mem:KB    REAL            VIRTUAL                     VN PAGER  SWAP PAGER
        Tot   Share      Tot    Share    Free         in  out     in  out
Act  273168   43380   749572   105288   20760 count                1
All  497356   51660 17265484   156920         pages                2
                                                          zfod   Interrupts
Proc:r  p  d  s  w    Csw  Trp  Sys  Int  Sof  Flt        cow    1514 total
     1     1134      1505   44 2374 2005  240    1  69176 wire   1007 0: clk
                                                   273368 act         1: atkb
92.4%Sys   1.5%Intr  2.3%User  0.0%Nice  3.8%Idl   137800 inact       3: sio1
|    |    |    |    |    |    |    |    |    |      19780 cache       4: sio0
==============================================+>      980 free        7: ppc0
                                                          daefr   129 8: rtc
Namei         Name-cache    Dir-cache                     prcfr       9: pcm0
    Calls     hits    %     hits    %                     react   350 11: nvi
     2276     2189   96                                   pdwak       12: psm
                                                          pdpgs       13: npx
Disks   ad0   da0   cd0 pass0 pass1                     1 intrn    28 14: ata
KB/t   4.07  4.00  0.00  0.00  0.00                 61024 buf         15: ata
tps      28    70     0     0     0                   268 dirtybuf
MB/s   0.11  0.27  0.00  0.00  0.00                 35360 desiredvnodes
% busy   69    32     0     0     0                 10098 numvnodes
                                                     6637 freevnodes


IRQ 11 is my "super fun IRQ" with 90% of my hardware attached..

I am copying about 17000 45k files. The controller is the one built into my
Dell Inspiron 8600 ->
Aug  9 11:29:24 inchoate kernel: fwohci0: <1394 Open Host Controller Interface> mem 0xfaffd800-0xfaffdfff,0xfaff8000-0xfaffbfff irq 11 at device 1.1 on pci2
Aug  9 11:29:24 inchoate kernel: fwohci0: [GIANT-LOCKED]
Aug  9 11:29:24 inchoate kernel: fwohci0: OHCI version 1.10 (ROM=0)
Aug  9 11:29:24 inchoate kernel: fwohci0: No. of Isochronous channels is 4.
Aug  9 11:29:24 inchoate kernel: fwohci0: EUI64 43:4f:c0:00:08:17:a4:38
Aug  9 11:29:24 inchoate kernel: fwohci0: Phy 1394a available S400, 2 ports.
Aug  9 11:29:24 inchoate kernel: fwohci0: Link S400, max_rec 2048 bytes.

When  I tried this earlier with 65000 much larger files I managed to get 
rsync to use 125% of the CPU (no HT/SMP here)..

-- 
Daniel O'Connor software and network engineer
for Genesis Software - http://www.gsoft.com.au
"The nice thing about standards is that there
are so many of them to choose from."
  -- Andrew Tanenbaum
GPG Fingerprint - 5596 B766 97C0 0E94 4347 295E E593 DC20 7B3F CE8C
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 187 bytes
Desc: not available
Url : http://lists.freebsd.org/pipermail/freebsd-stable/attachments/20050809/45ede0c9/attachment.bin


More information about the freebsd-stable mailing list