[systemd-devel] systemd hang on booting after mount

[Chanwoo Choi]

Kay Sievers wrote:
> On Tue, Jan 18, 2011 at 10:48, Lennart Poettering
> <lennart at poettering.net> wrote:
> 
>> One other guess is that your kernel is too old, you need the
>> /sys/fs/cgroup mount point which is only available in recent kernels...
> 
> That should be in 2.6.36, which is mentioned earlier in the thread. To
> check, it's this commit:
>   http://git.kernel.org/?p=linux/kernel/git/torvalds/linux-2.6.git;a=commitdiff;h=676db4af043014e852f67ba0349dae0071bd11f3
> 
> Kay
> 

I solved this error. The cause of error is that my system don't open directory 
with flag(O_NOCTTY, O_NONBLOCK, O_DIRECTORY) in manager_setup_cgroup(). (in src/cgroup.c)
I will find the reason of it on my system.

Then, I faced another error which prints following debug message :

Starting Apply System Clock UTC Offset...
[    5.128666] systemd[1]: About to execute: /sbin/hwclock --systz
[    5.158289] systemd[1]: Forked /sbin/hwclock as 1370
[    5.162304] systemd[1]: hwclock-load.service changed dead -> start
[    5.168205] systemd[1]: Running GC...
[    5.172719] systemd[1]: event type=116
[    5.175364] systemd[1]: Code should not be reached 'Unknown epoll event type.' at src/manager.c:2279, function proce
ss_event(). Aborting.
[    5.219936] systemd[1]: Caught <ABRT>, dumped core as pid 1373.


Why happen this aborting error?


It is full log on booting.


