Bug#1023484: libsystemd0: upgrade from 251.6-1 to 252-2 fails with timed out (service_start_timeout=25000ms)
Martin-Éric Racine
martin-eric.racine at iki.fi
Sat Nov 5 14:23:34 GMT 2022
On Sat, Nov 5, 2022 at 4:11 PM Martin-Éric Racine
<martin-eric.racine at iki.fi> wrote:
>
> On Sat, Nov 5, 2022 at 4:02 PM Michael Biebl <biebl at debian.org> wrote:
> >
> > Am 05.11.22 um 14:54 schrieb Martin-Éric Racine:
> > > On Sat, Nov 5, 2022 at 3:53 PM Michael Biebl <biebl at debian.org> wrote:
> > >>
> > >> Control: tags -1 + moreinfo
> > >>
> > >> Am 05.11.22 um 10:25 schrieb Martin-Éric Racine:
> > >>>
> > >>> Message from syslogd@[localhost] at Nov 5 11:09:11 ...
> > >>> systemd[1]: Caught <ABRT> from our own process.
> > >>>
> > >>
> > >>
> > >> Is this the same Geode system as in
> > >> https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=944645 ?
> > >>
> > >> If so, I think it's essentially the same issue.
> > >
> > > It probably is a return of the same issue.
> >
> > Is it the same Geode system?
>
> Yup.
>
> Martin-Éric
Here's what I get with the dbgsym packages installed:
$ sudo coredumpctl debug 1217
Failed to check if any systemd-coredump at .service units are running:
Yhteys aikakatkaistu
PID: 1217 (systemd)
UID: 0 (root)
GID: 0 (root)
Signal: 6 (ABRT)
Timestamp: Sat 2022-11-05 16:18:35 EET (1min 21s ago)
Command Line: /sbin/init noquiet nosplash
Executable: /usr/lib/systemd/systemd
Control Group: /init.scope
Unit: init.scope
Slice: -.slice
Boot ID: 523225b0d5a24a65a1d62f1300f6c7eb
Machine ID: 1063a9d1fb9df6e371ea9f94491345ed
Hostname: geode
Storage:
/var/lib/systemd/coredump/core.systemd.0.523225b0d5a24a65a1d62f1300f6c7eb.1217.1667657915000000.zst
(present)
Size on Disk: 531.6K
Package: systemd/252-2
build-id: e37b62ee63de4c1b55753371b32724427a4800ad
Message: Process 1217 (systemd) of user 0 dumped core.
Module libsystemd-shared-252.so from deb systemd-252-2.i386
Module libsystemd-core-252.so from deb systemd-252-2.i386
Module systemd from deb systemd-252-2.i386
Stack trace of thread 1217:
#0 0x00000000b7f23559 __kernel_vsyscall
(linux-gate.so.1 + 0x559)
#1 0x00000000b77d6de6 __kill (libc.so.6 + 0x36de6)
#2 0x000000000045b0c0 crash (systemd + 0xd0c0)
#3 0x00000000b7f23570 __kernel_rt_sigreturn
(linux-gate.so.1 + 0x570)
#4 0x00000000b7f23559 __kernel_vsyscall
(linux-gate.so.1 + 0x559)
#5 0x00000000b7825ec7 __pthread_kill_implementation
(libc.so.6 + 0x85ec7)
#6 0x00000000b77d6b41 __GI_raise (libc.so.6 + 0x36b41)
#7 0x00000000b77c0262 __GI_abort (libc.so.6 + 0x20262)
#8 0x00000000b7a24ffb log_assert_failed
(libsystemd-shared-252.so + 0x4fffb)
#9 0x00000000b7deeacf manager_unref_uid_internal
(libsystemd-core-252.so + 0xa8acf)
#10 0x00000000b7e47eee unit_unref_uid_internal
(libsystemd-core-252.so + 0x101eee)
#11 0x00000000b7e3bff0 unit_free
(libsystemd-core-252.so + 0xf5ff0)
#12 0x00000000b7df050c manager_dispatch_cleanup_queue
(libsystemd-core-252.so + 0xaa50c)
#13 0x00000000b7df5e81 manager_loop
(libsystemd-core-252.so + 0xafe81)
#14 0x00000000004540e0 main (systemd + 0x60e0)
#15 0x00000000b77c13b5 __libc_start_call_main
(libc.so.6 + 0x213b5)
#16 0x00000000b77c147f __libc_start_main_impl
(libc.so.6 + 0x2147f)
#17 0x0000000000456507 _start (systemd + 0x8507)
ELF object binary architecture: Intel 80386
GNU gdb (Debian 12.1-3) 12.1
Copyright (C) 2022 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 "i686-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<https://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/>.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /usr/lib/systemd/systemd...
Reading symbols from
/usr/lib/debug/.build-id/e3/7b62ee63de4c1b55753371b32724427a4800ad.debug...
[New LWP 1217]
[New LWP 1]
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/i386-linux-gnu/libthread_db.so.1".
Core was generated by `/sbin/init noquiet nosplash'.
Program terminated with signal SIGABRT, Aborted.
#0 0xb7f23559 in __kernel_vsyscall ()
[Current thread is 1 (LWP 1217)]
################################
handle SIG33 pass nostop noprint
##################
set pagination off
##############
backtrace full
#0 0xb7f23559 in __kernel_vsyscall ()
No symbol table info available.
#1 0xb77d6de6 in __GI_kill () at ../sysdeps/unix/syscall-template.S:120
No locals.
#2 0x0045b0c0 in crash (sig=6, siginfo=0xbfefc67c,
context=0xbfefc6fc) at ../src/core/crash-handler.c:83
sa = {__sigaction_handler = {sa_handler = 0x0, sa_sigaction =
0x0}, sa_mask = {__val = {0 <repeats 32 times>}}, sa_flags = 0,
sa_restorer = 0x0}
pid = <optimized out>
__func__ = "crash"
__PRETTY_FUNCTION__ = "crash"
#3 <signal handler called>
No symbol table info available.
#4 0xb7f23559 in __kernel_vsyscall ()
No symbol table info available.
#5 0xb7825ec7 in __pthread_kill_implementation
(threadid=threadid at entry=3074771072, signo=signo at entry=6,
no_tid=no_tid at entry=0) at ./nptl/pthread_kill.c:43
resultvar = <optimized out>
tid = 1
ret = <optimized out>
pd = 0xb7454880
old_mask = {__val = {3078795986, 0, 0, 0, 0, 0, 0, 0,
3887868160, 0, 0, 3081948041, 3084128252, 3084143648, 3084143648,
3084128252, 3081996449, 4269, 3085451708, 0, 0, 0, 0, 3084143648,
4222451713, 3084143648, 0, 3084143648, 3082913220, 3084143749,
3085406354, 3085451708}}
ret = <optimized out>
#6 0xb7825f3b in __pthread_kill_internal (signo=6,
threadid=3074771072) at ./nptl/pthread_kill.c:78
No locals.
#7 0xb77d6b41 in __GI_raise (sig=6) at ../sysdeps/posix/raise.c:26
ret = <optimized out>
#8 0xb77c0262 in __GI_abort () at ./stdlib/abort.c:79
save_stage = 1
act = {__sigaction_handler = {sa_handler = 0x2, sa_sigaction =
0x2}, sa_mask = {__val = {3085406354, 3084143648, 3079452832,
3084143648, 2048, 3083140548, 3887868160, 2, 0, 3220163040,
3084128252, 2, 3085406354, 3084143648, 3081999625, 26, 0, 3085406354,
4269, 3085451708, 0, 0, 0, 0, 3084143648, 17423180, 4294939140,
17423156, 1, 3081999549, 3084128252, 4294948095}}, sa_flags =
17423152, sa_restorer = 0x0}
sigs = {__val = {32, 0, 144, 3078828315, 0, 3080460224, 0,
18416928, 3080452192, 0, 0, 7, 3130306989, 18376064, 3220163016,
3082412065, 18376064, 18416928, 3081997621, 3887868160, 18416980, 0,
0, 3080460224, 3220163012, 3220163008, 3084128252, 3887868160,
62956620, 3575652474, 3078716697, 3084128252}}
#9 0xb7a24ffb in log_assert_failed (text=0xb7e825cf "n > 0",
file=0xb7e79092 "src/core/manager.c", line=4269, func=0xb7e841bc
<__PRETTY_FUNCTION__.15> "manager_unref_uid_internal") at
../src/basic/log.c:853
No locals.
#10 0xb7deeacf in manager_unref_uid_internal (uid_refs=0x109db30,
uid=4294948095, destroy_now=<optimized out>, _clean_ipc=0xb7a51bc0
<clean_ipc_by_gid>) at ../src/core/manager.c:4269
c = <optimized out>
n = <optimized out>
__PRETTY_FUNCTION__ = "manager_unref_uid_internal"
__func__ = "manager_unref_uid_internal"
#11 0xb7e47eee in unit_unref_uid_internal (u=0x105f560,
ref_uid=0x105f738, destroy_now=<optimized out>,
_manager_unref_uid=0xb7deeb00 <manager_unref_gid>) at
../src/core/unit.c:4895
__PRETTY_FUNCTION__ = "unit_unref_uid_internal"
#12 0xb7e3bff0 in unit_free (u=<optimized out>) at ../src/core/unit.c:735
slice = <optimized out>
t = 0x0
__PRETTY_FUNCTION__ = "unit_free"
#13 0xb7df050c in manager_dispatch_cleanup_queue (m=m at entry=0x104dc90)
at ../src/core/manager.c:1193
u = <optimized out>
n = 64
__PRETTY_FUNCTION__ = "manager_dispatch_cleanup_queue"
#14 0xb7df5e81 in manager_loop (m=0x104dc90) at ../src/core/manager.c:3043
rl = {interval = 1000000, burst = 50000, num = 2, begin = 1450870135}
r = <optimized out>
__PRETTY_FUNCTION__ = "manager_loop"
__func__ = "manager_loop"
#15 0x004540e0 in invoke_main_loop (ret_error_message=0xbfefcc0c,
ret_switch_root_init=<synthetic pointer>,
ret_switch_root_dir=<synthetic pointer>, ret_fds=0xbfefcc04,
ret_shutdown_verb=<synthetic pointer>, ret_retval=<synthetic pointer>,
saved_rlimit_memlock=0xbfefcc20, saved_rlimit_nofile=0xbfefcc30,
m=0x104dc90) at ../src/core/main.c:1898
objective = <optimized out>
table = <optimized out>
_level = <optimized out>
_e = <optimized out>
r = <optimized out>
r = <optimized out>
__PRETTY_FUNCTION__ = <optimized out>
__func__ = <optimized out>
objective = <optimized out>
_error = <optimized out>
_level = <optimized out>
_e = <optimized out>
saved_log_target = <optimized out>
saved_log_level = <optimized out>
_level = <optimized out>
_e = <optimized out>
_level = <optimized out>
_e = <optimized out>
_level = <optimized out>
_e = <optimized out>
_pptr_ = <optimized out>
_ptr_ = <optimized out>
_pptr_ = <optimized out>
_ptr_ = <optimized out>
_level = <optimized out>
_e = <optimized out>
table = <optimized out>
_level = <optimized out>
_e = <optimized out>
#16 main (argc=3, argv=0xbfefcfb4) at ../src/core/main.c:2981
initrd_timestamp = {realtime = 0, monotonic = 0}
userspace_timestamp = {realtime = 1667656528473402, monotonic
= 53143060}
kernel_timestamp = {realtime = 1667656475330341, monotonic = 0}
security_start_timestamp = {realtime = 1667656528600446,
monotonic = 53270105}
security_finish_timestamp = {realtime = 1667656528650576,
monotonic = 53320235}
saved_rlimit_nofile = {rlim_cur = 1024, rlim_max = 4096}
saved_rlimit_memlock = {rlim_cur = 8388608, rlim_max = 8388608}
skip_setup = <optimized out>
loaded_policy = false
queue_default_job = <optimized out>
first_boot = false
switch_root_dir = 0x0
switch_root_init = 0x0
before_startup = <optimized out>
after_startup = 62348387
shutdown_verb = 0x0
error_message = 0x0
r = <optimized out>
retval = 1
m = 0x104dc90
fds = 0x0
__PRETTY_FUNCTION__ = "main"
systemd = "systemd"
__func__ = "main"
_found = <optimized out>
__assert_in_set = <optimized out>
##############
info registers
eax 0x0 0
ecx 0x6 6
edx 0x462b70 4598640
ebx 0x4c1 1217
esp 0xbfefc4fc 0xbfefc4fc
ebp 0x6 0x6
esi 0x0 0
edi 0xbfefc64c -1074805172
eip 0xb7f23559 0xb7f23559 <__kernel_vsyscall+9>
eflags 0x200207 [ CF PF IF ID ]
cs 0x73 115
ss 0x7b 123
ds 0x7b 123
es 0x7b 123
fs 0x0 0
gs 0x33 51
###############################
thread apply all backtrace full
Thread 2 (Thread 0xb7454880 (LWP 1)):
warning: Couldn't find general-purpose registers in core file.
#0 <unavailable> in ?? ()
PC unavailable, cannot determine locals.
Backtrace stopped: not enough registers or memory available to unwind further
Thread 1 (LWP 1217):
#0 0xb7f23559 in __kernel_vsyscall ()
No symbol table info available.
#1 0xb77d6de6 in __GI_kill () at ../sysdeps/unix/syscall-template.S:120
No locals.
#2 0x0045b0c0 in crash (sig=6, siginfo=0xbfefc67c,
context=0xbfefc6fc) at ../src/core/crash-handler.c:83
sa = {__sigaction_handler = {sa_handler = 0x0, sa_sigaction =
0x0}, sa_mask = {__val = {0 <repeats 32 times>}}, sa_flags = 0,
sa_restorer = 0x0}
pid = <optimized out>
__func__ = "crash"
__PRETTY_FUNCTION__ = "crash"
#3 <signal handler called>
No symbol table info available.
#4 0xb7f23559 in __kernel_vsyscall ()
No symbol table info available.
#5 0xb7825ec7 in __pthread_kill_implementation
(threadid=threadid at entry=3074771072, signo=signo at entry=6,
no_tid=no_tid at entry=0) at ./nptl/pthread_kill.c:43
resultvar = <optimized out>
tid = 1
ret = <optimized out>
pd = 0xb7454880
old_mask = {__val = {3078795986, 0, 0, 0, 0, 0, 0, 0,
3887868160, 0, 0, 3081948041, 3084128252, 3084143648, 3084143648,
3084128252, 3081996449, 4269, 3085451708, 0, 0, 0, 0, 3084143648,
4222451713, 3084143648, 0, 3084143648, 3082913220, 3084143749,
3085406354, 3085451708}}
ret = <optimized out>
#6 0xb7825f3b in __pthread_kill_internal (signo=6,
threadid=3074771072) at ./nptl/pthread_kill.c:78
No locals.
#7 0xb77d6b41 in __GI_raise (sig=6) at ../sysdeps/posix/raise.c:26
ret = <optimized out>
#8 0xb77c0262 in __GI_abort () at ./stdlib/abort.c:79
save_stage = 1
act = {__sigaction_handler = {sa_handler = 0x2, sa_sigaction =
0x2}, sa_mask = {__val = {3085406354, 3084143648, 3079452832,
3084143648, 2048, 3083140548, 3887868160, 2, 0, 3220163040,
3084128252, 2, 3085406354, 3084143648, 3081999625, 26, 0, 3085406354,
4269, 3085451708, 0, 0, 0, 0, 3084143648, 17423180, 4294939140,
17423156, 1, 3081999549, 3084128252, 4294948095}}, sa_flags =
17423152, sa_restorer = 0x0}
sigs = {__val = {32, 0, 144, 3078828315, 0, 3080460224, 0,
18416928, 3080452192, 0, 0, 7, 3130306989, 18376064, 3220163016,
3082412065, 18376064, 18416928, 3081997621, 3887868160, 18416980, 0,
0, 3080460224, 3220163012, 3220163008, 3084128252, 3887868160,
62956620, 3575652474, 3078716697, 3084128252}}
#9 0xb7a24ffb in log_assert_failed (text=0xb7e825cf "n > 0",
file=0xb7e79092 "src/core/manager.c", line=4269, func=0xb7e841bc
<__PRETTY_FUNCTION__.15> "manager_unref_uid_internal") at
../src/basic/log.c:853
No locals.
#10 0xb7deeacf in manager_unref_uid_internal (uid_refs=0x109db30,
uid=4294948095, destroy_now=<optimized out>, _clean_ipc=0xb7a51bc0
<clean_ipc_by_gid>) at ../src/core/manager.c:4269
c = <optimized out>
n = <optimized out>
__PRETTY_FUNCTION__ = "manager_unref_uid_internal"
__func__ = "manager_unref_uid_internal"
#11 0xb7e47eee in unit_unref_uid_internal (u=0x105f560,
ref_uid=0x105f738, destroy_now=<optimized out>,
_manager_unref_uid=0xb7deeb00 <manager_unref_gid>) at
../src/core/unit.c:4895
__PRETTY_FUNCTION__ = "unit_unref_uid_internal"
#12 0xb7e3bff0 in unit_free (u=<optimized out>) at ../src/core/unit.c:735
slice = <optimized out>
t = 0x0
__PRETTY_FUNCTION__ = "unit_free"
#13 0xb7df050c in manager_dispatch_cleanup_queue (m=m at entry=0x104dc90)
at ../src/core/manager.c:1193
u = <optimized out>
n = 64
__PRETTY_FUNCTION__ = "manager_dispatch_cleanup_queue"
#14 0xb7df5e81 in manager_loop (m=0x104dc90) at ../src/core/manager.c:3043
rl = {interval = 1000000, burst = 50000, num = 2, begin = 1450870135}
r = <optimized out>
__PRETTY_FUNCTION__ = "manager_loop"
__func__ = "manager_loop"
#15 0x004540e0 in invoke_main_loop (ret_error_message=0xbfefcc0c,
ret_switch_root_init=<synthetic pointer>,
ret_switch_root_dir=<synthetic pointer>, ret_fds=0xbfefcc04,
ret_shutdown_verb=<synthetic pointer>, ret_retval=<synthetic pointer>,
saved_rlimit_memlock=0xbfefcc20, saved_rlimit_nofile=0xbfefcc30,
m=0x104dc90) at ../src/core/main.c:1898
objective = <optimized out>
table = <optimized out>
_level = <optimized out>
_e = <optimized out>
r = <optimized out>
r = <optimized out>
__PRETTY_FUNCTION__ = <optimized out>
__func__ = <optimized out>
objective = <optimized out>
_error = <optimized out>
_level = <optimized out>
_e = <optimized out>
saved_log_target = <optimized out>
saved_log_level = <optimized out>
_level = <optimized out>
_e = <optimized out>
_level = <optimized out>
_e = <optimized out>
_level = <optimized out>
_e = <optimized out>
_pptr_ = <optimized out>
_ptr_ = <optimized out>
_pptr_ = <optimized out>
_ptr_ = <optimized out>
_level = <optimized out>
_e = <optimized out>
table = <optimized out>
_level = <optimized out>
_e = <optimized out>
#16 main (argc=3, argv=0xbfefcfb4) at ../src/core/main.c:2981
initrd_timestamp = {realtime = 0, monotonic = 0}
userspace_timestamp = {realtime = 1667656528473402, monotonic
= 53143060}
kernel_timestamp = {realtime = 1667656475330341, monotonic = 0}
security_start_timestamp = {realtime = 1667656528600446,
monotonic = 53270105}
security_finish_timestamp = {realtime = 1667656528650576,
monotonic = 53320235}
saved_rlimit_nofile = {rlim_cur = 1024, rlim_max = 4096}
saved_rlimit_memlock = {rlim_cur = 8388608, rlim_max = 8388608}
skip_setup = <optimized out>
loaded_policy = false
queue_default_job = <optimized out>
first_boot = false
switch_root_dir = 0x0
switch_root_init = 0x0
before_startup = <optimized out>
after_startup = 62348387
shutdown_verb = 0x0
error_message = 0x0
r = <optimized out>
retval = 1
m = 0x104dc90
fds = 0x0
__PRETTY_FUNCTION__ = "main"
systemd = "systemd"
__func__ = "main"
_found = <optimized out>
__assert_in_set = <optimized out>
#########
x/16i $pc
=> 0xb7f23559 <__kernel_vsyscall+9>: pop %ebp
0xb7f2355a <__kernel_vsyscall+10>: pop %edx
0xb7f2355b <__kernel_vsyscall+11>: pop %ecx
0xb7f2355c <__kernel_vsyscall+12>: ret
0xb7f2355d: nop
0xb7f2355e: nop
0xb7f2355f: nop
0xb7f23560: nop
0xb7f23561: lea 0x0(%esi),%esi
0xb7f23564 <__kernel_sigreturn>: pop %eax
0xb7f23565 <__kernel_sigreturn+1>: mov $0x77,%eax
0xb7f2356a <__kernel_sigreturn+6>: int $0x80
0xb7f2356c <__kernel_sigreturn+8>: nop
0xb7f2356d: lea 0x0(%esi),%esi
0xb7f23570 <__kernel_rt_sigreturn>: mov $0xad,%eax
0xb7f23575 <__kernel_rt_sigreturn+5>: int $0x80
##########################
thread apply all backtrace
Thread 2 (Thread 0xb7454880 (LWP 1)):
#0 <unavailable> in ?? ()
Backtrace stopped: not enough registers or memory available to unwind further
Thread 1 (LWP 1217):
#0 0xb7f23559 in __kernel_vsyscall ()
#1 0xb77d6de6 in __GI_kill () at ../sysdeps/unix/syscall-template.S:120
#2 0x0045b0c0 in crash (sig=6, siginfo=0xbfefc67c,
context=0xbfefc6fc) at ../src/core/crash-handler.c:83
#3 <signal handler called>
#4 0xb7f23559 in __kernel_vsyscall ()
#5 0xb7825ec7 in __pthread_kill_implementation
(threadid=threadid at entry=3074771072, signo=signo at entry=6,
no_tid=no_tid at entry=0) at ./nptl/pthread_kill.c:43
#6 0xb7825f3b in __pthread_kill_internal (signo=6,
threadid=3074771072) at ./nptl/pthread_kill.c:78
#7 0xb77d6b41 in __GI_raise (sig=6) at ../sysdeps/posix/raise.c:26
#8 0xb77c0262 in __GI_abort () at ./stdlib/abort.c:79
#9 0xb7a24ffb in log_assert_failed (text=0xb7e825cf "n > 0",
file=0xb7e79092 "src/core/manager.c", line=4269, func=0xb7e841bc
<__PRETTY_FUNCTION__.15> "manager_unref_uid_internal") at
../src/basic/log.c:853
#10 0xb7deeacf in manager_unref_uid_internal (uid_refs=0x109db30,
uid=4294948095, destroy_now=<optimized out>, _clean_ipc=0xb7a51bc0
<clean_ipc_by_gid>) at ../src/core/manager.c:4269
#11 0xb7e47eee in unit_unref_uid_internal (u=0x105f560,
ref_uid=0x105f738, destroy_now=<optimized out>,
_manager_unref_uid=0xb7deeb00 <manager_unref_gid>) at
../src/core/unit.c:4895
#12 0xb7e3bff0 in unit_free (u=<optimized out>) at ../src/core/unit.c:735
#13 0xb7df050c in manager_dispatch_cleanup_queue (m=m at entry=0x104dc90)
at ../src/core/manager.c:1193
#14 0xb7df5e81 in manager_loop (m=0x104dc90) at ../src/core/manager.c:3043
#15 0x004540e0 in invoke_main_loop (ret_error_message=0xbfefcc0c,
ret_switch_root_init=<synthetic pointer>,
ret_switch_root_dir=<synthetic pointer>, ret_fds=0xbfefcc04,
ret_shutdown_verb=<synthetic pointer>, ret_retval=<synthetic pointer>,
saved_rlimit_memlock=0xbfefcc20, saved_rlimit_nofile=0xbfefcc30,
m=0x104dc90) at ../src/core/main.c:1898
#16 main (argc=3, argv=0xbfefcfb4) at ../src/core/main.c:2981
(gdb) quit
Martin-Éric
More information about the Pkg-systemd-maintainers
mailing list