ERROR (lirc.c,43): /var/run/lirc/lircd: Datei oder Verzeichnis nicht gefunden

habichthugo · 8. Mai 2014

Ja, seit einiger Zeit habe ich mal wieder Probleme mit dem lircd, leider auch noch nach dem dist-upgrade von letzter Woche:

Code

May  8 17:02:47 yavdr kernel: imklog 5.8.6, log source = /proc/kmsg started.
...
May  8 17:03:05 yavdr vdr: [1246] dynamite: startup channel is 1
May  8 17:03:05 yavdr vdr: [1246] setting current skin to "NarrowHD"
May  8 17:03:05 yavdr vdr: [1246] ERROR (lirc.c,43): /var/run/lirc/lircd: Datei oder Verzeichnis nicht gefunden
May  8 17:03:05 yavdr vdr: [1246] remote control graphtft-fe - keys known
May  8 17:03:05 yavdr vdr: [1246] ERROR: remote control LIRC not ready!
May  8 17:03:05 yavdr vdr: [1246] switching to channel 1
May  8 17:03:05 yavdr vdr: [1246] [softhddev]SetVolumeDevice: 255
May  8 17:03:05 yavdr vdr: [1246] [softhddev]SetPlayMode: 1
May  8 17:03:05 yavdr vdr: [1246] OSD size changed to 1920x1080 @ 1
May  8 17:03:05 yavdr vdr: [1609] LIRC remote control thread started (pid=1246, tid=1609, prio=high)
May  8 17:03:05 yavdr vdr: [1609] ERROR: lircd connection broken, trying to reconnect every 3,0 seconds
May  8 17:03:05 yavdr vdr: [1612] receiver on device 1 thread started (pid=1246, tid=1612, prio=high)
May  8 17:03:05 yavdr vdr: [1608] streamdev server thread started (pid=1246, tid=1608, prio=high)
...
May  8 17:03:06 yavdr kernel: [   44.974630] init: wait-for-job-state (vdr-frontendvdr) main process (863) killed by TERM signal
May  8 17:03:06 yavdr vdr: [1588] dbus2vdr: upstart replies: ()
May  8 17:03:06 yavdr vdr: [1588] dbus2vdr: thread-pool for handling method-calls started
May  8 17:03:06 yavdr vdr-frontend[1636]: acip-wakeup.time.old hatte Wert: Wed May  7 15:02:00 2014
May  8 17:03:06 yavdr vdr-frontend[1636]: vergleiche mit jetzt: Thu May  8 15:03:06 2014
May  8 17:03:06 yavdr vdr-frontend[1636]: assuming start for acpi-wakeup
May  8 17:03:06 yavdr vdr: [1640] dbus2vdr: use of deprecated interface: 'List' should be called with the interface 'de.tvdr.vdr.pluginmanager'!
May  8 17:03:06 yavdr vdr-frontend[1636]: started udev monitoring of input devices
May  8 17:03:06 yavdr vdr-frontend[1636]: Error: vdr-frontend could not connect to eventlircd socket
...

Alles anzeigen

Das Ganze nach einem Autostart wegen Timer/Aufnahme. Nach reboot per ssh ist der Spuk vorbei.

Was ist da los? Wie kann ich den Fehler eingrenzen / beheben?

habichthugo · 8. Mai 2014

Zuvor passiert noch folgendes:

Code

