Bug 218234

Summary: "udev_run_hotplugd abnormal exit" on a startup
Product: [Fedora] Fedora Reporter: Michal Jaegermann <michal>
Component: kernelAssignee: Kernel Maintainer List <kernel-maint>
Status: CLOSED ERRATA QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: medium Docs Contact:
Priority: medium    
Version: 6CC: harald, mefoster, triage
Target Milestone: ---   
Target Release: ---   
Hardware: All   
OS: Linux   
Whiteboard: bzcl34nup
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2008-04-04 16:03:08 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Attachments:
Description Flags
udevdebug output from a "level 3" boot
none
udevdebug output from a "normal" boot none

Description Michal Jaegermann 2006-12-03 22:59:35 UTC
Description of problem:

Sometimes, and I really mean "sometimes", when booting an FC6
machine something like that shows up from udev startup:
 
  udev: /lib/udev/udev_run_hotplugd abnormal exit

followed by "[ OK ]".  The reason for "something like that" is that
every time I observed that a machine was booting into a level 5 and
with rhgb so this message quickly vanished from a screen never to be
seen again.  I failed to find something in logs which would
look like associated with such event and it appears that things are
"normal" after that.

OTOH a newly inserted Cardbus card (a wireless interface) is not
automatically recognized, always, and it needs '/sbin/pccardctl insert'
to show up to the system; and I wonder if this is not really related
to the above with a possibilty that such failure is usually silent.

Version-Release number of selected component (if applicable):
udev-095-14

How reproducible:
I did not managed to get that even once when I tried to reproduce
that and look closer.  OTOH the message was observed many times.

Comment 1 Bill Nottingham 2006-12-05 17:26:22 UTC
/lib/udev/udev_run_hotplugd is provided by udev. Tag!

Comment 2 Harald Hoyer 2006-12-05 17:36:37 UTC
do find something in /var/log/messages?
maybe you could boot with udevdebug in the kernel command line?

Comment 3 Michal Jaegermann 2006-12-05 18:31:21 UTC
> do find something in /var/log/messages?

Quoting myself: "failed to find something in logs". Unfortunately.
This happens before anyting is mounted although syslogd should be
already running.

> maybe you could boot with udevdebug in the kernel command line?

Smells to me like some kind of a race so 'udevdebug' will likely
disturb that sufficiently enough. :-)  In any case - this is producing
so much output that if "abnormal exit" appears there somewhere
it is not likely to be noticed and I am not sure if everything is
logged.

I attach below two udev traces from logs.  One from a boot
to level 3 and another to level 5 with rhgb and all that jazz.
When booting to level 3 I later found in dmesg output, although not
in /var/log/messages, the following:

kobject_add failed for vcs1 with -EEXIST, don't try to register things with the
same name in the same directory.
 [<c04051db>] dump_trace+0x69/0x1af
 [<c0405339>] show_trace_log_lvl+0x18/0x2c
 [<c04058ed>] show_trace+0xf/0x11
 [<c04059ea>] dump_stack+0x15/0x17
 [<c04e52f2>] kobject_add+0x165/0x18e
 [<c0553703>] class_device_add+0xa2/0x3de
 [<c0553ad1>] class_device_create+0x82/0xa2
 [<c05373f0>] vcs_make_devfs+0x3c/0x7e
 [<c053c2bf>] con_open+0x6f/0x7c
 [<c0532bfe>] tty_open+0x1e2/0x369
 [<c0476eca>] chrdev_open+0xfb/0x12f
 [<c046de32>] __dentry_open+0xc7/0x1ab
 [<c046df90>] nameidata_to_filp+0x24/0x33
 [<c046dfd1>] do_filp_open+0x32/0x39
 [<c046e01a>] do_sys_open+0x42/0xbe
 [<c046e0cf>] sys_open+0x1c/0x1e
 [<c0404013>] syscall_call+0x7/0xb
DWARF2 unwinder stuck at syscall_call+0x7/0xb
Leftover inexact backtrace:
 =======================
kobject_add failed for vcsa1 with -EEXIST, don't try to register things with the
same name in the same directory.
 [<c04051db>] dump_trace+0x69/0x1af
 [<c0405339>] show_trace_log_lvl+0x18/0x2c
 [<c04058ed>] show_trace+0xf/0x11
 [<c04059ea>] dump_stack+0x15/0x17
 [<c04e52f2>] kobject_add+0x165/0x18e
 [<c0553703>] class_device_add+0xa2/0x3de
 [<c0553ad1>] class_device_create+0x82/0xa2
 [<c053742d>] vcs_make_devfs+0x79/0x7e
 [<c053c2bf>] con_open+0x6f/0x7c
 [<c0532bfe>] tty_open+0x1e2/0x369
 [<c0476eca>] chrdev_open+0xfb/0x12f
 [<c046de32>] __dentry_open+0xc7/0x1ab
 [<c046df90>] nameidata_to_filp+0x24/0x33
 [<c046dfd1>] do_filp_open+0x32/0x39
 [<c046e01a>] do_sys_open+0x42/0xbe
 [<c046e0cf>] sys_open+0x1c/0x1e
 [<c0404013>] syscall_call+0x7/0xb