[    2.785735] devtmpfs: mounted
Mounting proc to /proc of type proc with options n/a.
Mounting sysfs to /sys of type sysfs with options n/a.
Mounting tmpfs to /dev/shm of type tmpfs with options mode=1777.
Mounting devpts to /dev/pts of type devpts with options mode=620,gid=5.
Mounting tmpfs to /sys/fs/cgroup of type tmpfs with options mode=755.
Mounting cgroup to /sys/fs/cgroup/systemd of type cgroup with options none,name=systemd.
Mounting cgroup to /sys/fs/cgroup/cpu of type cgroup with options cpu.
[    3.110230] systemd[1]: Succesfully opened /dev/kmsg for logging.
[    3.115270] systemd[1]: systemd 16 running in system mode. (-PAM -LIBWRAP -AUDIT -SELINUX +SYSVINIT -LIBCRYPTSETUP;
ubuntu)
Welcome to Linux!
[    3.144437] systemd[1]: Your kernel apparently lacks built-in ipv6 support. Might be a good idea to compile it in. W
e'll now try to work around this by calling '/sbin/modprobe ipv6'...
[    3.160569] systemd[1]: About to execute: /sbin/modprobe -qab -- ipv6
[    3.169053] systemd[1]: Forked /sbin/modprobe as 1355
[    3.651683] systemd[1]: /sbin/modprobe failed with error code 1.
[    3.657482] systemd[1]: Set hostname to <chan>.
[    3.662137] systemd[1]: /etc/mtab is not a symlink or not pointing to /proc/self/mounts. This is not supported anymo
re. Please make sure to replace this file by a symlink to avoid incorrect or misleading mount(8) output.
[    3.704345] systemd[1]: Looking for unit files in:
[    3.707659] systemd[1]:      /usr/local/build/etc/systemd/system
[    3.713303] systemd[1]:      /etc/systemd/system
[    3.717554] systemd[1]:      /lib/systemd/system
[    3.721809] systemd[1]:      /usr/local/build/lib/systemd/system
[    3.727569] systemd[1]: Looking for SysV init scripts in:
[    3.732834] systemd[1]:      /etc/init.d
[    3.736397] systemd[1]: Looking for SysV rcN.d links in:
[    3.741688] systemd[1]:      /etc
[    3.745075] systemd[1]: Using cgroup controller name=systemd. File system hierarchy is at /sys/fs/cgroup/systemd/sys
tem.
[    3.755824] systemd[1]: Installed release agent.
[    3.782973] systemd[1]: Created root group.
[    3.785728] systemd[1]: Using notification socket /org/freedesktop/systemd1/notify
[    3.796057] systemd[1]: Failed to get system D-Bus connection, retrying later: Failed to connect to socket /var/run/
dbus/system_bus_socket: Connection refused
[    3.808767]
[    3.810350] systemd[1]: Failed to get API D-Bus connection, retrying later: Failed to connect to socket /var/run/dbu
s/system_bus_socket: Connection refused
[    3.824393] systemd[1]: Successfully created private D-Bus server.
[    3.830299] systemd[1]: Using kernel console ttySAC2
[    3.891874] systemd[1]: Failed to load configuration for rsyslog.service: No such file or directory
[    3.927792] systemd[1]: Failed to load configuration for udev.service: No such file or directory
[    3.969147] systemd[1]: Failed to load configuration for auditd.service: No such file or directory
[    3.981922] systemd[1]: Automatically adding serial getty for ttySAC2
[    4.014490] systemd[1]: -.mount changed dead -> mounted
[    4.018511] systemd[1]: Activating default unit: default.target
[    4.024179] systemd[1]: Trying to enqueue job default.target/start/replace
[    4.031907] systemd[1]: Installed new job default.target/start as 1
[    4.037264] systemd[1]: Installed new job multi-user.target/start as 2
[    4.043773] systemd[1]: Installed new job basic.target/start as 3
[    4.049850] systemd[1]: Installed new job sysinit.target/start as 4
[    4.056099] systemd[1]: Installed new job local-fs.target/start as 5
[    4.062436] systemd[1]: Installed new job boot.mount/start as 6
[    4.068400] systemd[1]: Installed new job dev-mmcblk0p2.device/start as 7
[    4.075112] systemd[1]: Installed new job quotaon.service/start as 12
[    4.081536] systemd[1]: Installed new job systemd-logger.socket/start as 13
[    4.088481] systemd[1]: Installed new job quotacheck.service/start as 14
[    4.095164] systemd[1]: Installed new job var-lock.mount/start as 15
[    4.101503] systemd[1]: Installed new job systemd-remount-api-vfs.service/start as 16
[    4.109315] systemd[1]: Installed new job fsck-root.service/start as 18
[    4.115912] systemd[1]: Installed new job var-run.mount/start as 19
[    4.122162] systemd
[    4.124247] [1]: Installed new job cryptsetup.target/start as 20
[    4.130235] systemd[1]: Installed new job remount-rootfs.service/start as 21
[    4.137264] systemd[1]: Installed new job swap.target/start as 22
[    4.143341] systemd[1]: Installed new job hwclock-load.service/start as 23
[    4.150200] systemd[1]: Installed new job systemd-ask-password-console.path/start as 25
[    4.158186] systemd[1]: Installed new job sys-kernel-debug.automount/start as 27
[    4.165565] systemd[1]: Installed new job proc-sys-fs-binfmt_misc.automount/start as 28
[    4.173552] systemd[1]: Installed new job systemd-random-seed-load.service/start as 29
[    4.181452] systemd[1]: Installed new job systemd-vconsole-setup.service/start as 31
[    4.189178] systemd[1]: Installed new job sys-kernel-security.automount/start as 32
[    4.196817] systemd[1]: Installed new job systemd-modules-load.service/start as 33
[    4.204369] systemd[1]: Installed new job dev-mqueue.automount/start as 34
[    4.211227] systemd[1]: Installed new job systemd-sysctl.service/start as 35
[    4.218259] systemd[1]: Installed new job dev-hugepages.automount/start as 36
[    4.225378] systemd[1]: Installed new job systemd-tmpfiles-setup.service/start as 37
[    4.233117] systemd[1]: Installed new job sockets.target/start as 40
[    4.239440] systemd[1]: Installed new job dbus.socket/start as 41
[    4.245517] systemd[1]: Installed new job syslog.socket/start as 42
[    4.251768] systemd[1]: Installed new job systemd-shutdownd.socket/start as 43
[    4.258974] systemd[1]: Installed new job systemd-initctl.socket/start as 44
[    4.266005] systemd[1]: Installed new job systemd-tmpfiles-clean.timer/start as 45
[    4.273557] systemd[1]: Installed new job sysctl.service/start as 46
[    4.279895] systemd[1]: Installed new job systemd-tmpfiles.service/start as 47
[    4.287100] systemd[1]: Installed new job remote-fs.target/start as 48
[    4.293610] systemd[1]: Installed new job network.target/start as 49
[    4.299948] systemd[1]: Installed new job getty.target/start as 50
[    4.306111] systemd[1]: Installed new job getty at tty2.service/start as 51
[    4.312813] systemd[1]: Installed new job dev-tty2.device/start as 52
[    4.319220] systemd[1]: Installed new job getty at tty1.service/start as 53
[    4.325920] systemd[1]: Installed new job dev-tty1.device/start as 54
[    4.332329] systemd[1]: Installed new job getty at tty6.service/start as 55
[    4.339027] systemd[1]: Installed new job dev-tty6.device/start as 56
[    4.345437] systemd[1]: Installed new job getty at tty4.service/start as 57
[    4.352136] systemd[1]: Installed new job dev-tty4.device/start as 58
[    4.358546] systemd[1]: Installed new job getty at tty5.service/start as 59
[    4.365249] systemd[1]: Installed new job dev-tty5.device/start as 60
[    4.371654] systemd[1]: Installed new job getty at tty3.service/start as 61
[    4.378352] systemd[1]: Installed new job dev-tty3.device/start as 62
[    4.384763] systemd[1]: Installed new job serial-getty at ttySAC2.service/start as 63
[    4.392329] systemd[1]: Installed new job dev-ttySAC2.device/start as 64
[    4.399000] systemd[1]: Installed new job systemd-user-sessions.service/start as 65
[    4.406638] systemd[1]: Installed new job dbus.service/start as 66
[    4.412802] systemd[1]: Installed new job systemd-ask-password-wall.path/start as 67
[    4.420564] systemd[1]: Enqueued job default.target/start as 1
[    4.427188] systemd[1]: systemd-ask-password-wall.path changed dead -> waiting
[    4.433572] systemd[1]: Job systemd-ask-password-wall.path/start finished, success=yes
[    4.441508] systemd[1]: network.target changed dead -> active
[    4.447194] systemd[1]: Job network.target/start finished, success=yes
[    4.453702] systemd[1]: remote-fs.target changed dead -> active
[    4.459607] systemd[1]: Job remote-fs.target/start finished, success=yes
Starting Apply Kernel Variables...
[    4.478182] systemd[1]: About to execute: /sbin/sysctl -e -q -p /etc/sysctl.conf
[    4.513082] systemd[1]: Forked /sbin/sysctl as 1356
[    4.516995] systemd[1]: sysctl.service changed dead -> start
[    4.522370] systemd[1]: systemd-initctl.socket changed dead -> listening
[    4.528838] systemd[1]: Job systemd-initctl.socket/start finished, success=yes
[    4.536146] systemd[1]: systemd-shutdownd.socket changed dead -> listening
[    4.542917] systemd
[    4.545334] [1]: Job systemd-shutdownd.socket/start finished, success=yes
[    4.551977] systemd[1]: syslog.socket changed dead -> listening
[    4.557674] systemd[1]: Job syslog.socket/start finished, success=yes
[    4.564192] systemd[1]: Autofs kernel version 1.0
[    4.569066] systemd[1]: Autofs protocol version 5.1
[    4.573640] systemd[1]: dev-hugepages.automount changed dead -> waiting
[    4.580244] systemd[1]: Job dev-hugepages.automount/start finished, success=yes
[    4.587621] systemd[1]: Starting of systemd-sysctl.service requested but condition failed. Ignoring.
[    4.596622] systemd[1]: Job systemd-sysctl.service/start finished, success=yes
[    4.604049] systemd[1]: Autofs protocol version 5.1
[    4.608703] systemd[1]: dev-mqueue.automount changed dead -> waiting
[    4.615042] systemd[1]: Job dev-mqueue.automount/start finished, success=yes
[    4.622116] systemd[1]: Starting of systemd-modules-load.service requested but condition failed. Ignoring.
[    4.631692] systemd[1]: Job systemd-modules-load.service/start finished, success=yes
[    4.639585] systemd[1]: Failed to initialize automounter: No such file or directory
[    4.647058] systemd
[    4.649177] [1]: sys-kernel-security.automount changed dead -> failed
[    4.655960] systemd[1]: Job sys-kernel-security.automount/start finished, success=no
[    4.663316] systemd[1]: Unit sys-kernel-security.automount entered failed state.
[    4.670929] systemd[1]: Failed to initialize automounter: No such file or directory
[    4.678306] systemd[1]: proc-sys-fs-binfmt_misc.automount changed dead -> failed
[    4.685705] systemd[1]: Job proc-sys-fs-binfmt_misc.automount/start finished, success=no
[    4.693764] systemd[1]: Unit proc-sys-fs-binfmt_misc.automount entered failed state.
[    4.701686] systemd[1]: Autofs protocol version 5.1
[    4.706359] systemd[1]: sys-kernel-debug.automount changed dead -> waiting
[    4.713221] systemd[1]: Job sys-kernel-debug.automount/start finished, success=yes
[    4.721163] systemd[1]: systemd-ask-password-console.path changed dead -> waiting
[    4.728242] systemd[1]: Job systemd-ask-password-console.path/start finished, success=yes
[    4.736409] systemd[1]: swap.target changed dead -> active
[    4.741862] systemd[1]: Job swap.target/start finished, success=yes
[    4.748170] systemd[1]: cryptsetup.target changed dead -> active
[    4.754107] systemd[1]: Job cryptsetup.target/start finished, success=yes
Starting Runtime Directory...
[    4.773233] systemd[1]: About to execute: /bin/mount tmpfs /var/run -t tmpfs -o mode=755,nosuid,nodev,noexec
[    4.818142] systemd[1]: Forked /bin/mount as 1359
[    4.821435] systemd[1]: var-run.mount changed dead -> mounting
Starting Lock Directory...
[    4.838627] systemd[1]: About to execute: /bin/mount tmpfs /var/lock -t tmpfs -o mode=775,gid=lock,nosuid,nodev,noex
ec
[    4.848215]
[    4.883164] systemd[1]: Forked /bin/mount as 1361
[    4.886447] systemd
[    4.886990] tmpfs: Bad value 'lock' for mount option 'gid'
[    4.893974] [1]: var-lock.mount changed dead -> mounting
[    4.899422] systemd[1]: systemd-logger.socket changed dead -> listening
[    4.905873] systemd[1]: Job systemd-logger.socket/start finished, success=yes
Starting Remount API VFS...
[    4.923262] systemd[1]: About to execute: /usr/local/build//lib/systemd/systemd-remount-api-vfs
[    4.978287] systemd[1]: Forked /usr/local/build/lib/systemd/systemd-remount-api-vfs as 1363
[    4.985812] systemd[1]: systemd-remount-api-vfs.service changed dead -> start
Starting File System Check on Root Device...
[    5.003431] systemd[1]: About to execute: /usr/local/build//lib/systemd/systemd-fsck
[    5.043367] systemd[1]: Forked /usr/local/build/lib/systemd/systemd-fsck as 1364
[    5.049758] systemd[1]: fsck-root.service changed dead -> start
Starting Setup Virtual Console...
[    5.061896] systemd[1]: About to execute: /usr/local/build//lib/systemd/systemd-vconsole-setup
[    5.103288] systemd[1]: Forked /usr/local/build/lib/systemd/systemd-vconsole-setup as 1368
[    5.111954] systemd[1]: systemd-vconsole-setup.service changed dead -> start
Starting Apply System Clock UTC Offset...
[    5.128666] systemd[1]: About to execute: /sbin/hwclock --systz
[    5.158289] systemd[1]: Forked /sbin/hwclock as 1370
[    5.162304] systemd[1]: hwclock-load.service changed dead -> start
[    5.168205] systemd[1]: Running GC...
[    5.172719] systemd[1]: event type=116
[    5.175364] systemd[1]: Code should not be reached 'Unknown epoll event type.' at src/manager.c:2279, function proce
ss_event(). Aborting.
[    5.219936] systemd[1]: Caught <ABRT>, dumped core as pid 1373.
[    5.224582] systemd[1]: Freezing execution.

[   10.134949] systemd-vconsole-setup[1368]: Succesfully opened /dev/kmsg for logging.
[   10.141324] systemd-vconsole-setup
[   10.145256] [1368]: /bin/setfont failed with error code 1.
[   10.213835] systemd-cgroups-agent[1374]: Succesfully opened /dev/kmsg for logging.
[   10.219935] systemd-cgroups-agent[1374]: Succesfully opened syslog for logging.
[   15.155658] systemd-cgroups-agent[1376]: Succesfully opened /dev/kmsg for logging.
[   15.162113] systemd-cgroups-agent[1376]: Succesfully opened syslog for logging.


Thanks you,
Chanwoo Choi