More info on request.
Kind regards
R.
[29429.039959] ------------[ cut here ]------------
[29429.039959] WARNING: at drivers/base/power/main.c:82
device_pm_add+0x4d/0xb1()
[29429.039959] Modules linked in: tun radeon drm rfcomm l2cap usblp ipv6
cpufreq_ondemand cpufreq_stats cpufreq_userspace cpufreq_powersave
ipt_recent xt_tcpudp nf_conntrack_ipv4 xt_state nf_conntrack
iptable_filter ip_tables x_tables vfat fat fuse acpi_cpufreq freq_table
usbserial microcode snd_intel8x0m pcmcia snd_intel8x0 snd_ac97_codec
ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi
snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd
soundcore snd_page_alloc yenta_socket rsrc_nonstatic ipw2200 pcmcia_core
hci_usb i2c_i801 bluetooth video output battery ac button evdev usbhid
ohci1394 8139cp uhci_hcd ehci_hcd ieee1394 usbcore sg sr_mod cdrom
thermal processor fan
[29429.039959] Pid: 10544, comm: s2ram Tainted: G W
2.6.26-00000-g8be1a6d #1
[29429.039959] [<c011b733>] warn_on_slowpath+0x40/0x63
[29429.039959] [<c01e93b7>] sprintf+0x1d/0x20
[29429.039959] [<c011bfc9>] vprintk+0x1cd/0x274
[29429.039959] [<c011ba65>] try_acquire_console_sem+0xa/0x2a
[29429.039959] [<c019e631>] sysfs_find_dirent+0x13/0x23
[29429.039959] [<c019e107>] sysfs_add_file_mode+0x4c/0x6a
[29429.039959] [<c019f8cd>] internal_create_group+0xda/0x139
[29429.039959] [<c011c084>] printk+0x14/0x18
[29429.039959] [<c023ee00>] device_pm_add+0x4d/0xb1
[29429.039959] [<c023aacb>] device_add+0x340/0x4ad
[29429.039959] [<f887599e>] usb_create_ep_files+0x1ed/0x26b [usbcore]
[29429.039959] [<c019d850>] sysfs_hash_and_remove+0x44/0x50
[29429.039959] [<f887533f>] usb_create_sysfs_intf_files+0x5a/0x72
[usbcore]
[29429.039959] [<f887272c>] usb_set_interface+0x137/0x140 [usbcore]
[29429.039959] [<f8871923>] usb_disable_interface+0x1e/0x2f [usbcore]
[29429.039959] [<f88737e6>] usb_unbind_interface+0x46/0x62 [usbcore]
[29429.039959] [<c023bf5d>] __device_release_driver+0x58/0x76
[29429.039959] [<c023bf93>] device_release_driver+0x18/0x21
[29429.039959] [<f887323f>] usb_driver_release_interface+0x3c/0x76
[usbcore]
[29429.039959] [<f88b37b6>] hci_usb_disconnect+0x35/0x62 [hci_usb]
[29429.039959] [<f88737d6>] usb_unbind_interface+0x36/0x62 [usbcore]
[29429.039959] [<c023bf5d>] __device_release_driver+0x58/0x76
[29429.039959] [<c023bf93>] device_release_driver+0x18/0x21
[29429.039959] [<f887323f>] usb_driver_release_interface+0x3c/0x76
[usbcore]
[29429.039959] [<f8873292>] usb_rebind_intf+0x19/0x4e [usbcore]
[29429.039959] [<f887332d>] do_unbind_rebind+0x4d/0x5a [usbcore]
[29429.039959] [<f887342a>] usb_external_resume_device+0xf0/0xf6
[usbcore]
[29429.039959] [<c023ea2c>] device_resume+0x85/0x2f4
[29429.039959] [<c01391c9>] suspend_devices_and_enter+0xd5/0x10d
[29429.039959] [<c0139312>] enter_state+0xcf/0x11c
[29429.039959] [<c01393ec>] state_store+0x8d/0xa1
[29429.039959] [<c013935f>] state_store+0x0/0xa1
[29429.039959] [<c01e58af>] kobj_attr_store+0x18/0x1c
[29429.039959] [<c019de6f>] sysfs_write_file+0xa4/0xd8
[29429.039959] [<c019ddcb>] sysfs_write_file+0x0/0xd8
[29429.039959] [<c016816a>] vfs_write+0x83/0xf6
[29429.039959] [<c0168597>] sys_write+0x3c/0x63
[29429.039959] [<c01038da>] syscall_call+0x7/0xb
[29429.039959] =======================
[29429.039959] ---[ end trace fa351609edb352dc ]---
On Mon, 28 Jul 2008 13:33:21 +0200 Roberto Oppedisano <[email protected]> wrote:
> More info on request.
>
Let's cc linux-usb, as that appears to be the subsystem which triggered
this:
int device_pm_add(struct device *dev)
{
int error;
pr_debug("PM: Adding info for %s:%s\n",
dev->bus ? dev->bus->name : "No Bus",
kobject_name(&dev->kobj));
mutex_lock(&dpm_list_mtx);
if (dev->parent) {
if (dev->parent->power.status >= DPM_SUSPENDING) {
dev_warn(dev, "parent %s is sleeping, will not add\n",
dev->parent->bus_id);
WARN_ON(true);
}
WARN_ON.
Also, I assume that your kernel did print that "parent %s is sleeping"
message, but you trimmed it off. That would probably be useful
information.
Thanks.
>
> [29429.039959] ------------[ cut here ]------------
> [29429.039959] WARNING: at drivers/base/power/main.c:82
> device_pm_add+0x4d/0xb1()
> [29429.039959] Modules linked in: tun radeon drm rfcomm l2cap usblp ipv6
> cpufreq_ondemand cpufreq_stats cpufreq_userspace cpufreq_powersave
> ipt_recent xt_tcpudp nf_conntrack_ipv4 xt_state nf_conntrack
> iptable_filter ip_tables x_tables vfat fat fuse acpi_cpufreq freq_table
> usbserial microcode snd_intel8x0m pcmcia snd_intel8x0 snd_ac97_codec
> ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi
> snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd
> soundcore snd_page_alloc yenta_socket rsrc_nonstatic ipw2200 pcmcia_core
> hci_usb i2c_i801 bluetooth video output battery ac button evdev usbhid
> ohci1394 8139cp uhci_hcd ehci_hcd ieee1394 usbcore sg sr_mod cdrom
> thermal processor fan
> [29429.039959] Pid: 10544, comm: s2ram Tainted: G W
> 2.6.26-00000-g8be1a6d #1
> [29429.039959] [<c011b733>] warn_on_slowpath+0x40/0x63
> [29429.039959] [<c01e93b7>] sprintf+0x1d/0x20
> [29429.039959] [<c011bfc9>] vprintk+0x1cd/0x274
> [29429.039959] [<c011ba65>] try_acquire_console_sem+0xa/0x2a
> [29429.039959] [<c019e631>] sysfs_find_dirent+0x13/0x23
> [29429.039959] [<c019e107>] sysfs_add_file_mode+0x4c/0x6a
> [29429.039959] [<c019f8cd>] internal_create_group+0xda/0x139
> [29429.039959] [<c011c084>] printk+0x14/0x18
> [29429.039959] [<c023ee00>] device_pm_add+0x4d/0xb1
> [29429.039959] [<c023aacb>] device_add+0x340/0x4ad
> [29429.039959] [<f887599e>] usb_create_ep_files+0x1ed/0x26b [usbcore]
> [29429.039959] [<c019d850>] sysfs_hash_and_remove+0x44/0x50
> [29429.039959] [<f887533f>] usb_create_sysfs_intf_files+0x5a/0x72
> [usbcore]
> [29429.039959] [<f887272c>] usb_set_interface+0x137/0x140 [usbcore]
> [29429.039959] [<f8871923>] usb_disable_interface+0x1e/0x2f [usbcore]
> [29429.039959] [<f88737e6>] usb_unbind_interface+0x46/0x62 [usbcore]
> [29429.039959] [<c023bf5d>] __device_release_driver+0x58/0x76
> [29429.039959] [<c023bf93>] device_release_driver+0x18/0x21
> [29429.039959] [<f887323f>] usb_driver_release_interface+0x3c/0x76
> [usbcore]
> [29429.039959] [<f88b37b6>] hci_usb_disconnect+0x35/0x62 [hci_usb]
> [29429.039959] [<f88737d6>] usb_unbind_interface+0x36/0x62 [usbcore]
> [29429.039959] [<c023bf5d>] __device_release_driver+0x58/0x76
> [29429.039959] [<c023bf93>] device_release_driver+0x18/0x21
> [29429.039959] [<f887323f>] usb_driver_release_interface+0x3c/0x76
> [usbcore]
> [29429.039959] [<f8873292>] usb_rebind_intf+0x19/0x4e [usbcore]
> [29429.039959] [<f887332d>] do_unbind_rebind+0x4d/0x5a [usbcore]
> [29429.039959] [<f887342a>] usb_external_resume_device+0xf0/0xf6
> [usbcore]
> [29429.039959] [<c023ea2c>] device_resume+0x85/0x2f4
> [29429.039959] [<c01391c9>] suspend_devices_and_enter+0xd5/0x10d
> [29429.039959] [<c0139312>] enter_state+0xcf/0x11c
> [29429.039959] [<c01393ec>] state_store+0x8d/0xa1
> [29429.039959] [<c013935f>] state_store+0x0/0xa1
> [29429.039959] [<c01e58af>] kobj_attr_store+0x18/0x1c
> [29429.039959] [<c019de6f>] sysfs_write_file+0xa4/0xd8
> [29429.039959] [<c019ddcb>] sysfs_write_file+0x0/0xd8
> [29429.039959] [<c016816a>] vfs_write+0x83/0xf6
> [29429.039959] [<c0168597>] sys_write+0x3c/0x63
> [29429.039959] [<c01038da>] syscall_call+0x7/0xb
> [29429.039959] =======================
> [29429.039959] ---[ end trace fa351609edb352dc ]---
On Wed, 30 Jul 2008 01:16:16 -0700 Andrew Morton wrote:
> On Mon, 28 Jul 2008 13:33:21 +0200 Roberto Oppedisano <[email protected]> wrote:
>
> Let's cc linux-usb, as that appears to be the subsystem which triggered
> this:
>
> int device_pm_add(struct device *dev)
> {
> int error;
>
> pr_debug("PM: Adding info for %s:%s\n",
> dev->bus ? dev->bus->name : "No Bus",
> kobject_name(&dev->kobj));
> mutex_lock(&dpm_list_mtx);
> if (dev->parent) {
> if (dev->parent->power.status >= DPM_SUSPENDING) {
> dev_warn(dev, "parent %s is sleeping, will not add\n",
> dev->parent->bus_id);
> WARN_ON(true);
> }
>
> WARN_ON.
>
> Also, I assume that your kernel did print that "parent %s is sleeping"
> message, but you trimmed it off. That would probably be useful
> information.
>
yep, here it is;
Jul 28 13:18:59 poppero1 kernel: [29429.039959] usb_endpoint
usbdev4.2_ep03: parent 4-2:1.1 is sleeping, will not add
BTW, looking at the syslog I realize that 5 traces were connected
to the same event, so I'm going to attach them all, in case they're useful.
Thanks,
R.
> Thanks.
>
>
>> [29429.039959] ------------[ cut here ]------------
>> [29429.039959] WARNING: at drivers/base/power/main.c:82
>> device_pm_add+0x4d/0xb1()
>> [29429.039959] Modules linked in: tun radeon drm rfcomm l2cap usblp ipv6
>> cpufreq_ondemand cpufreq_stats cpufreq_userspace cpufreq_powersave
>> ipt_recent xt_tcpudp nf_conntrack_ipv4 xt_state nf_conntrack
>> iptable_filter ip_tables x_tables vfat fat fuse acpi_cpufreq freq_table
>> usbserial microcode snd_intel8x0m pcmcia snd_intel8x0 snd_ac97_codec
>> ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi
>> snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd
>> soundcore snd_page_alloc yenta_socket rsrc_nonstatic ipw2200 pcmcia_core
>> hci_usb i2c_i801 bluetooth video output battery ac button evdev usbhid
>> ohci1394 8139cp uhci_hcd ehci_hcd ieee1394 usbcore sg sr_mod cdrom
>> thermal processor fan
>> [29429.039959] Pid: 10544, comm: s2ram Tainted: G W
>> 2.6.26-00000-g8be1a6d #1
>> [29429.039959] [<c011b733>] warn_on_slowpath+0x40/0x63
>> [29429.039959] [<c01e93b7>] sprintf+0x1d/0x20
>> [29429.039959] [<c011bfc9>] vprintk+0x1cd/0x274
>> [29429.039959] [<c011ba65>] try_acquire_console_sem+0xa/0x2a
>> [29429.039959] [<c019e631>] sysfs_find_dirent+0x13/0x23
>> [29429.039959] [<c019e107>] sysfs_add_file_mode+0x4c/0x6a
>> [29429.039959] [<c019f8cd>] internal_create_group+0xda/0x139
>> [29429.039959] [<c011c084>] printk+0x14/0x18
>> [29429.039959] [<c023ee00>] device_pm_add+0x4d/0xb1
>> [29429.039959] [<c023aacb>] device_add+0x340/0x4ad
>> [29429.039959] [<f887599e>] usb_create_ep_files+0x1ed/0x26b [usbcore]
>> [29429.039959] [<c019d850>] sysfs_hash_and_remove+0x44/0x50
>> [29429.039959] [<f887533f>] usb_create_sysfs_intf_files+0x5a/0x72
>> [usbcore]
>> [29429.039959] [<f887272c>] usb_set_interface+0x137/0x140 [usbcore]
>> [29429.039959] [<f8871923>] usb_disable_interface+0x1e/0x2f [usbcore]
>> [29429.039959] [<f88737e6>] usb_unbind_interface+0x46/0x62 [usbcore]
>> [29429.039959] [<c023bf5d>] __device_release_driver+0x58/0x76
>> [29429.039959] [<c023bf93>] device_release_driver+0x18/0x21
>> [29429.039959] [<f887323f>] usb_driver_release_interface+0x3c/0x76
>> [usbcore]
>> [29429.039959] [<f88b37b6>] hci_usb_disconnect+0x35/0x62 [hci_usb]
>> [29429.039959] [<f88737d6>] usb_unbind_interface+0x36/0x62 [usbcore]
>> [29429.039959] [<c023bf5d>] __device_release_driver+0x58/0x76
>> [29429.039959] [<c023bf93>] device_release_driver+0x18/0x21
>> [29429.039959] [<f887323f>] usb_driver_release_interface+0x3c/0x76
>> [usbcore]
>> [29429.039959] [<f8873292>] usb_rebind_intf+0x19/0x4e [usbcore]
>> [29429.039959] [<f887332d>] do_unbind_rebind+0x4d/0x5a [usbcore]
>> [29429.039959] [<f887342a>] usb_external_resume_device+0xf0/0xf6
>> [usbcore]
>> [29429.039959] [<c023ea2c>] device_resume+0x85/0x2f4
>> [29429.039959] [<c01391c9>] suspend_devices_and_enter+0xd5/0x10d
>> [29429.039959] [<c0139312>] enter_state+0xcf/0x11c
>> [29429.039959] [<c01393ec>] state_store+0x8d/0xa1
>> [29429.039959] [<c013935f>] state_store+0x0/0xa1
>> [29429.039959] [<c01e58af>] kobj_attr_store+0x18/0x1c
>> [29429.039959] [<c019de6f>] sysfs_write_file+0xa4/0xd8
>> [29429.039959] [<c019ddcb>] sysfs_write_file+0x0/0xd8
>> [29429.039959] [<c016816a>] vfs_write+0x83/0xf6
>> [29429.039959] [<c0168597>] sys_write+0x3c/0x63
>> [29429.039959] [<c01038da>] syscall_call+0x7/0xb
>> [29429.039959] =======================
>> [29429.039959] ---[ end trace fa351609edb352dc ]---
>>
>
>
--
Roberto Oppedisano [RO2480-RIPE]
Infracom Network Application OSS and LIR Services
Tel. +39 045 8271518 Fax +39 045 8271499 Cell. +39 348 7419534
On Wed, 30 Jul 2008, Andrew Morton wrote:
> On Mon, 28 Jul 2008 13:33:21 +0200 Roberto Oppedisano <[email protected]> wrote:
>
> > More info on request.
> >
>
> Let's cc linux-usb, as that appears to be the subsystem which triggered
> this:
>
> int device_pm_add(struct device *dev)
> {
> int error;
>
> pr_debug("PM: Adding info for %s:%s\n",
> dev->bus ? dev->bus->name : "No Bus",
> kobject_name(&dev->kobj));
> mutex_lock(&dpm_list_mtx);
> if (dev->parent) {
> if (dev->parent->power.status >= DPM_SUSPENDING) {
> dev_warn(dev, "parent %s is sleeping, will not add\n",
> dev->parent->bus_id);
> WARN_ON(true);
> }
>
> WARN_ON.
>
> Also, I assume that your kernel did print that "parent %s is sleeping"
> message, but you trimmed it off. That would probably be useful
> information.
Hmmm... I don't suppose anyone ever thought to indicate which kernel
version produced this warning?
If you look at the offending source code in 2.6.27-rc1, you'll find a
comment that explains the whole thing. From do_unbind_rebind() --
admittedly not easy to single out from the rather lengthy stack trace
-- in drivers/usb/core/driver.c:
/* FIXME: The next line is needed because we are going to probe
* the interface, but as far as the PM core is concerned the
* interface is still suspended. The problem wouldn't exist
* if we could rebind the interface during the interface's own
* resume() call, but at the time the usb_device isn't locked!
*
* The real solution will be to carry this out during the device's
* complete() callback. Until that is implemented, we have to
* use this hack.
*/
// intf->dev.power.sleeping = 0;
The last line had to be commented out because the power.sleeping field
was removed during the transition from 2.6.26 to 2.6.27-rc1. The code
that will make this work properly, with no warnings, hasn't been
written yet; I was waiting for 2.6.27-rc1 to appear so there'd be a
more-or-less stable base to work from.
For the time being you can simply ignore the WARNING; it is harmless.
Alan Stern