DWARF2 unwinder stuck at syscall_call+0x7/0xb
Leftover inexact backtrace:
 =======================

and that seems to related to udevd operations.  These backtraces
were not repeated when booting to level 5 although another boot
to level 3 produced those again.  OTOH I cannot tell when I will
see "abnormal exit" the next time and I never encoutered such
thing when booting to level 3 (although this may be "luck";
I was not doing that as often as a "normal" boot).



Comment 4 Michal Jaegermann 2006-12-05 18:32:36 UTC
Created attachment 142875 [details]
udevdebug output from a "level 3" boot

Comment 5 Michal Jaegermann 2006-12-05 18:33:16 UTC
Created attachment 142876 [details]
udevdebug output from a "normal" boot

Comment 6 Michal Jaegermann 2006-12-05 18:36:35 UTC
Oops! I should add. Backtraces in comment #3 are from 2.6.18-1.2849.fc6
kernel.

Comment 7 Michal Jaegermann 2006-12-05 19:20:57 UTC
One more clarification to comment #3.  When booting to level 3 _without_
'udevdebug' I am not seeing backtraces as quoted there; or at least it
did not happen when I was checking.

Comment 8 Harald Hoyer 2007-09-20 10:52:14 UTC
still a problem with latest kernel/udev/fedora versions?

Comment 9 Michal Jaegermann 2007-09-24 16:47:46 UTC
> still a problem with latest kernel/udev/fedora versions?

I did not notice anything like that on a few tries with
2.6.22.5-49.fc6.  OTOH this was showing up only sporadically
so, unless an underlying cause is determined, who knows?

Comment 10 Harald Hoyer 2008-02-20 11:33:34 UTC
Fixed in recent F8?

Comment 11 Michal Jaegermann 2008-02-20 16:35:58 UTC
I did not observe that for a long time so I guess that it is gone
although noticing that never was easy.  OTOH comment #9 still applies.

Comment 12 Harald Hoyer 2008-02-20 16:47:56 UTC
As far as I can interpret, the backtraces are from kernel drivers trying to
register files in sysfs. Namely "vcs1" and "vcsa1". Maybe a framebuffer module
not listed in /etc/modprobe.d/blacklist-compat.

Comment 13 Harald Hoyer 2008-02-20 16:49:01 UTC
maybe a kernel guy can give more information on comment #3 ?

Comment 14 Michal Jaegermann 2008-02-20 17:26:13 UTC
The backtrace from a comment #3 may, or may not, be releated to
those abnormal exits.  In any case this was a while.

AFAICT _currently_ framebuffer modules are listed in
/etc/modprobe.d/blacklist-compat.  If your guess is correct that
would prevent the problem from showing up.

The issue was reported well over a year ago and I am afraid that
currently all setup from that time (configurations, software versions)
is impossible for me to reproduce.  It now apparently vanished so,
unless somebody has some insights to share, I would think that this
could be closed.


Comment 15 Chuck Ebbert 2008-02-20 22:34:33 UTC
(In reply to comment #13)
> maybe a kernel guy can give more information on comment #3 ?

That was an old kernel bug that was causing duplicate virtual console names to
be registered.

Comment 16 Harald Hoyer 2008-02-21 07:11:50 UTC
close?

Comment 17 Bug Zapper 2008-04-04 05:05:05 UTC
Fedora apologizes that these issues have not been resolved yet. We're
sorry it's taken so long for your bug to be properly triaged and acted
on. We appreciate the time you took to report this issue and want to
make sure no important bugs slip through the cracks.

If you're currently running a version of Fedora Core between 1 and 6,
please note that Fedora no longer maintains these releases. We strongly
encourage you to upgrade to a current Fedora release. In order to
refocus our efforts as a project we are flagging all of the open bugs
for releases which are no longer maintained and closing them.
http://fedoraproject.org/wiki/LifeCycle/EOL

If this bug is still open against Fedora Core 1 through 6, thirty days
from now, it will be closed 'WONTFIX'. If you can reporduce this bug in
the latest Fedora version, please change to the respective version. If
you are unable to do this, please add a comment to this bug requesting
the change.

Thanks for your help, and we apologize again that we haven't handled
these issues to this point.

The process we are following is outlined here:
http://fedoraproject.org/wiki/BugZappers/F9CleanUp

We will be following the process here:
http://fedoraproject.org/wiki/BugZappers/HouseKeeping to ensure this
doesn't happen again.

And if you'd like to join the bug triage team to help make things
better, check out http://fedoraproject.org/wiki/BugZappers