[systemd-bugs] [Bug 87428] systemd-nspawn: restarting systemd-nspawn@ container fails

Mon Feb 9 17:24:21 PST 2015

https://bugs.freedesktop.org/show_bug.cgi?id=87428

--- Comment #4 from Jonathan Liu <net147 at gmail.com> ---
Tested again with systemd git 1c11181458990715e0ffd49e848baac5959e3c89
installed on both host and the container with sleep time of 60 seconds and 10
seconds. The container fails to reboot for both cases now.

60 second sleep before reboot (reboot unsuccessful):
gettid()                = 2523
recvmsg(12, {msg_name(0)=NULL, msg_iov(1)=[{"OK
613e6eb1d637e4965336676c54d95ac3\r\nAGREE_UNIX_FD\r\n", 256}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 52
sendmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\1\0\1\0\0\0\0\1\0\0\0m\0\0\0\1\1o\0\25\0\0\0/org/freedesktop/DBus\0\0\0\3\1s\0\5\0\0\0Hello\0\0\0\2\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0\6\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0",
128}], msg_controllen=0, msg_flags=0}, MSG_DONTWAIT|MSG_NOSIGNAL) = 128
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\2\1\1\v\0\0\0\1\0\0\0=\0\0\0\6\1s\0\6\0\0\0", 24}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 24
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{":1.844\0\0\5\1u\0\1\0\0\0\10\1g\0\1s\0\0\7\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0\6\0\0\0:1.844\0",
67}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 67
sendmsg(12, {msg_name(0)=NULL,
msg_iov(2)=[{"l\1\0\1\4\0\0\0\2\0\0\0\237\0\0\0\1\1o\0\31\0\0\0/org/freedesktop/machine1\0\0\0\0\0\0\0\3\1s\0\17\0\0\0GetMachineByPID\0\2\1s\0
\0\0\0org.freedesktop.machine1.Manager\0\0\0\0\0\0\0\0\6\1s\0\30\0\0\0org.freedesktop.machine1\0\0\0\0\0\0\0\0\10\1g\0\1u\0\0",
176}, {"\334\t\0\0", 4}], msg_controllen=0, msg_flags=0},
MSG_DONTWAIT|MSG_NOSIGNAL) = 180
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\4\1\1\v\0\0\0\2\0\0\0\215\0\0\0\1\1o\0\25\0\0\0", 24}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 24
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"/org/freedesktop/DBus\0\0\0\2\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0\3\1s\0\f\0\0\0NameAcquired\0\0\0\0\6\1s\0\6\0\0\0:1.844\0\0\10\1g\0\1s\0\0\7\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0\6\0\0\0:1.844\0",
147}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 147
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\2\1\1+\0\0\0\36\0\0\0/\0\0\0\5\1u\0\2\0\0\0", 24}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 24
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"\6\1s\0\6\0\0\0:1.844\0\0\10\1g\0\1o\0\0\7\1s\0\6\0\0\0:1.680\0\0&\0\0\0/org/freedesktop/machine1/machine/test\0",
83}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 83
sendmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\1\0\1\0\0\0\0\3\0\0\0\231\0\0\0\1\1o\0&\0\0\0/org/freedesktop/machine1/machine/test\0\0\3\1s\0\t\0\0\0Terminate\0\0\0\0\0\0\0\2\1s\0
\0\0\0org.freedesktop.machine1.Machine\0\0\0\0\0\0\0\0\6\1s\0\30\0\0\0org.freedesktop.machine1\0\0\0\0\0\0\0\0",
176}], msg_controllen=0, msg_flags=0}, MSG_DONTWAIT|MSG_NOSIGNAL) = 176
recvmsg(12, 0x7fffe15c73e0, MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = -1
EAGAIN (Resource temporarily unavailable)
ppoll([{fd=12, events=POLLIN}], 1, {24, 999700000}, NULL, 8) = 1 ([{fd=12,
revents=POLLIN}], left {24, 999697784})
--- SIGCONT {si_signo=SIGCONT, si_code=SI_USER, si_pid=1, si_uid=0} ---
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\2\1\1\0\0\0\0\"\0\0\0'\0\0\0\5\1u\0\3\0\0\0", 24}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 24
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"\6\1s\0\6\0\0\0:1.844\0\0\7\1s\0\6\0\0\0:1.680\0\0", 32}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 32
close(12)               = 0
kill(2524, SIGKILL)     = 0
waitid(P_PID, 2524, {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=2524,
si_uid=0, si_status=SIGHUP, si_utime=0, si_stime=0}, WEXITED, NULL) = 0
close(6)                = 0
close(7)                = 0
close(8)                = 0
close(10)               = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
sendmsg(6, {msg_name(21)={sa_family=AF_LOCAL, sun_path="/run/systemd/notify"},
msg_iov(1)=[{"STOPPING=1\nSTATUS=Terminating...", 32}], msg_controllen=0,
msg_flags=0}, MSG_NOSIGNAL) = 32
close(6)                = 0
open("/run/systemd/nspawn/propagate/test",
O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_NOFOLLOW|O_NOATIME|O_CLOEXEC) = 6
fstatfs(6, {f_type=0x1021994, f_bsize=4096, f_blocks=506452, f_bfree=506100,
f_bavail=506100, f_files=506452, f_ffree=505865, f_fsid={0, 0}, f_namelen=255,
f_frsize=4096}) = 0
fstat(6, {st_dev=makedev(0, 15), st_ino=55456, st_mode=S_IFDIR|0600,
st_nlink=2, st_uid=0, st_gid=0, st_blksize=4096, st_blocks=0, st_size=40,
st_atime=2015/02/10-12:20:42, st_mtime=2015/02/10-12:20:42,
st_ctime=2015/02/10-12:20:42}) = 0
fcntl(6, F_GETFL)       = 0x78800 (flags
O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_NOFOLLOW|O_NOATIME)
fcntl(6, F_SETFD, FD_CLOEXEC) = 0
getdents(6, {{d_ino=55456, d_off=1, d_reclen=24, d_name=".", d_type=DT_DIR}
{d_ino=23207, d_off=2, d_reclen=24, d_name="..", d_type=DT_DIR}}, 32768) = 48
getdents(6, {}, 32768)  = 0
close(6)                = 0
rmdir("/run/systemd/nspawn/propagate/test") = 0
unlink("/var/lib/container/.#test.lck") = 0
close(3)                = 0
unlink("/run/systemd/nspawn/locks/inode-2067:6432234") = 0
close(4)                = 0
close(5)                = 0
exit_group(133)         = ?
+++ exited with 133 +++

