Bug 2013266
| Summary: | [NMCI] [abrt] [faf] NetworkManager: _g_log_abort(): vlan_ipv4_ipv6_restart_persistence killed by 5 | ||
|---|---|---|---|
| Product: | Red Hat Enterprise Linux 8 | Reporter: | Vladimir Benes <vbenes> |
| Component: | NetworkManager | Assignee: | Thomas Haller <thaller> |
| Status: | CLOSED ERRATA | QA Contact: | Vladimir Benes <vbenes> |
| Severity: | unspecified | Docs Contact: | |
| Priority: | high | ||
| Version: | 8.4 | CC: | bgalvani, djasa, ferferna, fge, lrintel, rkhan, sukulkar, thaller, till, wenliang |
| Target Milestone: | rc | Keywords: | Triaged |
| Target Release: | --- | Flags: | pm-rhel:
mirror+
|
| Hardware: | Unspecified | ||
| OS: | Unspecified | ||
| URL: | https://faf.lab.eng.brq.redhat.com/faf/reports/bthash/507b8e2fecae97e9f83d7e2830cc1bb2182541c0/ | ||
| Whiteboard: | |||
| Fixed In Version: | NetworkManager-1.36.0-0.4.el8 | Doc Type: | No Doc Update |
| Doc Text: | Story Points: | --- | |
| Clone Of: | Environment: | ||
| Last Closed: | 2022-05-10 14:54:14 UTC | Type: | Bug |
| Regression: | --- | Mount Type: | --- |
| Documentation: | --- | CRM: | |
| Verified Versions: | Category: | --- | |
| oVirt Team: | --- | RHEL 7.3 requirements from Atomic Host: | |
| Cloudforms Team: | --- | Target Upstream Version: | |
| Embargoed: | |||
|
Description
Vladimir Benes
2021-10-12 13:32:22 UTC
backtrace:
[New LWP 842655]
[New LWP 842656]
[New LWP 842657]
[New LWP 842662]
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib64/libthread_db.so.1".
Core was generated by `/usr/sbin/NetworkManager --no-daemon'.
Program terminated with signal SIGTRAP, Trace/breakpoint trap.
#0 _g_log_abort (breakpoint=1) at gmessages.c:580
580 G_BREAKPOINT ();
[Current thread is 1 (Thread 0x7f422fee03c0 (LWP 842655))]
Thread 1 (Thread 0x7f422fee03c0 (LWP 842655)):
#0 0x00007f422df56895 in _g_log_abort (breakpoint=1) at gmessages.c:580
debugger_present = 1
#1 0x00007f422df57b98 in g_logv (log_domain=0x56102e4d17e0 "nm", log_level=G_LOG_LEVEL_CRITICAL, format=<optimized out>, args=args@entry=0x7ffcf1062890) at gmessages.c:1391
domain = 0x0
data = 0x0
depth = 1
log_func = 0x7f422df57890 <g_log_default_handler>
domain_fatal_mask = <optimized out>
masquerade_fatal = 0
test_level = 10
was_fatal = <optimized out>
was_recursion = <optimized out>
msg = 0x56102ffd2230 "nm_ip6_config_reset_addresses_ndisc: assertion 'priv->ifindex > 0' failed"
msg_alloc = 0x56102ffd2230 "nm_ip6_config_reset_addresses_ndisc: assertion 'priv->ifindex > 0' failed"
i = 3
#2 0x00007f422df57d63 in g_log (log_domain=log_domain@entry=0x56102e4d17e0 "nm", log_level=log_level@entry=G_LOG_LEVEL_CRITICAL, format=format@entry=0x7f422dfa4620 "%s: assertion '%s' failed") at gmessages.c:1432
args = {{gp_offset = 40, fp_offset = 48, overflow_arg_area = 0x7ffcf1062970, reg_save_area = 0x7ffcf10628b0}}
#3 0x00007f422df5859d in g_return_if_fail_warning (log_domain=log_domain@entry=0x56102e4d17e0 "nm", pretty_function=pretty_function@entry=0x56102e429840 <__func__.43634> "nm_ip6_config_reset_addresses_ndisc", expression=expression@entry=0x56102e428b20 "priv->ifindex > 0") at gmessages.c:2809
#4 0x000056102e1a479a in nm_ip6_config_reset_addresses_ndisc (self=0x56102fea2d40 [NMIP6Config], addresses=0x561030017590, addresses_n=1, plen=plen@entry=64 '@', ifa_flags=ifa_flags@entry=768) at src/core/nm-ip6-config.c:1468
priv = 0x56102fea2da0
i = <optimized out>
changed = 0
base_time_sec = <optimized out>
__func__ = "nm_ip6_config_reset_addresses_ndisc"
#5 0x000056102e312dd0 in ndisc_config_changed (ndisc=<optimized out>, rdata=0x56102ffa84c0, changed_int=159, self=0x561030000730 [NMDeviceOvsInterface]) at src/core/devices/nm-device.c:10838
ifa_flags = 768
changed = (NM_NDISC_CONFIG_DHCP_LEVEL | NM_NDISC_CONFIG_GATEWAYS | NM_NDISC_CONFIG_ADDRESSES | NM_NDISC_CONFIG_ROUTES | NM_NDISC_CONFIG_DNS_SERVERS | NM_NDISC_CONFIG_MTU)
priv = 0x561030000250
__func__ = "ndisc_config_changed"
i = <optimized out>
#6 0x00007f4228ecc09e in ffi_call_unix64 () at ../src/x86/unix64.S:76
#7 0x00007f4228ecba4f in ffi_call (cif=cif@entry=0x7ffcf1062d20, fn=fn@entry=0x56102e3129b0 <ndisc_config_changed>, rvalue=<optimized out>, avalue=avalue@entry=0x7ffcf1062c30) at ../src/x86/ffi64.c:525
classes = {X86_64_INTEGER_CLASS, 1664693858, 804494576, 22032}
stack = <optimized out>
argp = <optimized out>
arg_types = <optimized out>
gprcount = <optimized out>
ssecount = <optimized out>
ngpr = 1
nsse = 0
i = <optimized out>
avn = <optimized out>
ret_in_memory = <optimized out>
reg_args = <optimized out>
#8 0x00007f422e431386 in g_cclosure_marshal_generic_va (closure=<optimized out>, return_value=<optimized out>, instance=<optimized out>, args_list=<optimized out>, marshal_data=<optimized out>, n_params=<optimized out>, param_types=<optimized out>) at gclosure.c:1604
rtype = <optimized out>
rvalue = 0x7ffcf1062c90
n_args = <optimized out>
atypes = <optimized out>
args = <optimized out>
storage = 0x7ffcf1062c10
i = <optimized out>
cif = {abi = FFI_UNIX64, nargs = 4, arg_types = 0x7ffcf1062c60, rtype = 0x7f4228ecc490 <ffi_type_void>, bytes = 0, flags = 0}
cc = <optimized out>
enum_tmpval = <optimized out>
tmpval_used = 0
args_copy = {{gp_offset = 40, fp_offset = 48, overflow_arg_area = 0x7ffcf10630b0, reg_save_area = 0x7ffcf1062ff0}}
#9 0x00007f422e430616 in _g_closure_invoke_va (closure=0x56102fe76ef0, return_value=0x0, instance=0x56102ffa85c0, args=0x7ffcf1062fd0, n_params=2, param_types=0x56102ff39cf0) at gclosure.c:867
marshal = 0x7f422e430f40 <g_cclosure_marshal_generic_va>
marshal_data = 0x0
in_marshal = 0
real_closure = 0x56102fe76ed0
__func__ = "_g_closure_invoke_va"
#10 0x00007f422e44ca9c in g_signal_emit_valist (instance=0x56102ffa85c0, signal_id=<optimized out>, detail=0, var_args=var_args@entry=0x7ffcf1062fd0) at gsignal.c:3301
return_accu = 0x0
accu = {g_type = 0, data = {{v_int = 0, v_uint = 0, v_long = 0, v_ulong = 0, v_int64 = 0, v_uint64 = 0, v_float = 0, v_double = 0, v_pointer = 0x0}, {v_int = 0, v_uint = 0, v_long = 0, v_ulong = 0, v_int64 = 0, v_uint64 = 0, v_float = 0, v_double = 0, v_pointer = 0x0}}}
accumulator = 0x0
emission = {next = 0x0, instance = 0x56102ffa85c0, ihint = {signal_id = 77, detail = 0, run_type = G_SIGNAL_RUN_FIRST}, state = EMISSION_RUN, chain_type = 94627523986320}
signal_id = 77
instance_type = 94627523986320
emission_return = {g_type = 0, data = {{v_int = 0, v_uint = 0, v_long = 0, v_ulong = 0, v_int64 = 0, v_uint64 = 0, v_float = 0, v_double = 0, v_pointer = 0x0}, {v_int = 0, v_uint = 0, v_long = 0, v_ulong = 0, v_int64 = 0, v_uint64 = 0, v_float = 0, v_double = 0, v_pointer = 0x0}}}
rtype = 4
static_scope = 0
fastpath_handler = <optimized out>
closure = <optimized out>
run_type = <optimized out>
hlist = <optimized out>
l = <optimized out>
fastpath = 1
instance_and_params = <optimized out>
signal_return_type = <optimized out>
param_values = <optimized out>
node = <optimized out>
i = <optimized out>
n_params = <optimized out>
__func__ = "g_signal_emit_valist"
#11 0x00007f422e44d093 in g_signal_emit (instance=<optimized out>, signal_id=<optimized out>, detail=<optimized out>) at gsignal.c:3448
var_args = {{gp_offset = 24, fp_offset = 48, overflow_arg_area = 0x7ffcf10630b0, reg_save_area = 0x7ffcf1062ff0}}
#12 0x000056102e3bf06b in check_timestamps (ndisc=ndisc@entry=0x56102ffa85c0 [NMLndpNDisc], now_msec=now_msec@entry=13850, changed=changed@entry=(NM_NDISC_CONFIG_DHCP_LEVEL | NM_NDISC_CONFIG_GATEWAYS | NM_NDISC_CONFIG_ADDRESSES | NM_NDISC_CONFIG_ROUTES | NM_NDISC_CONFIG_DNS_SERVERS | NM_NDISC_CONFIG_MTU)) at src/core/ndisc/nm-ndisc.c:1539
priv = <optimized out>
__func__ = "check_timestamps"
next_msec = <optimized out>
#13 0x000056102e3c08f0 in nm_ndisc_ra_received (ndisc=ndisc@entry=0x56102ffa85c0 [NMLndpNDisc], now_msec=now_msec@entry=13850, changed=changed@entry=(NM_NDISC_CONFIG_DHCP_LEVEL | NM_NDISC_CONFIG_GATEWAYS | NM_NDISC_CONFIG_ADDRESSES | NM_NDISC_CONFIG_ROUTES | NM_NDISC_CONFIG_DNS_SERVERS | NM_NDISC_CONFIG_MTU)) at src/core/ndisc/nm-ndisc.c:1556
priv = <optimized out>
__func__ = "nm_ndisc_ra_received"
#14 0x000056102e3b8d70 in receive_ra (ndp=<optimized out>, msg=0x56102ff2c800, user_data=<optimized out>) at src/core/ndisc/nm-lndp-ndisc.c:333
ndisc = <optimized out>
rdata = 0x56102ffa84c0
changed = (NM_NDISC_CONFIG_DHCP_LEVEL | NM_NDISC_CONFIG_GATEWAYS | NM_NDISC_CONFIG_ADDRESSES | NM_NDISC_CONFIG_ROUTES | NM_NDISC_CONFIG_DNS_SERVERS | NM_NDISC_CONFIG_MTU)
msgra = 0x56102ff2ce10
gateway_addr = {__in6_u = {__u6_addr8 = {254 '\376', 128 '\200', 0 '\000', 0 '\000', 0 '\000', 0 '\000', 0 '\000', 0 '\000', <optimized out>, <optimized out>, <optimized out>, <optimized out>, <optimized out>, <optimized out>, <optimized out>, <optimized out>}, __u6_addr16 = {33022, 0, 0, 0, <optimized out>, <optimized out>, <optimized out>, <optimized out>}, __u6_addr32 = {33022, 0, <optimized out>, <optimized out>}}}
now_msec = <optimized out>
offset = -1
hop_limit = <optimized out>
val = <optimized out>
__func__ = "receive_ra"
#15 0x00007f422f1028a3 in ndp_call_handlers (msg=0x56102ff2c800, ndp=0x56102ffef950) at libndp.c:1993
handler_item = 0x7f4218018a30
err = <optimized out>
msg = 0x56102ff2c800
len = <optimized out>
err = 0
__FUNCTION__ = "ndp_sock_recv"
#16 0x00007f422f1028a3 in ndp_sock_recv (ndp=0x56102ffef950) at libndp.c:1871
msg = 0x56102ff2c800
len = <optimized out>
err = 0
__FUNCTION__ = "ndp_sock_recv"
#17 0x00007f422f1028a3 in ndp_call_eventfd_handler (ndp=ndp@entry=0x56102ffef950) at libndp.c:2097
#18 0x00007f422f10299f in ndp_callall_eventfd_handler (ndp=0x56102ffef950) at libndp.c:2126
pfd = {fd = 30, events = 1, revents = 1}
ret = <optimized out>
err = <optimized out>
#19 0x000056102e3ba249 in event_ready (fd=<optimized out>, condition=<optimized out>, user_data=<optimized out>) at src/core/ndisc/nm-lndp-ndisc.c:588
ndisc = 0x56102ffa85c0 [NMLndpNDisc]
netns = 0x0
priv = 0x56102ffa85e0
__func__ = "event_ready"
#20 0x00007f422df5095d in g_main_dispatch (context=0x56102fe3ea40) at gmain.c:3193
dispatch = 0x7f422df96dc0 <g_unix_fd_source_dispatch>
prev_source = 0x0
was_in_call = 0
user_data = 0x56102ffa85c0
callback = 0x56102e3b9ff0 <event_ready>
cb_funcs = 0x7f422e21a280 <g_source_callback_funcs>
cb_data = 0x7f421800a7d0
need_destroy = <optimized out>
source = 0x56102ff59440
current = 0x56102fe4c810
i = 4
__func__ = "g_main_dispatch"
#21 0x00007f422df5095d in g_main_context_dispatch (context=context@entry=0x56102fe3ea40) at gmain.c:3873
#22 0x00007f422df50d18 in g_main_context_iterate (context=0x56102fe3ea40, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at gmain.c:3946
max_priority = 2147483647
timeout = 2374
some_ready = 1
nfds = <optimized out>
allocated_nfds = 12
fds = 0x56102ffbe590
#23 0x00007f422df51042 in g_main_loop_run (loop=0x56102fe1a150) at gmain.c:4142
__func__ = "g_main_loop_run"
#24 0x000056102e1104a0 in main (argc=<optimized out>, argv=<optimized out>) at src/core/main.c:519
success = 1
manager = 0x56102fe95060 [NMManager]
config = <optimized out>
error = 0x0
wrote_pidfile = 0
bad_domains = 0x0
config_cli = 0x0
sd_id = 120
error_invalid_logging_config = 0x0
warnings = <optimized out>
errsv = <optimized out>
__func__ = "main"
Thread 2 (Thread 0x7f422734a700 (LWP 842656)):
#0 0x00007f422d5f3a41 in __GI___poll (fds=0x56102fe55510, nfds=2, timeout=3999) at ../sysdeps/unix/sysv/linux/poll.c:29
resultvar = 18446744073709551100
sc_cancel_oldtype = 0
#1 0x00007f422df50c86 in g_main_context_poll (priority=<optimized out>, n_fds=2, fds=0x56102fe55510, timeout=<optimized out>, context=0x56102fe55530) at gmain.c:4247
ret = <optimized out>
errsv = <optimized out>
poll_func = 0x7f422df605e0 <g_poll>
max_priority = 2147483647
timeout = 3999
some_ready = <optimized out>
nfds = 2
allocated_nfds = 2
fds = 0x56102fe55510
#2 0x00007f422df50c86 in g_main_context_iterate (context=context@entry=0x56102fe55530, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at gmain.c:3941
max_priority = 2147483647
timeout = 3999
some_ready = <optimized out>
nfds = 2
allocated_nfds = 2
fds = 0x56102fe55510
#3 0x00007f422df50db0 in g_main_context_iteration (context=0x56102fe55530, may_block=may_block@entry=1) at gmain.c:4007
retval = <optimized out>
#4 0x00007f422df50e01 in glib_worker_main (data=<optimized out>) at gmain.c:5817
#5 0x00007f422df78fca in g_thread_proxy (data=0x56102fe56400) at gthread.c:784
thread = 0x56102fe56400
__func__ = "g_thread_proxy"
#6 0x00007f422d8cf17a in start_thread (arg=<optimized out>) at pthread_create.c:479
ret = <optimized out>
pd = <optimized out>
unwind_buf = {cancel_jmp_buf = {{jmp_buf = {139922102331136, -1884502435883354606, 140724352201198, 140724352201199, 140724352201328, 139922102328000, 1971637026101949970, 1971657569636349458}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}
not_first_call = <optimized out>
#7 0x00007f422d5fedc3 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 3 (Thread 0x7f4226b49700 (LWP 842657)):
#0 0x00007f422d5f3a41 in __GI___poll (fds=0x56102fe6d4f0, nfds=3, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
resultvar = 18446744073709551100
sc_cancel_oldtype = 0
#1 0x00007f422df50c86 in g_main_context_poll (priority=<optimized out>, n_fds=3, fds=0x56102fe6d4f0, timeout=<optimized out>, context=0x56102fe6afc0) at gmain.c:4247
ret = <optimized out>
errsv = <optimized out>
poll_func = 0x7f422df605e0 <g_poll>
max_priority = 2147483647
timeout = -1
some_ready = <optimized out>
nfds = 3
allocated_nfds = 3
fds = 0x56102fe6d4f0
#2 0x00007f422df50c86 in g_main_context_iterate (context=0x56102fe6afc0, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at gmain.c:3941
max_priority = 2147483647
timeout = -1
some_ready = <optimized out>
nfds = 3
allocated_nfds = 3
fds = 0x56102fe6d4f0
#3 0x00007f422df51042 in g_main_loop_run (loop=0x56102fe6b100) at gmain.c:4142
__func__ = "g_main_loop_run"
#4 0x00007f422e74a5da in gdbus_shared_thread_func (user_data=0x56102fe6af90) at gdbusprivate.c:275
data = 0x56102fe6af90
#5 0x00007f422df78fca in g_thread_proxy (data=0x56102fe564a0) at gthread.c:784
thread = 0x56102fe564a0
__func__ = "g_thread_proxy"
#6 0x00007f422d8cf17a in start_thread (arg=<optimized out>) at pthread_create.c:479
ret = <optimized out>
pd = <optimized out>
unwind_buf = {cancel_jmp_buf = {{jmp_buf = {139922093938432, -1884502435883354606, 140724352201502, 140724352201503, 140724352201632, 139922093935296, 1971638126150448658, 1971657569636349458}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}
not_first_call = <optimized out>
#7 0x00007f422d5fedc3 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 4 (Thread 0x7f4217029700 (LWP 842662)):
#0 0x00007f422d5f952d in syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38
#1 0x00007f422df97e3e in g_cond_wait_until (cond=cond@entry=0x56102fe4a2e8, mutex=mutex@entry=0x56102fe4a2e0, end_time=end_time@entry=15815699205) at gthread-posix.c:1449
now = {tv_sec = 15800, tv_nsec = 699205805}
span = {tv_sec = 14, tv_nsec = 999999195}
sampled = 0
res = <optimized out>
#2 0x00007f422df23191 in g_async_queue_pop_intern_unlocked (queue=queue@entry=0x56102fe4a2e0, wait=wait@entry=1, end_time=end_time@entry=15815699205) at gasyncqueue.c:422
retval = <optimized out>
__func__ = "g_async_queue_pop_intern_unlocked"
#3 0x00007f422df23780 in g_async_queue_timeout_pop (queue=0x56102fe4a2e0, timeout=timeout@entry=15000000) at gasyncqueue.c:543
end_time = 15815699205
retval = <optimized out>
#4 0x00007f422df79a71 in g_thread_pool_wait_for_new_pool () at gthreadpool.c:167
pool = <optimized out>
local_max_idle_time = 15000
local_wakeup_thread_serial = <optimized out>
last_wakeup_thread_serial = 0
have_relayed_thread_marker = <optimized out>
free_pool = <optimized out>
task = <optimized out>
pool = <optimized out>
#5 0x00007f422df79a71 in g_thread_pool_thread_proxy (data=<optimized out>) at gthreadpool.c:364
free_pool = <optimized out>
task = <optimized out>
pool = <optimized out>
#6 0x00007f422df78fca in g_thread_proxy (data=0x56102fea88f0) at gthread.c:784
thread = 0x56102fea88f0
__func__ = "g_thread_proxy"
#7 0x00007f422d8cf17a in start_thread (arg=<optimized out>) at pthread_create.c:479
ret = <optimized out>
pd = <optimized out>
unwind_buf = {cancel_jmp_buf = {{jmp_buf = {139921830614784, -1884502435883354606, 140724352200190, 140724352200191, 140724352200320, 139921830611648, 1971671747154441746, 1971657569636349458}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}
not_first_call = <optimized out>
#8 0x00007f422d5fedc3 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Hi Thomas, Is this crash easily trigger by real user? (In reply to Gris Ge from comment #6) > Hi Thomas, > > Is this crash easily trigger by real user? as it happened in CI, it is to assume that yes. But I didn't understand why or how. Also, a normal user does not run with `G_DEBUG=fatal-warnings` (unlike NetworkManager-ci), so the issue would possibly not lead to a crash for the user, and unclear how wrong the behavior would be... Hopefull not too bad. Since the problem described in this bug report should be resolved in a recent advisory, it has been closed with a resolution of ERRATA. For information on the advisory (NetworkManager bug fix and enhancement update), and where to find the updated files, follow the link below. If the solution does not work for you, open a new bug report. https://access.redhat.com/errata/RHEA-2022:1985 |