...
May  8 17:02:47 yavdr kernel: [   18.130486] eth1: no IPv6 routers present
May  8 17:02:47 yavdr kernel: [   18.335242] RPC: Registered named UNIX socket transport module.
May  8 17:02:47 yavdr kernel: [   18.335246] RPC: Registered udp transport module.
May  8 17:02:47 yavdr kernel: [   18.335247] RPC: Registered tcp transport module.
May  8 17:02:47 yavdr kernel: [   18.335248] RPC: Registered tcp NFSv4.1 backchannel transport module.
May  8 17:02:47 yavdr kernel: [   20.207323] [drm] Initialized drm 1.1.0 20060810
May  8 17:02:47 yavdr kernel: [   22.343036] FS-Cache: Loaded
May  8 17:02:47 yavdr kernel: [   22.835575] [drm:i915_init] *ERROR* drm/i915 can't work without intel_agp module!
May  8 17:02:47 yavdr kernel: [   22.958467] eventlircd[1050]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007fff368e4848 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   23.961542] FS-Cache: Netfs 'nfs' registered for caching
May  8 17:02:47 yavdr kernel: [   24.677409] init: eventlircd main process (1050) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.677433] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.691489] init: Error while reading from descriptor: Bad file descriptor
May  8 17:02:47 yavdr kernel: [   24.694123] init: irexec main process (1051) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.694755] init: lircd main process (1108) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.711659] eventlircd[1118]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007ffff7aa3fd8 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.717801] init: eventlircd main process (1118) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.717823] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.722962] init: irexec main process (1120) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.739585] eventlircd[1133]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007fff983fa928 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.739973] init: eventlircd main process (1133) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.739993] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.743926] init: irexec main process (1136) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.762286] eventlircd[1147]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007fff225cab48 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.762602] init: eventlircd main process (1147) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.762632] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.763818] init: irexec main process (1153) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.771910] eventlircd[1165]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007fff331d4c98 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.774364] init: eventlircd main process (1165) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.774387] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.775845] init: irexec main process (1166) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.792093] eventlircd[1176]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007fff16598d88 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.792595] init: eventlircd main process (1176) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.792619] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.797528] init: Error while reading from descriptor: Bad file descriptor
May  8 17:02:47 yavdr kernel: [   24.797600] init: irexec main process (1177) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.798048] init: lircd main process (1186) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.808464] eventlircd[1187]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007fffe372e378 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.808741] init: eventlircd main process (1187) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.808764] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.810385] init: irexec main process (1188) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.818927] eventlircd[1195]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007fff54726478 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.819386] init: eventlircd main process (1195) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.819408] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.821629] init: irexec main process (1196) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.830926] eventlircd[1203]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007fff6c0914e8 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.831128] init: eventlircd main process (1203) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.831149] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.832978] init: irexec main process (1204) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.844235] eventlircd[1211]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007ffff3fb8f18 error 5 in eventlircd[400000+f000]
May  8 17:02:47 yavdr kernel: [   24.844437] init: eventlircd main process (1211) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.844458] init: eventlircd main process ended, respawning
May  8 17:02:47 yavdr kernel: [   24.845763] init: irexec main process (1212) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   24.853706] init: eventlircd main process (1219) killed by SEGV signal
May  8 17:02:47 yavdr kernel: [   24.853728] init: eventlircd respawning too fast, stopped
May  8 17:02:47 yavdr kernel: [   24.856526] init: irexec main process (1220) killed by TERM signal
May  8 17:02:47 yavdr kernel: [   25.274963] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
May  8 17:02:48 yavdr kernel: [   26.866481] init: plymouth-upstart-bridge main process (1247) killed by TERM signal
May  8 17:02:48 yavdr vdr: [1246] VDR version 2.0.3 started
May  8 17:02:48 yavdr vdr: [1246] switched to user 'vdr'
...

Alles anzeigen

!?

seahawk1986 · 8. Mai 2014

Wie sieht die /etc/init/eventlircd.conf aus? Hattest du etwas an den udev-Regeln oder evmaps verändert?

habichthugo · 8. Mai 2014

Zitat von seahawk1986

Wie sieht die /etc/init/eventlircd.conf aus? Hattest du etwas an den udev-Regeln oder evmaps verändert?

Wüsste nicht was, jedenfalls nicht in den letzten Monaten. Das Phänomen habe ich aber erst seit ca. vier Wochen...
/etc/init/eventlircd.conf:

Code

# Starts Eventlircd daemon.
#
# Eventlircd is used to control different
# infrared receivers and transceivers.


description     "Eventlirc Daemon"
author          "Gerald Dachs <gda@dachsweb.de>"


start on (filesystem or resume)
stop on (suspend or runlevel [!2345])


respawn
nice -10


script
test -f /usr/sbin/eventlircd || exit 0
mkdir -p /var/run/lirc


/sbin/modprobe uinput || /bin/true


exec /usr/sbin/eventlircd -f --socket=/var/run/lirc/lircd #--repeat-filter
end script

Alles anzeigen

mini73 · 8. Mai 2014

Bist du sicher, dass repeat-filter richtig auskommentiert ist? Nimm es mal ganz weg, vielleicht verschluckt sich eventlircd ja daran.

Lars.

habichthugo · 8. Mai 2014

Zitat von mini73

Bist du sicher, dass repeat-filter richtig auskommentiert ist? Nimm es mal ganz weg, vielleicht verschluckt sich eventlircd ja daran.

Ich habe an dieser Datei meines Wissens nichts verändert. Und das Problem tritt ja auch nur sporadisch auf.

mini73 · 8. Mai 2014

Ok, die wird schon seit zwei Jahren so ausgeliefert, müsste also ok sein.

Lars

mini73 · 8. Mai 2014

Liegen unter /var/log/vdr evtl. coredumps zum passenden Zeitpunkt?
Die könnte man mal mit gdb untersuchen, warum eventlircd da abstürzt.

Lars

habichthugo · 8. Mai 2014

Zitat von mini73

Liegen unter /var/log/vdr evtl. coredumps zum passenden Zeitpunkt?
Die könnte man mal mit gdb untersuchen, warum eventlircd da abstürzt.

Nein, /var/log/vdr ist leer.

habichthugo · 9. Mai 2014

Was könnte ich noch probieren, um dem Herr zu werden? Meine Family ist völlig hilflos, wenn das auftritt und ich nicht da bin, denn der VDR lässt sich ja nicht bedienen.

Das dist-upgrade, letzte Woche, hatte ich hauptsächlich in der Hoffnung gemacht, eben dieses Phänomen zu beseitigen. Das tritt erst seit ein paar Wochen auf, ich hatte aber seit Monaten nichts mehr ernsthaftes auf VDR verändert (letztes dist-upgrade Mitte letzten Jahres)...

mini73 · 9. Mai 2014

Moin!

Code

May  8 17:02:47 yavdr kernel: [   24.844235] eventlircd[1211]: segfault at fffffffffffffff8 ip 0000000000401c30 sp 00007ffff3fb8f18 error 5 in eventlircd[400000+f000]

Das ist dein Problem. Warum auch immer stürzt eventlircd bei dir ab. Evtl. könnte es helfen, im Upstart-Job mal "ulimit -c unlimited" einzutragen, vielleicht entstehen ja dann coredumps. Direkt vor der exec-Zeile.

Lars.

seahawk1986 · 9. Mai 2014

Wenn ich dich richtig verstanden habe tritt das Problem also nur sporadisch auf? Siehst du in der Ausgabe von dmesg oder dem Syslog irgendeinen Kernel-Oops oder andere Probleme?

Mir fällt da momentan auch nichts anderes ein als zu versuchen einen Backtrace zu erzeugen um zu sehen warum er abschmiert. Also mal die Pakete gdb und pastebinit installieren und beim nächsten Mal wenn das passiert mal eine kleine Liste abarbeiten:

Code

df -h
df -i
sudo -i
stop eventlircd
mkdir -p /var/run/lirc
ulimit -c unlimited
gdb --args /usr/sbin/eventlircd -f -vvv --socket=/var/run/lirc/lircd
# in gdb
set logging file /tmp/mylog.txt
set logging on
run
# nach dem Crash 
bt
bt full
info registers
x/16i $pc
thread apply all backtrace
set logging off
quit
# Log pasten
pastebinit /tmp/mylog.txt

