Thread: node not destroyed on unplug
Brought to you by:
aeb,
bencollins
From: Carl K. <ca...@pe...> - 2011-12-20 15:42:03
|
un/re-plugging the cable. waiting a few seconds between each (but not watching syslog, so maybe didn't wait long enough...) - 3 or 4 worked fine, fw2,3 created/destroyed as expected, then I unplug, and fw2 doesn't destroy: juser@pc8:~$ ls /dev/fw? /dev/fw0 /dev/fw1 /dev/fw2 and syslog has a ton of Dec 20 09:08:22 pc8 kernel: [ 2325.059013] firewire_ohci: AR evt_bus_reset, generation 0 .... 0-255 loop that 25 times. Dec 20 09:08:08 pc8 kernel: [ 2310.108394] firewire_ohci: AR evt_bus_reset, generation 4 Dec 20 09:08:08 pc8 kernel: [ 2310.108482] firewire_ohci: isochronous cycle too long Dec 20 09:08:08 pc8 kernel: [ 2310.108614] firewire_ohci: AR evt_bus_reset, generation 5 Dec 20 09:08:08 pc8 kernel: [ 2310.108786] firewire_ohci: AR evt_bus_reset, generation 6 Dec 20 09:08:08 pc8 kernel: [ 2310.108993] firewire_ohci: 1 selfIDs, generation 6, local node ID ffc0 Dec 20 09:08:08 pc8 kernel: [ 2310.109004] firewire_ohci: selfID 0: 807f8842, phy 0 [-..] S400 gc=63 + 0W Lci Dec 20 09:08:08 pc8 kernel: [ 2310.109011] firewire_core: skipped bus generations, destroying all node s Dec 20 09:08:08 pc8 kernel: [ 2310.109097] firewire_ohci: AR evt_bus_reset, generation 4 Dec 20 09:08:08 pc8 kernel: [ 2310.109113] firewire_ohci: 1 selfIDs, generation 4, local node ID ffc0 Dec 20 09:08:08 pc8 kernel: [ 2310.109122] firewire_ohci: selfID 0: 807fcc56, phy 0 [---] beta gc=63 -3W Lci Dec 20 09:08:08 pc8 kernel: [ 2310.608114] firewire_core: rediscovered device fw0 Dec 20 09:08:08 pc8 kernel: [ 2310.608188] firewire_ohci: AT spd 0 tl 15, ffc0 -> ffc1, evt_missing_ack, QW req, fffff0000234 = c000001f Dec 20 09:08:08 pc8 kernel: [ 2310.845150] firewire_ohci: AR evt_bus_reset, generation 5 Dec 20 09:08:08 pc8 kernel: [ 2310.845622] firewire_ohci: AR evt_bus_reset, generation 6 ++ 28705 times, nothing else. juser@pc8:~$ grep "09:08:.*firewire_ohci: AR evt_bus_reset, generation" /var/log/syslog |wc 28705 344460 2599790 Dec 20 09:08:23 pc8 kernel: [ 2325.200602] firewire_ohci: AR evt_bus_reset, generation 27 Dec 20 09:08:23 pc8 kernel: [ 2325.201097] firewire_ohci: AR evt_bus_reset, generation 28 Dec 20 09:08:23 pc8 kernel: [ 2325.201596] firewire_ohci: AR evt_bus_reset, generation 29 Dec 20 09:08:23 pc8 kernel: [ 2325.201713] firewire_ohci: AR evt_bus_reset, generation 7 Dec 20 09:08:23 pc8 kernel: [ 2325.201946] firewire_ohci: 2 selfIDs, generation 29, local node ID ffc1 Dec 20 09:08:23 pc8 kernel: [ 2325.201958] firewire_ohci: selfID 0: 807f8880, phy 0 [p..] S400 gc=63 +0W Lc Dec 20 09:08:23 pc8 kernel: [ 2325.201968] firewire_ohci: selfID 0: 817fccd6, phy 1 [c--] beta gc=63 -3W Lci Dec 20 09:08:23 pc8 kernel: [ 2325.201975] firewire_core: skipped bus generations, destroying all nodes Dec 20 09:08:23 pc8 kernel: [ 2325.201984] firewire_ohci: isochronous cycle inconsistent Dec 20 09:08:23 pc8 kernel: [ 2325.202027] firewire_ohci: 2 selfIDs, generation 7, local node ID ffc0 Dec 20 09:08:23 pc8 kernel: [ 2325.202035] firewire_ohci: selfID 0: 807f8880, phy 0 [p..] S400 gc=63 +0W Lc Dec 20 09:08:23 pc8 kernel: [ 2325.202043] firewire_ohci: selfID 0: 817fccd6, phy 1 [c--] beta gc=63 -3W Lci Dec 20 09:08:23 pc8 kernel: [ 2325.202100] firewire_ohci: AR spd 0 tl 16, ffc1 -> ffc0, ack_complete, Lk resp 4,2 Dec 20 09:08:23 pc8 kernel: [ 2325.202113] firewire_ohci: AT spd 0 tl 16, ffc0 -> ffc1, pending/cancelled, Lk req, fffff000021c 8,2 Dec 20 09:08:23 pc8 kernel: [ 2325.456176] firewire_ohci: AT spd 0 tl 11, ffc1 -> ffc0, ack_pending , QW req, fffff0000234 = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.456202] firewire_ohci: AR spd 0 tl 11, ffc1 -> ffc0, ack_pending , QW req, fffff0000234 = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.456243] firewire_ohci: AR spd 0 tl 11, ffc0 -> ffc1, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.456257] firewire_ohci: AT spd 0 tl 11, ffc0 -> ffc1, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.700144] firewire_core: rediscovered device fw1 Dec 20 09:08:23 pc8 kernel: [ 2325.700187] firewire_ohci: AR spd 0 tl 28, ffc0 -> ffc1, ack_complete, QR resp = 0404e334 Dec 20 09:08:23 pc8 kernel: [ 2325.700199] firewire_ohci: AT spd 0 tl 28, ffc1 -> ffc0, pending/cancelled, QR req, fffff0000400 Dec 20 09:08:23 pc8 kernel: [ 2325.700237] firewire_ohci: AR spd 0 tl 29, ffc0 -> ffc1, ack_complete, QR resp = 31333934 Dec 20 09:08:23 pc8 kernel: [ 2325.700247] firewire_ohci: AT spd 0 tl 29, ffc1 -> ffc0, pending/cancelled, QR req, fffff0000404 Dec 20 09:08:23 pc8 kernel: [ 2325.700285] firewire_ohci: AT spd 0 tl 2a, ffc1 -> ffc0, ack_pending , QR req, fffff0000408 Dec 20 09:08:23 pc8 kernel: [ 2325.700300] firewire_ohci: AT spd 0 tl 17, ffc0 -> ffc1, ack_pending , QR req, fffff0000400 Dec 20 09:08:23 pc8 kernel: [ 2325.700315] firewire_ohci: AR spd 0 tl 17, ffc1 -> ffc0, ack_complete, QR resp = 0404fb9d Dec 20 09:08:23 pc8 kernel: [ 2325.700328] firewire_ohci: AR spd 0 tl 2a, ffc0 -> ffc1, ack_complete, QR resp = f000a222 Dec 20 09:08:23 pc8 kernel: [ 2325.700373] firewire_ohci: AT spd 0 tl 18, ffc0 -> ffc1, ack_pending , QR req, fffff0000404 Dec 20 09:08:23 pc8 kernel: [ 2325.700384] firewire_ohci: AT spd 0 tl 2b, ffc1 -> ffc0, ack_pending , QR req, fffff000040c Dec 20 09:08:23 pc8 kernel: [ 2325.700394] firewire_ohci: AR spd 0 tl 18, ffc1 -> ffc0, ack_complete, QR resp = 31333934 Dec 20 09:08:23 pc8 kernel: [ 2325.700413] firewire_ohci: AR spd 0 tl 2b, ffc0 -> ffc1, ack_complete, QR resp = 00241b00 Dec 20 09:08:23 pc8 kernel: [ 2325.700450] firewire_ohci: AT spd 0 tl 2c, ffc1 -> ffc0, ack_pending , QR req, fffff0000410 Dec 20 09:08:23 pc8 kernel: [ 2325.700461] firewire_ohci: AR spd 0 tl 19, ffc1 -> ffc0, ack_complete, QR resp = f000b223 Dec 20 09:08:23 pc8 kernel: [ 2325.700471] firewire_ohci: AT spd 0 tl 19, ffc0 -> ffc1, pending/cancelled, QR req, fffff0000408 Dec 20 09:08:23 pc8 kernel: [ 2325.700488] firewire_ohci: AR spd 0 tl 2c, ffc0 -> ffc1, ack_complete, QR resp = 964cac00 Dec 20 09:08:23 pc8 kernel: [ 2325.700540] firewire_ohci: AT spd 0 tl 1a, ffc0 -> ffc1, ack_pending , QR req, fffff000040c Dec 20 09:08:23 pc8 kernel: [ 2325.700551] firewire_ohci: AT spd 0 tl 2d, ffc1 -> ffc0, ack_pending , QR req, fffff0000414 Dec 20 09:08:23 pc8 kernel: [ 2325.700566] firewire_ohci: AR spd 0 tl 1a, ffc1 -> ffc0, ack_complete, QR resp = 01080000 Dec 20 09:08:23 pc8 kernel: [ 2325.700579] firewire_ohci: AR spd 0 tl 2d, ffc0 -> ffc1, ack_complete, QR resp = 0005dd0b Dec 20 09:08:23 pc8 kernel: [ 2325.700630] firewire_ohci: AT spd 0 tl 1b, ffc0 -> ffc1, ack_pending , QR req, fffff0000410 Dec 20 09:08:23 pc8 kernel: [ 2325.700641] firewire_ohci: AT spd 0 tl 30, ffc1 -> ffc0, ack_pending , QR req, fffff0000418 Dec 20 09:08:23 pc8 kernel: [ 2325.700656] firewire_ohci: AR spd 0 tl 1b, ffc1 -> ffc0, ack_complete, QR resp = 00006351 Dec 20 09:08:23 pc8 kernel: [ 2325.700669] firewire_ohci: AR spd 0 tl 30, ffc0 -> ffc1, ack_complete, QR resp = 0c0083c0 Dec 20 09:08:23 pc8 kernel: [ 2325.700698] firewire_ohci: AT spd 0 tl 31, ffc1 -> ffc0, ack_pending , QR req, fffff000041c Dec 20 09:08:23 pc8 kernel: [ 2325.700713] firewire_ohci: AR spd 0 tl 1c, ffc1 -> ffc0, ack_complete, QR resp = 0005dd0b Dec 20 09:08:23 pc8 kernel: [ 2325.700722] firewire_ohci: AT spd 0 tl 1c, ffc0 -> ffc1, pending/cancelled, QR req, fffff0000414 Dec 20 09:08:23 pc8 kernel: [ 2325.700740] firewire_ohci: AR spd 0 tl 31, ffc0 -> ffc1, ack_complete, QR resp = 03d00d1e Dec 20 09:08:23 pc8 kernel: [ 2325.700784] firewire_ohci: AT spd 0 tl 32, ffc1 -> ffc0, ack_pending , QR req, fffff0000420 Dec 20 09:08:23 pc8 kernel: [ 2325.700795] firewire_ohci: AR spd 0 tl 1d, ffc1 -> ffc0, ack_complete, QR resp = 0c0083c0 Dec 20 09:08:23 pc8 kernel: [ 2325.700806] firewire_ohci: AT spd 0 tl 1d, ffc0 -> ffc1, pending/cancelled, QR req, fffff0000418 Dec 20 09:08:23 pc8 kernel: [ 2325.700823] firewire_ohci: AR spd 0 tl 32, ffc0 -> ffc1, ack_complete, QR resp = 81000003 Dec 20 09:08:23 pc8 kernel: [ 2325.700853] firewire_ohci: AT spd 0 tl 33, ffc1 -> ffc0, ack_pending , QR req, fffff0000424 Dec 20 09:08:23 pc8 kernel: [ 2325.700867] firewire_ohci: AR spd 0 tl 1e, ffc1 -> ffc0, ack_complete, QR resp = 03d00d1e Dec 20 09:08:23 pc8 kernel: [ 2325.700877] firewire_ohci: AT spd 0 tl 1e, ffc0 -> ffc1, pending/cancelled, QR req, fffff000041c Dec 20 09:08:23 pc8 kernel: [ 2325.700908] firewire_ohci: AR spd 0 tl 33, ffc0 -> ffc1, ack_complete, QR resp = 17000001 Dec 20 09:08:23 pc8 kernel: [ 2325.700940] firewire_ohci: AR spd 0 tl 1f, ffc1 -> ffc0, ack_complete, QR resp = 81000003 Dec 20 09:08:23 pc8 kernel: [ 2325.700950] firewire_ohci: AT spd 0 tl 1f, ffc0 -> ffc1, pending/cancelled, QR req, fffff0000420 Dec 20 09:08:23 pc8 kernel: [ 2325.700967] firewire_ohci: AT spd 0 tl 34, ffc1 -> ffc0, ack_pending , QR req, fffff0000428 Dec 20 09:08:23 pc8 kernel: [ 2325.700978] firewire_ohci: AR spd 0 tl 34, ffc0 -> ffc1, ack_complete, QR resp = 81000008 Dec 20 09:08:23 pc8 kernel: [ 2325.701027] firewire_ohci: AT spd 0 tl 35, ffc1 -> ffc0, ack_pending , QR req, fffff0000448 Dec 20 09:08:23 pc8 kernel: [ 2325.701041] firewire_ohci: AT spd 0 tl 20, ffc0 -> ffc1, ack_pending , QR req, fffff0000424 Dec 20 09:08:23 pc8 kernel: [ 2325.701055] firewire_ohci: AR spd 0 tl 20, ffc1 -> ffc0, ack_complete, QR resp = 17000001 Dec 20 09:08:23 pc8 kernel: [ 2325.701069] firewire_ohci: AR spd 0 tl 35, ffc0 -> ffc1, ack_complete, QR resp = 0003ff1c Dec 20 09:08:23 pc8 kernel: [ 2325.701108] firewire_ohci: AT spd 0 tl 21, ffc0 -> ffc1, ack_pending , QR req, fffff0000428 Dec 20 09:08:23 pc8 kernel: [ 2325.701120] firewire_ohci: AT spd 0 tl 36, ffc1 -> ffc0, ack_pending , QR req, fffff000044c Dec 20 09:08:23 pc8 kernel: [ 2325.701130] firewire_ohci: AR spd 0 tl 21, ffc1 -> ffc0, ack_complete, QR resp = 81000008 Dec 20 09:08:23 pc8 kernel: [ 2325.701150] firewire_ohci: AR spd 0 tl 36, ffc0 -> ffc1, ack_complete, QR resp = 00000000 Dec 20 09:08:23 pc8 kernel: [ 2325.701188] firewire_ohci: AT spd 0 tl 22, ffc0 -> ffc1, ack_pending , QR req, fffff0000448 Dec 20 09:08:23 pc8 kernel: [ 2325.701199] firewire_ohci: AT spd 0 tl 37, ffc1 -> ffc0, ack_pending , QR req, fffff0000450 Dec 20 09:08:23 pc8 kernel: [ 2325.701213] firewire_ohci: AR spd 0 tl 22, ffc1 -> ffc0, ack_complete, QR resp = 0003ff1c Dec 20 09:08:23 pc8 kernel: [ 2325.701226] firewire_ohci: AR spd 0 tl 37, ffc0 -> ffc1, ack_complete, QR resp = 00000000 Dec 20 09:08:23 pc8 kernel: [ 2325.701258] firewire_ohci: AT spd 0 tl 38, ffc1 -> ffc0, ack_pending , QR req, fffff0000454 Dec 20 09:08:23 pc8 kernel: [ 2325.701276] firewire_ohci: AR spd 0 tl 23, ffc1 -> ffc0, ack_complete, QR resp = 00000000 Dec 20 09:08:23 pc8 kernel: [ 2325.701286] firewire_ohci: AT spd 0 tl 23, ffc0 -> ffc1, pending/cancelled, QR req, fffff000044c Dec 20 09:08:23 pc8 kernel: [ 2325.701305] firewire_ohci: AR spd 0 tl 38, ffc0 -> ffc1, ack_complete, QR resp = 4a756a75 Dec 20 09:08:23 pc8 kernel: [ 2325.701340] firewire_ohci: AT spd 0 tl 39, ffc1 -> ffc0, ack_pending , QR req, fffff000042c Dec 20 09:08:23 pc8 kernel: [ 2325.701353] firewire_ohci: AT spd 0 tl 24, ffc0 -> ffc1, ack_pending , QR req, fffff0000450 Dec 20 09:08:23 pc8 kernel: [ 2325.701368] firewire_ohci: AR spd 0 tl 24, ffc1 -> ffc0, ack_complete, QR resp = 00000000 Dec 20 09:08:23 pc8 kernel: [ 2325.701381] firewire_ohci: AR spd 0 tl 39, ffc0 -> ffc1, ack_complete, QR resp = 00064cb7 Dec 20 09:08:23 pc8 kernel: [ 2325.701416] firewire_ohci: AT spd 0 tl 3a, ffc1 -> ffc0, ack_pending , QR req, fffff0000430 Dec 20 09:08:23 pc8 kernel: [ 2325.701429] firewire_ohci: AT spd 0 tl 25, ffc0 -> ffc1, ack_pending , QR req, fffff0000454 Dec 20 09:08:23 pc8 kernel: [ 2325.701444] firewire_ohci: AR spd 0 tl 25, ffc1 -> ffc0, ack_complete, QR resp = 4a756a75 Dec 20 09:08:23 pc8 kernel: [ 2325.701457] firewire_ohci: AR spd 0 tl 3a, ffc0 -> ffc1, ack_complete, QR resp = 00000000 Dec 20 09:08:23 pc8 kernel: [ 2325.701492] firewire_ohci: AT spd 0 tl 3b, ffc1 -> ffc0, ack_pending , QR req, fffff0000434 Dec 20 09:08:23 pc8 kernel: [ 2325.701506] firewire_ohci: AT spd 0 tl 26, ffc0 -> ffc1, ack_pending , QR req, fffff000042c Dec 20 09:08:23 pc8 kernel: [ 2325.701517] firewire_ohci: AR spd 0 tl 26, ffc1 -> ffc0, ack_complete, QR resp = 00064cb7 Dec 20 09:08:23 pc8 kernel: [ 2325.701533] firewire_ohci: AR spd 0 tl 3b, ffc0 -> ffc1, ack_complete, QR resp = 00000000 Dec 20 09:08:23 pc8 kernel: [ 2325.701569] firewire_ohci: AT spd 0 tl 3c, ffc1 -> ffc0, ack_pending , QR req, fffff0000438 Dec 20 09:08:23 pc8 kernel: [ 2325.701582] firewire_ohci: AT spd 0 tl 27, ffc0 -> ffc1, ack_pending , QR req, fffff0000430 Dec 20 09:08:23 pc8 kernel: [ 2325.701593] firewire_ohci: AR spd 0 tl 27, ffc1 -> ffc0, ack_complete, QR resp = 00000000 Dec 20 09:08:23 pc8 kernel: [ 2325.701610] firewire_ohci: AR spd 0 tl 3c, ffc0 -> ffc1, ack_complete, QR resp = 4c696e75 Dec 20 09:08:23 pc8 kernel: [ 2325.701652] firewire_ohci: AT spd 0 tl 3d, ffc1 -> ffc0, ack_pending , QR req, fffff000043c Dec 20 09:08:23 pc8 kernel: [ 2325.701666] firewire_ohci: AT spd 0 tl 28, ffc0 -> ffc1, ack_pending , QR req, fffff0000434 Dec 20 09:08:23 pc8 kernel: [ 2325.701676] firewire_ohci: AR spd 0 tl 28, ffc1 -> ffc0, ack_complete, QR resp = 00000000 Dec 20 09:08:23 pc8 kernel: [ 2325.701693] firewire_ohci: AR spd 0 tl 3d, ffc0 -> ffc1, ack_complete, QR resp = 78204669 Dec 20 09:08:23 pc8 kernel: [ 2325.701728] firewire_ohci: AT spd 0 tl 3e, ffc1 -> ffc0, ack_pending , QR req, fffff0000440 Dec 20 09:08:23 pc8 kernel: [ 2325.701742] firewire_ohci: AT spd 0 tl 29, ffc0 -> ffc1, ack_pending , QR req, fffff0000438 Dec 20 09:08:23 pc8 kernel: [ 2325.701752] firewire_ohci: AR spd 0 tl 29, ffc1 -> ffc0, ack_complete, QR resp = 4c696e75 Dec 20 09:08:23 pc8 kernel: [ 2325.701776] firewire_ohci: AR spd 0 tl 3e, ffc0 -> ffc1, ack_complete, QR resp = 72657769 Dec 20 09:08:23 pc8 kernel: [ 2325.701813] firewire_ohci: AT spd 0 tl 2a, ffc0 -> ffc1, ack_pending , QR req, fffff000043c Dec 20 09:08:23 pc8 kernel: [ 2325.701823] firewire_ohci: AT spd 0 tl 3f, ffc1 -> ffc0, ack_pending , QR req, fffff0000444 Dec 20 09:08:23 pc8 kernel: [ 2325.701837] firewire_ohci: AR spd 0 tl 2a, ffc1 -> ffc0, ack_complete, QR resp = 78204669 Dec 20 09:08:23 pc8 kernel: [ 2325.701851] firewire_ohci: AR spd 0 tl 3f, ffc0 -> ffc1, ack_complete, QR resp = 72650000 Dec 20 09:08:23 pc8 kernel: [ 2325.701952] firewire_ohci: AR spd 0 tl 2b, ffc1 -> ffc0, ack_complete, QR resp = 72657769 Dec 20 09:08:23 pc8 kernel: [ 2325.701963] firewire_ohci: AT spd 0 tl 2b, ffc0 -> ffc1, pending/cancelled, QR req, fffff0000440 Dec 20 09:08:23 pc8 kernel: [ 2325.702003] firewire_ohci: AT spd 0 tl 2c, ffc0 -> ffc1, ack_pending , QR req, fffff0000444 Dec 20 09:08:23 pc8 kernel: [ 2325.702014] firewire_ohci: AR spd 0 tl 2c, ffc1 -> ffc0, ack_complete, QR resp = 72650000 Dec 20 09:08:23 pc8 kernel: [ 2325.702184] firewire_core: created device fw2: GUID 00241b00964cac00, S100 Dec 20 09:08:23 pc8 kernel: [ 2325.702213] firewire_ohci: AT spd 0 tl 00, ffc1 -> ffc0, ack_pending , QR req, fffff0000234 Dec 20 09:08:23 pc8 kernel: [ 2325.702225] firewire_ohci: AR spd 0 tl 00, ffc1 -> ffc0, ack_pending , QR req, fffff0000234 Dec 20 09:08:23 pc8 kernel: [ 2325.702255] firewire_ohci: AR spd 0 tl 00, ffc0 -> ffc1, ack_complete, QR resp = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.702269] firewire_ohci: AT spd 0 tl 00, ffc0 -> ffc1, ack_complete, QR resp = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.702331] firewire_core: created device fw3: GUID 0108000000006351, S100 Dec 20 09:08:23 pc8 kernel: [ 2325.702365] firewire_ohci: AR spd 0 tl 2d, ffc0 -> ffc1, ack_pending , QR req, fffff0000234 Dec 20 09:08:23 pc8 kernel: [ 2325.702379] firewire_ohci: AT spd 0 tl 01, ffc1 -> ffc0, ack_pending , QW req, fffff0000234 = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.702394] firewire_ohci: AR spd 0 tl 01, ffc1 -> ffc0, ack_pending , QW req, fffff0000234 = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.702412] firewire_ohci: AT spd 0 tl 2d, ffc0 -> ffc1, ack_pending , QR req, fffff0000234 Dec 20 09:08:23 pc8 kernel: [ 2325.702423] firewire_ohci: AT spd 0 tl 2d, ffc1 -> ffc0, ack_complete, QR resp = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.702440] firewire_ohci: AR spd 0 tl 2d, ffc1 -> ffc0, ack_complete, QR resp = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.702453] firewire_ohci: AR spd 0 tl 01, ffc0 -> ffc1, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.702465] firewire_ohci: AT spd 0 tl 01, ffc0 -> ffc1, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.702502] firewire_ohci: AR spd 0 tl 2e, ffc0 -> ffc1, ack_pending , QW req, fffff0000234 = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.702515] firewire_ohci: AT spd 0 tl 2e, ffc0 -> ffc1, ack_pending , QW req, fffff0000234 = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.702540] firewire_ohci: AT spd 0 tl 2e, ffc1 -> ffc0, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.702549] firewire_ohci: AR spd 0 tl 2e, ffc1 -> ffc0, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.702567] firewire_core: phy config: card 0, new root=ffc1, gap_count=5 Dec 20 09:08:23 pc8 kernel: [ 2325.702593] firewire_ohci: AT ack_complete, PHY 01c50000 fe3affff Dec 20 09:08:23 pc8 kernel: [ 2325.702603] firewire_ohci: AR ack_complete, PHY 01c50000 fe3affff Dec 20 09:08:23 pc8 kernel: [ 2325.702646] firewire_ohci: AR evt_bus_reset, generation 30 Dec 20 09:08:23 pc8 kernel: [ 2325.702653] firewire_ohci: AR evt_bus_reset, generation 8 Dec 20 09:08:23 pc8 kernel: [ 2325.704727] firewire_ohci: 2 selfIDs, generation 30, local node ID ffc1 Dec 20 09:08:23 pc8 kernel: [ 2325.704744] firewire_ohci: selfID 0: 80458882, phy 0 [p..] S400 gc=5 +0W Lci Dec 20 09:08:23 pc8 kernel: [ 2325.704758] firewire_ohci: selfID 0: 8145ccd4, phy 1 [c--] beta gc=5 -3W Lc Dec 20 09:08:23 pc8 kernel: [ 2325.704797] firewire_ohci: 2 selfIDs, generation 8, local node ID ffc0 Dec 20 09:08:23 pc8 kernel: [ 2325.704811] firewire_ohci: selfID 0: 80458882, phy 0 [p..] S400 gc=5 +0W Lci Dec 20 09:08:23 pc8 kernel: [ 2325.704824] firewire_ohci: selfID 0: 8145ccd4, phy 1 [c--] beta gc=5 -3W Lc Dec 20 09:08:23 pc8 kernel: [ 2325.704881] firewire_ohci: AR spd 0 tl 2f, ffc1 -> ffc0, ack_complete, Lk resp 4,2 Dec 20 09:08:23 pc8 kernel: [ 2325.704894] firewire_ohci: AT spd 0 tl 2f, ffc0 -> ffc1, pending/cancelled, Lk req, fffff000021c 8,2 Dec 20 09:08:23 pc8 kernel: [ 2325.704941] firewire_ohci: AR spd 0 tl 30, ffc0 -> ffc1, ack_pending , QW req, fffff0000004 = 00000100 Dec 20 09:08:23 pc8 kernel: [ 2325.704961] firewire_ohci: AT spd 0 tl 30, ffc0 -> ffc1, ack_pending , QW req, fffff0000004 = 00000100 Dec 20 09:08:23 pc8 kernel: [ 2325.704979] firewire_ohci: AT spd 0 tl 30, ffc1 -> ffc0, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.704988] firewire_ohci: AR spd 0 tl 30, ffc1 -> ffc0, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.960111] firewire_ohci: AT spd 0 tl 04, ffc1 -> ffc0, ack_pending , QW req, fffff0000234 = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.960132] firewire_ohci: AR spd 0 tl 04, ffc1 -> ffc0, ack_pending , QW req, fffff0000234 = c000001f Dec 20 09:08:23 pc8 kernel: [ 2325.960167] firewire_ohci: AR spd 0 tl 04, ffc0 -> ffc1, ack_complete, W resp Dec 20 09:08:23 pc8 kernel: [ 2325.960179] firewire_ohci: AT spd 0 tl 04, ffc0 -> ffc1, ack_complete, W resp Dec 20 09:08:24 pc8 kernel: [ 2326.204100] firewire_ohci: AR spd 0 tl 05, ffc0 -> ffc1, ack_complete, QR resp = 0404e334 Dec 20 09:08:24 pc8 kernel: [ 2326.204116] firewire_ohci: AT spd 0 tl 05, ffc1 -> ffc0, pending/cancelled, QR req, fffff0000400 Dec 20 09:08:24 pc8 kernel: [ 2326.204158] firewire_ohci: AR spd 0 tl 06, ffc0 -> ffc1, ack_complete, QR resp = 31333934 Dec 20 09:08:24 pc8 kernel: [ 2326.204168] firewire_ohci: AT spd 0 tl 06, ffc1 -> ffc0, pending/cancelled, QR req, fffff0000404 Dec 20 09:08:24 pc8 kernel: [ 2326.204201] firewire_ohci: AR spd 0 tl 07, ffc0 -> ffc1, ack_complete, QR resp = f000a222 Dec 20 09:08:24 pc8 kernel: [ 2326.204211] firewire_ohci: AT spd 0 tl 07, ffc1 -> ffc0, pending/cancelled, QR req, fffff0000408 Dec 20 09:08:24 pc8 kernel: [ 2326.204242] firewire_ohci: AR spd 0 tl 08, ffc0 -> ffc1, ack_complete, QR resp = 00241b00 Dec 20 09:08:24 pc8 kernel: [ 2326.204252] firewire_ohci: AT spd 0 tl 08, ffc1 -> ffc0, pending/cancelled, QR req, fffff000040c Dec 20 09:08:24 pc8 kernel: [ 2326.204284] firewire_ohci: AR spd 0 tl 09, ffc0 -> ffc1, ack_complete, QR resp = 964cac00 Dec 20 09:08:24 pc8 kernel: [ 2326.204294] firewire_ohci: AT spd 0 tl 09, ffc1 -> ffc0, pending/cancelled, QR req, fffff0000410 Dec 20 09:08:24 pc8 kernel: [ 2326.204327] firewire_ohci: AT spd 0 tl 0a, ffc1 -> ffc0, ack_pending , QR req, fffff0000414 Dec 20 09:08:24 pc8 kernel: [ 2326.204345] firewire_ohci: AR spd 0 tl 0a, ffc0 -> ffc1, ack_complete, QR resp = 0005dd0b Dec 20 09:08:32 pc8 kernel: [ 2334.764102] firewire_ohci: AR evt_bus_reset, generation 9 Dec 20 09:08:32 pc8 kernel: [ 2334.764270] firewire_ohci: 1 selfIDs, generation 9, local node ID ffc0 Dec 20 09:08:32 pc8 kernel: [ 2334.764283] firewire_ohci: selfID 0: 807f8842, phy 0 [-..] S400 gc=63 +0W Lci Dec 20 09:08:32 pc8 kernel: [ 2334.764416] firewire_ohci: AT spd 0 tl 00, ffc0 -> ffc1, evt_missing_ack, QW req, fffff0000234 = c000001f Dec 20 09:17:01 pc8 CRON[3329]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) -- Carl K |
From: Stefan R. <st...@s5...> - 2011-12-20 20:18:47
|
On Dec 20 Carl Karsten wrote: > un/re-plugging the cable. waiting a few seconds between each (but not > watching syslog, so maybe didn't wait long enough...) - 3 or 4 worked > fine, fw2,3 created/destroyed as expected, then I unplug, and fw2 > doesn't destroy: > > juser@pc8:~$ ls /dev/fw? > /dev/fw0 /dev/fw1 /dev/fw2 > > and syslog has a ton of > Dec 20 09:08:22 pc8 kernel: [ 2325.059013] firewire_ohci: AR > evt_bus_reset, generation 0 > .... 0-255 > loop that 25 times. This can happen if a process keeps /dev/fw2 open, but in this case I think the problem is that your bus reset series was never finished by a proper self-ID-complete event. Only when the controller received selfIDs after bus reset, firewire-core gets to work and check which nodes are there and which are gone. [...] > Dec 20 09:08:23 pc8 kernel: [ 2325.704727] firewire_ohci: 2 selfIDs, generation 30, local node ID ffc1 > Dec 20 09:08:23 pc8 kernel: [ 2325.704744] firewire_ohci: selfID 0: 80458882, phy 0 [p..] S400 gc=5 +0W Lci > Dec 20 09:08:23 pc8 kernel: [ 2325.704758] firewire_ohci: selfID 0: 8145ccd4, phy 1 [c--] beta gc=5 -3W Lc > Dec 20 09:08:23 pc8 kernel: [ 2325.704797] firewire_ohci: 2 selfIDs, generation 8, local node ID ffc0 > Dec 20 09:08:23 pc8 kernel: [ 2325.704811] firewire_ohci: selfID 0: 80458882, phy 0 [p..] S400 gc=5 +0W Lci > Dec 20 09:08:23 pc8 kernel: [ 2325.704824] firewire_ohci: selfID 0: 8145ccd4, phy 1 [c--] beta gc=5 -3W Lc Here, both controllers got a self-ID-complete event with 2 nodes present in each event. [...] > Dec 20 09:08:32 pc8 kernel: [ 2334.764102] firewire_ohci: AR evt_bus_reset, generation 9 > Dec 20 09:08:32 pc8 kernel: [ 2334.764270] firewire_ohci: 1 selfIDs, generation 9, local node ID ffc0 > Dec 20 09:08:32 pc8 kernel: [ 2334.764283] firewire_ohci: selfID 0: 807f8842, phy 0 [-..] S400 gc=63 +0W Lci Here, only one controller reports a self-ID-complete event, now with the local node being the only one on the bus. But the other controller is notably silent. > Dec 20 09:08:32 pc8 kernel: [ 2334.764416] firewire_ohci: AT spd 0 tl 00, ffc0 -> ffc1, evt_missing_ack, QW req, fffff0000234 = c000001f Alas firewire-ohci does not log the PCI device name of the card... Anyhow. As a rule of thumb, anything is possible with faulty hardware. Anything bad, that is. -- Stefan Richter -=====-==-== ==-- =-=-- http://arcgraph.de/sr/ |