[Pkg-sysvinit-devel] Bug#696534: bootlogd: System doesn't boot when serial console and bootlogd enabled

Sat Dec 22 17:11:46 UTC 2012

Am 22.12.2012 16:58, schrieb Roger Leigh:
> On Sat, Dec 22, 2012 at 02:47:49PM +0100, Rico Pietzsch wrote:
>> Dear Maintainer,
>> i've installed the package and at the next reboot the system stopped the
>> bootprocess with:
>>
>> [ ok ] Setting parameters of disc: (none).
>> ^[[24;2R
>>
>> if i press strg+c on the serial console the bootprocess run's again ...
>> first following message is
>> ... activating swap.
>>
>> if i change the kernel cmdline to start withou serial console booting
>> runs without errors.
>> normally i use the following cmdline for the kernel:
>> (console=tty0 console=ttyS0,57600n8)
>> changed to (console=tty0) works, but i need the serial line.
>> If i remove/purge bootlogd booting runs without errors.
>>
>> the archived Bug #522211 describes the same problem.
>>
>> i think it's an critical bug.
> 
> What's the root cause of the problem here?  Why does the
> boot block here?  What is strg-c (Control-C?)?
> 
> This is definitely a serious bug if bootlogd is installed
> by default, but I think it's strictly optional.  Still,
> it would be ideal if the bug is fixable.
> 

yes, (strg+c) is the german version of (control+c)

i've pasted an copy of the Output of the console from the serial line at
pastebin:
http://paste.debian.net/218305/
and attach the bootlog at this email:

i suppose it has something to to with an buffer ... but i've no
experience with this.

-------------- next part --------------
Sat Dec 22 17:39:25 2012: [....] Setting parameters of disc: (none)^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:25 2012: ï\200\240^[[6n^[D[....] Activating swap...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:25 2012: [....] Checking root file system...fsck from util-linux 2.20.1
Sat Dec 22 17:39:25 2012: hda1: clean, 1019172/19169280 files, 24470494/38315017 blocks
Sat Dec 22 17:39:25 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:25 2012: [^[[36minfo^[[39;49m] Loading kernel module loop.
Sat Dec 22 17:39:25 2012: [^[[36minfo^[[39;49m] Loading kernel module coretemp.
Sat Dec 22 17:39:25 2012: [^[[36minfo^[[39;49m] Loading kernel module w83627ehf.
Sat Dec 22 17:39:25 2012: [^[[36minfo^[[39;49m] Loading kernel module eeprom.
Sat Dec 22 17:39:25 2012: [....] Assembling MD arrays...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone (disabled in /etc/default/mdadm).
Sat Dec 22 17:39:26 2012: [....] Cleaning up temporary files... /tmp /lib/init/rw^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:26 2012: [....] Setting up LVM Volume Groups...  No volume groups found
Sat Dec 22 17:39:26 2012:   No volume groups found
Sat Dec 22 17:39:26 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:26 2012: [....] Activating lvm and md swap...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:26 2012: [....] Checking file systems...fsck from util-linux 2.20.1
Sat Dec 22 17:39:26 2012: /dev/sdb1: clean, 3608937/61054976 files, 100959868/244190000 blocks
Sat Dec 22 17:39:27 2012: /dev/sdc1: clean, 1400029/122101760 files, 452932838/488378000 blocks
Sat Dec 22 17:39:27 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:28 2012: [....] Mounting local filesystems...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:28 2012: [....] Activating swapfile swap...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:28 2012: [....] Cleaning up temporary files...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:29 2012: [....] Setting kernel variables ...sysctl: setting key "net.ipv4.tcp_mem": Invalid argument
Sat Dec 22 17:39:29 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:29 2012: [....] Configuring network interfaces...Starting rpcbind daemon....
Sat Dec 22 17:39:33 2012: Starting NFS common utilities: statd idmapd.
Sat Dec 22 17:39:34 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:34 2012: [....] Starting rpcbind daemon...[....] Already running.^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:35 2012: [....] Starting NFS common utilities: statd idmapd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:35 2012: [....] Cleaning up temporary files...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:35 2012: [^[[36minfo^[[39;49m] Setting console screen modes and fonts.
Sat Dec 22 17:39:35 2012: setterm: cannot (un)set powersave mode: Invalid argument
Sat Dec 22 17:39:35 2012: ^[[9;30]^[[14;30][....] Setting sensors limits^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:36 2012: Running 0dns-down to make sure resolv.conf is ok...done.
Sat Dec 22 17:39:36 2012: [....] Setting up X socket directories... /tmp/.X11-unix /tmp/.ICE-unix^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:36 2012: Loading the saved-state of the serial devices... 
Sat Dec 22 17:39:36 2012: /dev/ttyS0 at 0x03f8 (irq = 4) is a 16550A
Sat Dec 22 17:39:36 2012: /dev/ttyS1 at 0x02f8 (irq = 3) is a 16550A
Sat Dec 22 17:39:36 2012: [....] Starting iSCSI initiator service: iscsid^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:36 2012: [....] Setting up iSCSI targets:
Sat Dec 22 17:39:36 2012: iscsiadm: No records found
Sat Dec 22 17:39:36 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:36 2012: [....] Mounting network filesystems:^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:36 2012: [....] startpar: service(s) returned failure: keymap.sh ...^[[?25l^[[?1c^[7^[[1G[^[[31mFAIL^[[39;49m^[8^[[?25h^[[?0c ^[[31mfailed!^[[39;49m
Sat Dec 22 17:39:36 2012: INIT: Entering runlevel: 2
Sat Dec 22 17:39:36 2012: [^[[36minfo^[[39;49m] Using makefile-style concurrent boot in runlevel 2.
Sat Dec 22 17:39:37 2012: [....] Starting NFS common utilities: statd idmapd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:37 2012: [....] Not starting fancontrol; run pwmconfig first. ...^[[?25l^[[?1c^[7^[[1G[^[[33mwarn^[[39;49m^[8^[[?25h^[[?0c ^[[33m(warning).^[[39;49m
Sat Dec 22 17:39:37 2012: not starting bwbar, please edit /etc/default/bwbar
Sat Dec 22 17:39:37 2012: [....] Starting rpcbind daemon...[....] Already running.^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:37 2012: [....] Network Interface Plugging Daemon...start eth0...skip eth1...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:37 2012: [....] Enabling additional executable binary formats: binfmt-support^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:38 2012: [....] Starting system log daemon...Starting uptime daemon: ud.
Sat Dec 22 17:39:39 2012: ud[3262]: Uptime daemon starting...
Sat Dec 22 17:39:39 2012: Starting uptime daemon: uptimed.
Sat Dec 22 17:39:39 2012: Starting file alteration monitor: FAM.
Sat Dec 22 17:39:39 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:39 2012: [....] Starting ACPI services...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:39 2012: [....] Starting anac(h)ronistic cron: anacron^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:40 2012: [....] Starting deferred execution scheduler: atd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:40 2012: [....] Starting Courier authentication services: authdaemond^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:40 2012: [....] Starting Courier IMAP server: imapd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:40 2012: [....] Starting backuppc...[....] Starting Courier IMAP-SSL server: imapd-ssl^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:41 2012: [....] Starting dictionary server: dictd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:42 2012: [....] iscsitarget not enabled in "/etc/default/iscsitarget", not starting... ...^[[?25l^[[?1c^[7^[[1G[^[[33mwarn^[[39;49m^[8^[[?25h^[[?0c ^[[33m(warning).^[[39;49m
Sat Dec 22 17:39:44 2012: [....] Starting system message bus: dbus^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:44 2012: Use of qw(...) as parentheses is deprecated at /usr/share/backuppc/lib/BackupPC/Storage/Text.pm line 302.
Sat Dec 22 17:39:44 2012: Use of qw(...) as parentheses is deprecated at /usr/share/backuppc/lib/BackupPC/Lib.pm line 1413.
Sat Dec 22 17:39:44 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:44 2012: [....] Starting disk temperature monitoring daemon: hddtemp:  /dev/sda /dev/sdb /dev/sdc /dev/sdd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:45 2012: [....] No valid /etc/lirc/lircd.conf has been found.^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:45 2012: [....] Remote control support has been disabled.^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:45 2012: [....] Reconfigure LIRC or manually replace /etc/lirc/lircd.conf to enable.^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:45 2012: [....] Loading cpufreq kernel modules...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone (acpi-cpufreq).
Sat Dec 22 17:39:45 2012: [....] Starting kernel log daemon...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:46 2012: [....] CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:39:46 2012: [....] Starting domain name service: lwresd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:46 2012: [....] Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:46 2012: [....] Starting MD monitoring service: mdadm --monitor^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:46 2012: [....] Starting web server: apache2^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:49 2012: [....] Starting Avahi Unicast DNS Configuration Daemon: avahi-dnsconfd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:49 2012: [....] Starting periodic command scheduler: cron^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:52 2012: Starting mt-daapd: mt-daapd.
Sat Dec 22 17:39:58 2012: [....] Starting Common Unix Printing System: cupsd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:39:58 2012: [....] Starting MySQL database server: mysqld . . . .[....] Exporting directories for NFS kernel daemon...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:00 2012: [....] Starting NFS kernel daemon: nfsd mountd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:00 2012:  . .[....] Starting internet superserver: inetd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:01 2012: [....] Starting NTP server: ntpd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:01 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:01 2012: [^[[36minfo^[[39;49m] Checking for tables which need an upgrade, are corrupt or were 
Sat Dec 22 17:40:01 2012: not closed cleanly..
Sat Dec 22 17:40:01 2012: ProFTPD is started from inetd/xinetd.
Sat Dec 22 17:40:01 2012: [....] Starting MTA: exim4^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:07 2012: [....] Starting Samba daemons: nmbd smbd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:10 2012: saned disabled; edit /etc/default/saned
Sat Dec 22 17:40:10 2012: [....] Starting sensor daemon: sensord^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:12 2012: [....] Enabling S.M.A.R.T..../dev/sda...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0cdone.
Sat Dec 22 17:40:13 2012: [....] Starting S.M.A.R.T. daemon: smartd[....] Setting sysfs variables...^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:15 2012: [....] Starting the Winbind daemon: winbind^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:15 2012: [....] Starting OpenBSD Secure Shell server: sshd^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
Sat Dec 22 17:40:19 2012: ^[[?25l^[[?1c^[7^[[1G[^[[32m ok ^[[39;49m^[8^[[?25h^[[?0c.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 259 bytes
Desc: OpenPGP digital signature
URL: <http://lists.alioth.debian.org/pipermail/pkg-sysvinit-devel/attachments/20121222/df62754d/attachment-0001.pgp>