Alles anzeigen

habichthugo · 9. Mai 2014

So, bin jetzt wieder in dem Zustand, habe aber noch nix vorbereiten können, daher erst mal:

Code

root@yavdr:~# cd /var/log
root@yavdr:/var/log# pastebinit syslog
http://paste.ubuntu.com/7422171/
root@yavdr:/var/log# pastebinit dmesg
http://paste.ubuntu.com/7422178/
root@yavdr:/var/log# df -h
Dateisystem    Größe Benutzt Verf. Verw% Eingehängt auf
/dev/sdb1       7,1G    4,4G  2,5G   65% /
udev            2,0G    4,0K  2,0G    1% /dev
tmpfs           393M    616K  392M    1% /run
none            5,0M       0  5,0M    0% /run/lock
none            2,0G       0  2,0G    0% /run/shm
/dev/sda1       2,7T    2,1T  448G   83% /srv
root@yavdr:/var/log# df -i
Dateisystem       Inodes IBenutzt     IFrei IUse% Eingehängt auf
/dev/sdb1         471424   205045    266379   44% /
udev              499607      518    499089    1% /dev
tmpfs             501818      425    501393    1% /run
none              501818        1    501817    1% /run/lock
none              501818        1    501817    1% /run/shm
/dev/sda1      180707328   209698 180497630    1% /srv

Alles anzeigen

Da ich eh gnadenlos als root arbeite ist 'sudo -i' ja wohl eher nicht von Nöten?...

seahawk1986 · 9. Mai 2014

Zitat von habichthugo

Da ich eh gnadenlos als root arbeite ist 'sudo -i' ja wohl eher nicht von Nöten?

Nein, dann nicht...

habichthugo · 9. Mai 2014

Also weiter...

Code

root@yavdr:/var/log# ls -l /var/run/lirc
insgesamt 0
srw-rw-rw- 1 root root 0 Mai  9 13:29 lircd.1149
srw-rw-rw- 1 root root 0 Mai  9 18:32 lircd.5707
root@yavdr:/var/log# ls -ld /var/run/lirc
drwxr-xr-x 2 root root 80 Mai  9 18:33 /var/run/lirc
root@yavdr:/var/log# ulimit -c
0
root@yavdr:/var/log# gdb --args /usr/sbin/eventlircd -f -vvv --socket=/var/run/lirc/lircd
GNU gdb (Ubuntu/Linaro 7.4-2012.04-0ubuntu2.1) 7.4-2012.04
Copyright (C) 2012 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-linux-gnu".
For bug reporting instructions, please see:
<http://bugs.launchpad.net/gdb-linaro/>...
Reading symbols from /usr/sbin/eventlircd...(no debugging symbols found)...done.
(gdb) set logging file /tmp/mylog.txt
(gdb) set logging on
Copying output to /tmp/mylog.txt.
(gdb) run
Starting program: /usr/sbin/eventlircd -f -vvv --socket=/var/run/lirc/lircd
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".


Program received signal SIGSEGV, Segmentation fault.
0x0000000000401c30 in ?? ()
(gdb) q
A debugging session is active.


        Inferior 1 [process 6804] will be killed.


Quit anyway? (y or n) y

Alles anzeigen

Hm...

habichthugo · 9. Mai 2014

Zitat von seahawk1986

Code

gdb --args /usr/sbin/eventlircd -f -vvv --socket=/var/run/lirc/lircd
# in gdb
set logging file /tmp/mylog.txt
set logging on
run
# nach dem Crash 
bt
bt full
info registers
x/16i $pc
thread apply all backtrace
set logging off
quit
# Log pasten
pastebinit /tmp/mylog.txt

Alles anzeigen

