just realized I'd been sending my replies to alan rather than the list. apologies to everyone, here's my latest. thanks,
matt On Wed, 2006-22-11 at 14:06 -0500, Alan Stern wrote: > On Wed, 22 Nov 2006, Matt Price wrote: > Turn on CONFIG_USB_DEBUG and see what shows up in the kernel log. > so I've done that and theaoutput is very verbose as you doubtless expected. much of it is uninterpretable to me I'm afraid. This is what I think is the relevant part, covering a period in the boot process roughly similar to what I described in my last post: [ 5.788000] Suspend2 2.2.8: Resuming enabled. [ 5.788000] Failed to launch userspace program '/usr/bin/suspend2ui_text': Error -1 [ 5.788000] Launch userspace program failed. [ 5.808000] hub 1-0:1.0: debounce: port 3: total 100ms stable 100ms status 0x501 [ 5.824000] Reading kernel & process data... [ 5.864000] ehci_hcd 0000:00:1d.7: port 3 full speed --> companion [ 5.864000] ehci_hcd 0000:00:1d.7: GetStatus port 3 status 003801 POWER OWNER sig=j CONNECT [ 5.864000] hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0004 [ 5.864000] uhci_hcd 0000:00:1d.0: port 2 portsc 0082,00 [ 5.864000] hub 2-0:1.0: port 2, status 0100, change 0001, 12 Mb/s [ 5.868000] ehci_hcd 0000:00:1d.7: fatal command 010011 (park)=0 ithresh=1 Periodic period=1024 RUN [ 5.868000] ehci_hcd 0000:00:1d.7: fatal status 6000 Periodic Recl [ 5.992000] hub 2-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100 [ 5.992000] hub 3-0:1.0: state 7 ports 2 chg 0000 evt 0002 [ 5.992000] uhci_hcd 0000:00:1d.1: port 1 portsc 009b,00 [ 5.992000] hub 3-0:1.0: port 1, status 0101, change 0003, 12 Mb/s [ 6.040000] 20%...<7>hub 3-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x101 [ 6.180000] 40%...<6>usb 3-1: new full speed USB device using uhci_hcd and address 2 [ 6.308000] 60%...<7>usb 3-1: ep0 maxpacket = 8 [ 6.384000] usb 3-1: new device strings: Mfr=0, Product=0, SerialNumber=0 [ 6.384000] usb 3-1: uevent [ 6.384000] usb 3-1: configuration #1 chosen from 1 choice [ 6.388000] usb 3-1: adding 3-1:1.0 (config #1, interface 0) [ 6.388000] usb 3-1:1.0: uevent [ 6.388000] hub 3-1:1.0: usb_probe_interface [ 6.388000] hub 3-1:1.0: usb_probe_interface - got id [ 6.388000] hub 3-1:1.0: USB hub found [ 6.388000] hub 3-1:1.0: 3 ports detected [ 6.388000] hub 3-1:1.0: compound device; port removable status: RFR [ 6.388000] hub 3-1:1.0: individual port power switching [ 6.388000] hub 3-1:1.0: individual port over-current protection [ 6.388000] hub 3-1:1.0: power on to power good time: 100ms [ 6.392000] hub 3-1:1.0: hub controller current requirement: 100mA [ 6.392000] hub 3-1:1.0: 100mA bus power budget for each child [ 6.392000] hub 3-1:1.0: enabling power on all ports [ 6.420000] 80%...<7>drivers/usb/core/inode.c: creating file '002' [ 6.500000] hub 1-0:1.0: state 7 ports 8 chg 0000 evt 0000 [ 6.500000] hub 4-0:1.0: state 7 ports 2 chg 0000 evt 0000 [ 6.500000] hub 5-0:1.0: state 7 ports 2 chg 0000 evt 0000 [ 6.500000] hub 1-2:1.0: state 7 ports 4 chg 0000 evt 0008 [ 6.500000] hub 1-2:1.0: port 3, status 0101, change 0001, 12 Mb/s [ 6.520000] usb usb4: suspend_rh (auto-stop) [ 6.568000] 100%...done. [ 6.624000] usb usb5: suspend_rh (auto-stop) [ 6.628000] hub 1-2:1.0: debounce: port 3: total 100ms stable 100ms status 0x101 [ 6.700000] usb 1-2.3: new full speed USB device using ehci_hcd and address 4 [ 6.720000] hub 1-2:1.0: port 3 not reset yet, waiting 10ms [ 6.844000] usb 1-2.3: skipped 1 descriptor after interface [ 6.844000] usb 1-2.3: new device strings: Mfr=0, Product=0, SerialNumber=0 [ 6.844000] usb 1-2.3: uevent [ 6.844000] usb 1-2.3: configuration #1 chosen from 1 choice [ 6.844000] usb 1-2.3: adding 1-2.3:1.0 (config #1, interface 0) [ 6.844000] usb 1-2.3:1.0: uevent [ 6.844000] usb 1-2.3: adding 1-2.3:1.1 (config #1, interface 1) [ 6.844000] usb 1-2.3:1.1: uevent [ 6.844000] usb 1-2.3: adding 1-2.3:1.2 (config #1, interface 2) [ 6.844000] usb 1-2.3:1.2: uevent [ 6.844000] drivers/usb/core/inode.c: creating file '004' [ 6.844000] hub 3-1:1.0: state 7 ports 3 chg 0000 evt 0004 [ 6.848000] hub 3-1:1.0: port 2, status 0101, change 0001, 12 Mb/s [ 6.888000] Atomic restore preparation [ 6.892000] Freezing cpus ... [ 6.952000] ieee1394: Host added: ID:BUS[0-00:1023] GUID[374fc00018700941] [ 6.980000] hub 3-1:1.0: debounce: port 2: total 100ms stable 100ms status 0x101 [ 7.008000] CPU 1 is now offline [ 7.008000] SMP alternatives: switching to UP code [ 7.020000] CPU1 is down [ 7.020000] usbdev1.4_ep83: PM: suspend 0->1, parent 1-2.3:1.1 already 1 [ 7.020000] usbdev1.4_ep03: PM: suspend 0->1, parent 1-2.3:1.1 already 1 [ 7.020000] usbdev1.4_ep82: PM: suspend 0->1, parent 1-2.3:1.0 already 1 [ 7.020000] usbdev1.4_ep02: PM: suspend 0->1, parent 1-2.3:1.0 already 1 [ 7.020000] usbdev1.4_ep81: PM: suspend 0->1, parent 1-2.3:1.0 already 1 [ 7.024000] usb 1-2.3: usb suspend [ 7.052000] usb 3-1.2: new full speed USB device using uhci_hcd and address 3 [ 7.068000] usb usb2: suspend_rh (auto-stop) [ 7.160000] usb 3-1.2: skipped 1 descriptor after endpoint [ 7.164000] usb 3-1.2: default language 0x0409 [ 7.172000] usb 3-1.2: new device strings: Mfr=1, Product=2, SerialNumber=0 [ 7.172000] usb 3-1.2: Product: O2Micro CCID SC Reader [ 7.172000] usb 3-1.2: Manufacturer: O2 [ 7.172000] usb 3-1.2: uevent [ 7.172000] usb 3-1.2: configuration #1 chosen from 1 choice [ 7.176000] usb 3-1.2: adding 3-1.2:1.0 (config #1, interface 0) [ 7.176000] usb 3-1.2:1.0: uevent [ 7.176000] drivers/usb/core/inode.c: creating file '003' [ 7.176000] hub 3-1:1.0: 400mA power budget left [ 7.176000] hub 3-1:1.0: state 7 ports 3 chg 0000 evt 0004 [ 7.176000] suspend_device(): usb_generic_suspend+0x0/0x120 [usbcore]() returns -16 [ 7.176000] Could not suspend device 3-1: error -16 [ 7.176000] hub 3-1:1.0: state 7 ports 3 chg 0000 evt 0004 [ 7.192000] usb 3-1.2: finish resume [ 7.192000] usb 1-2.3: RESUME [ 7.240000] usb 1-2.3: finish resume [ 7.240000] usbdev1.4_ep81: PM: resume from 0, parent 1-2.3:1.0 still 1 [ 7.240000] usbdev1.4_ep02: PM: resume from 0, parent 1-2.3:1.0 still 1 [ 7.240000] usbdev1.4_ep82: PM: resume from 0, parent 1-2.3:1.0 still 1 [ 7.240000] usbdev1.4_ep03: PM: resume from 0, parent 1-2.3:1.1 still 1 [ 7.240000] usbdev1.4_ep83: PM: resume from 0, parent 1-2.3:1.1 still 1 [ 7.240000] Some devices failed to suspend [ 7.240000] ------------[ cut here ]------------ [ 7.240000] kernel BUG at kernel/power/atomic_copy.c:496! [ 7.240000] invalid opcode: 0000 [#1] [ 7.240000] SMP [ 7.240000] Modules linked in: ohci1394 ieee1394 uhci_hcd ehci_hcd usbcore ide_generic sd_mod generic ata_piix libata scsi_mod thermal processor fan [ 7.240000] CPU: 0 [ 7.240000] EIP: 0060:[<c0149052>] Not tainted VLI [ 7.240000] EFLAGS: 00010246 (2.6.18debiannousb #1) [ 7.240000] EIP is at suspend_atomic_restore+0x1c2/0x200 [ 7.240000] eax: 00004010 ebx: 00000000 ecx: 00000046 edx: 00000000 [ 7.240000] esi: dfc689d4 edi: dfc689c0 ebp: dfc42000 esp: dfcbdf10 [ 7.240000] ds: 007b es: 007b ss: 0068 [ 7.240000] Process exe (pid: 2035, ti=dfcbc000 task=dfc29550 task.ti=dfcbc000) [ 7.240000] Stack: c03062c0 c0303557 dfc689d4 dfc689c0 c0146332 dfc689c0 c0146a30 c03831b0 [ 7.240000] c01463c7 c0148375 00000000 00000000 00000001 fffffff2 c03831ec dfc689d4 [ 7.240000] dfc689c0 00000001 c01ba514 00000001 c03831ec b7feb000 dff37c00 dff37c00 [ 7.240000] Call Trace: [ 7.240000] [<c0146332>] suspend_resume+0x82/0x100 [ 7.240000] [<c0146a30>] suspend_start_anything+0x50/0xc0 [ 7.240000] [<c01463c7>] __suspend_try_resume+0x17/0x30 [ 7.240000] [<c0148375>] suspend2_attr_store+0x65/0x240 [ 7.240000] [<c01ba514>] sysfs_write_file+0x94/0xf0 [ 7.240000] [<c0179e0e>] vfs_write+0xbe/0x190 [ 7.240000] [<c01ba480>] sysfs_write_file+0x0/0xf0 [ 7.240000] [<c017a591>] sys_write+0x41/0x70 [ 7.240000] [<c01031a7>] syscall_call+0x7/0xb [ 7.240000] Code: 12 00 0f 0b 13 02 2d 35 30 c0 83 c4 08 b8 01 00 00 00 5e 5f c3 c7 04 24 c0 62 30 c0 e8 d8 98 fd ff a1 80 2b 38 c0 f6 c4 10 75 17 <0f> 0b f0 01 2d 35 30 c0 e9 82 fe ff ff e8 6c 8b ff ff e 9 61 fe [ 7.240000] EIP: [<c0149052>] suspend_atomic_restore+0x1c2/0x200 SS:ESP 0068:dfcbdf10 [ 7.240000] <6>EXT3-fs: INFO: recovery required on readonly filesystem. [ 7.284000] EXT3-fs: write access will be enabled during recovery. [ 7.288000] kjournald starting. Commit interval 5 seconds [ 7.288000] EXT3-fs: sda3: orphan cleanup on readonly fs [ 7.320000] ext3_orphan_cleanup: deleting unreferenced inode 6675563 [ 7.320000] ext3_orphan_cleanup: deleting unreferenced inode 6675552 [ 7.320000] ext3_orphan_cleanup: deleting unreferenced inode 6675532 [ 7.320000] ext3_orphan_cleanup: deleting unreferenced inode 6675525 [ 7.320000] ext3_orphan_cleanup: deleting unreferenced inode 6675514 [ 7.320000] EXT3-fs: sda3: 5 orphan inodes deleted [ 7.320000] EXT3-fs: recovery complete. [ 7.332000] EXT3-fs: mounted filesystem with ordered data mode. ----------------------------------- some of the stuff at the end is from suspend2 I guess. I see a bunch of references to usb 3-1 and 3-1.2 -- are these the culprits? If so, 3-1.2 seems to be just the smartcard reader which I don't use at all. can I somehow blacklist the device? and if this is the culprit is there any way to figure out WHY it would suddenly and unpredictably get all screwed up when the device has never been used at all? thanks, matt -- > Alan Stern > -- Matt Price History Dept University of Toronto [EMAIL PROTECTED]
signature.asc
Description: This is a digitally signed message part
------------------------------------------------------------------------- Take Surveys. Earn Cash. Influence the Future of IT Join SourceForge.net's Techsay panel and you'll get the chance to share your opinions on IT & business topics through brief surveys - and earn cash http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
_______________________________________________ Linux-usb-users@lists.sourceforge.net To unsubscribe, use the last form field at: https://lists.sourceforge.net/lists/listinfo/linux-usb-users