10 second sleep before reboot (reboot unsuccessful):
gettid()                = 2331
recvmsg(12, {msg_name(0)=NULL, msg_iov(1)=[{"OK
613e6eb1d637e4965336676c54d95ac3\r\nAGREE_UNIX_FD\r\n", 256}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 52
sendmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\1\0\1\0\0\0\0\1\0\0\0m\0\0\0\1\1o\0\25\0\0\0/org/freedesktop/DBus\0\0\0\3\1s\0\5\0\0\0Hello\0\0\0\2\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0\6\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0",
128}], msg_controllen=0, msg_flags=0}, MSG_DONTWAIT|MSG_NOSIGNAL) = 128
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\2\1\1\v\0\0\0\1\0\0\0=\0\0\0\6\1s\0\6\0\0\0", 24}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 24
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{":1.699\0\0\5\1u\0\1\0\0\0\10\1g\0\1s\0\0\7\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0\6\0\0\0:1.699\0",
67}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 67
sendmsg(12, {msg_name(0)=NULL,
msg_iov(2)=[{"l\1\0\1\4\0\0\0\2\0\0\0\237\0\0\0\1\1o\0\31\0\0\0/org/freedesktop/machine1\0\0\0\0\0\0\0\3\1s\0\17\0\0\0GetMachineByPID\0\2\1s\0
\0\0\0org.freedesktop.machine1.Manager\0\0\0\0\0\0\0\0\6\1s\0\30\0\0\0org.freedesktop.machine1\0\0\0\0\0\0\0\0\10\1g\0\1u\0\0",
176}, {"\34\t\0\0", 4}], msg_controllen=0, msg_flags=0},
MSG_DONTWAIT|MSG_NOSIGNAL) = 180
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\4\1\1\v\0\0\0\2\0\0\0\215\0\0\0\1\1o\0\25\0\0\0", 24}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 24
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"/org/freedesktop/DBus\0\0\0\2\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0\3\1s\0\f\0\0\0NameAcquired\0\0\0\0\6\1s\0\6\0\0\0:1.699\0\0\10\1g\0\1s\0\0\7\1s\0\24\0\0\0org.freedesktop.DBus\0\0\0\0\6\0\0\0:1.699\0",
147}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 147
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\2\1\1+\0\0\0\20\0\0\0/\0\0\0\5\1u\0\2\0\0\0", 24}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 24
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"\6\1s\0\6\0\0\0:1.699\0\0\10\1g\0\1o\0\0\7\1s\0\6\0\0\0:1.680\0\0&\0\0\0/org/freedesktop/machine1/machine/test\0",
83}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 83
sendmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\1\0\1\0\0\0\0\3\0\0\0\231\0\0\0\1\1o\0&\0\0\0/org/freedesktop/machine1/machine/test\0\0\3\1s\0\t\0\0\0Terminate\0\0\0\0\0\0\0\2\1s\0
\0\0\0org.freedesktop.machine1.Machine\0\0\0\0\0\0\0\0\6\1s\0\30\0\0\0org.freedesktop.machine1\0\0\0\0\0\0\0\0",
176}], msg_controllen=0, msg_flags=0}, MSG_DONTWAIT|MSG_NOSIGNAL) = 176
recvmsg(12, 0x7fff06bb2860, MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = -1
EAGAIN (Resource temporarily unavailable)
ppoll([{fd=12, events=POLLIN}], 1, {24, 999944000}, NULL, 8) = ? ERESTARTNOHAND
(To be restarted if no handler)
--- SIGCONT {si_signo=SIGCONT, si_code=SI_USER, si_pid=1, si_uid=0} ---
ppoll([{fd=12, events=POLLIN}], 1, {24, 998455394}, NULL, 8) = 1 ([{fd=12,
revents=POLLIN}], left {24, 997227737})
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"l\2\1\1\0\0\0\0\24\0\0\0'\0\0\0\5\1u\0\3\0\0\0", 24}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 24
recvmsg(12, {msg_name(0)=NULL,
msg_iov(1)=[{"\6\1s\0\6\0\0\0:1.699\0\0\7\1s\0\6\0\0\0:1.680\0\0", 32}],
msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC},
MSG_DONTWAIT|MSG_NOSIGNAL|MSG_CMSG_CLOEXEC) = 32
close(12)               = 0
kill(2332, SIGKILL)     = 0
waitid(P_PID, 2332, {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=2332,
si_uid=0, si_status=SIGHUP, si_utime=0, si_stime=0}, WEXITED, NULL) = 0
close(6)                = 0
close(7)                = 0
close(8)                = 0
close(10)               = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
sendmsg(6, {msg_name(21)={sa_family=AF_LOCAL, sun_path="/run/systemd/notify"},
msg_iov(1)=[{"STOPPING=1\nSTATUS=Terminating...", 32}], msg_controllen=0,
msg_flags=0}, MSG_NOSIGNAL) = 32
close(6)                = 0
open("/run/systemd/nspawn/propagate/test",
O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_NOFOLLOW|O_NOATIME|O_CLOEXEC) = 6
fstatfs(6, {f_type=0x1021994, f_bsize=4096, f_blocks=506452, f_bfree=506099,
f_bavail=506099, f_files=506452, f_ffree=505864, f_fsid={0, 0}, f_namelen=255,
f_frsize=4096}) = 0
fstat(6, {st_dev=makedev(0, 15), st_ino=50469, st_mode=S_IFDIR|0600,
st_nlink=2, st_uid=0, st_gid=0, st_blksize=4096, st_blocks=0, st_size=40,
st_atime=2015/02/10-12:20:05, st_mtime=2015/02/10-12:20:05,
st_ctime=2015/02/10-12:20:05}) = 0
fcntl(6, F_GETFL)       = 0x78800 (flags
O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_NOFOLLOW|O_NOATIME)
fcntl(6, F_SETFD, FD_CLOEXEC) = 0
getdents(6, {{d_ino=50469, d_off=1, d_reclen=24, d_name=".", d_type=DT_DIR}
{d_ino=23207, d_off=2, d_reclen=24, d_name="..", d_type=DT_DIR}}, 32768) = 48
getdents(6, {}, 32768)  = 0
close(6)                = 0
rmdir("/run/systemd/nspawn/propagate/test") = 0
unlink("/var/lib/container/.#test.lck") = 0
close(3)                = 0
unlink("/run/systemd/nspawn/locks/inode-2067:6432234") = 0
close(4)                = 0
close(5)                = 0
exit_group(133)         = ?
+++ exited with 133 +++

-- 
You are receiving this mail because:
You are the QA Contact for the bug.
You are the assignee for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freedesktop.org/archives/systemd-bugs/attachments/20150210/c723f12f/attachment-0001.html>