Ok, jetzt hab' ich's wohl kapiert:

Code

root@yavdr:/var/log# pastebinit /tmp/mylog.txt
http://paste.ubuntu.com/7422369/

seahawk1986 · 9. Mai 2014

Ok, so ganz ohne Debug-Symbole sieht man nichts... kannst du das mal mit diesen Paketen mit Debug-Symbolen probieren?

Code

cd /tmp
wget https://dl.dropboxusercontent.com/u/960809/eventlircd/eventlircd_0.0.1%2Bsvn20110409.0930-8yavdr2%7Eprecise_amd64.deb
wget https://dl.dropboxusercontent.com/u/960809/eventlircd/eventlircd-dbg_0.0.1%2Bsvn20110409.0930-8yavdr2%7Eprecise_amd64.deb
dpkg -i eventlircd*amd64.deb

habichthugo · 9. Mai 2014

Zitat von seahawk1986
Ok, so ganz ohne Debug-Symbole sieht man nichts... kannst du das mal mit diesen Paketen mit Debug-Symbolen probieren?
Code
cd /tmp
wget https://dl.dropboxusercontent.com/u/960809/eventlircd/eventlircd_0.0.1%2Bsvn20110409.0930-8yavdr2%7Eprecise_amd64.deb
wget https://dl.dropboxusercontent.com/u/960809/eventlircd/eventlircd-dbg_0.0.1%2Bsvn20110409.0930-8yavdr2%7Eprecise_amd64.deb
dpkg -i eventlircd*amd64.deb

Ja, aber...

Code

root@yavdr:/tmp# gdb --args /usr/sbin/eventlircd -f -vvv --socket=/var/run/lirc/lircd
GNU gdb (Ubuntu/Linaro 7.4-2012.04-0ubuntu2.1) 7.4-2012.04
Copyright (C) 2012 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-linux-gnu".
For bug reporting instructions, please see:
<http://bugs.launchpad.net/gdb-linaro/>...
Reading symbols from /usr/sbin/eventlircd...Reading symbols from /usr/lib/debug/usr/sbin/eventlircd...done.
done.
(gdb) set logging file /tmp/mylog.txt
(gdb) set logging on
Copying output to /tmp/mylog.txt.
(gdb) run
Starting program: /usr/sbin/eventlircd -f -vvv --socket=/var/run/lirc/lircd
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
eventlircd[7515]: input device /dev/input/event2: events of unsupported event type EV_MSC will be discarded
eventlircd[7515]: input device /dev/input/event2: event code 0x04 of unsupported event type EV_MSC will be discarded
eventlircd[7515]: input device /dev/input/event2: events of unsupported event type EV_REP will be discarded
eventlircd[7515]: input device /dev/input/event2: grabbed

Alles anzeigen

So steht der Debugger (ohne weitere Eingabemöglichkeit)!? Andere Konsole:

Code

root@yavdr:~# status eventlircd
eventlircd stop/waiting
root@yavdr:~# status lircd
lircd stop/waiting

Wie geht's weiter?

seahawk1986 · 9. Mai 2014

Jetzt tritt wohl gerade kein Segfault mehr auf. Am besten schaust du beim nächsten Mal wenn das Problem auftritt nach.

habichthugo · 9. Mai 2014

Zitat von seahawk1986

Jetzt tritt wohl gerade kein Segfault mehr auf. Am besten schaust du beim nächsten Mal wenn das Problem auftritt nach.

Die Debug-Version verhält sich offenbar anders. Vorher war eventlircd nicht zu starten, jetzt schon...
Hatte ich schon erwähnt, das lircd auch nicht (mehr) gelaufen ist? Vielleicht liegt es auch daran, dass der nicht hoch kommt?

ERROR (lirc.c,43): /var/run/lirc/lircd: Datei oder Verzeichnis nicht gefunden

Jetzt mitmachen!

Teilen

Benutzer online in diesem Thema