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: | --- | ||
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 |