NetworkManager crash in g_source_set_ready_time()
I came across this crash today:
Thread 4 (Thread 0x78b0c9a56700 (LWP 3113)):
#0 0x000078b0cb25c07f in __GI___poll (fds=0x609c7dcebdb0, nfds=2, timeout=3995) at ../sysdeps/unix/sysv/linux/poll.c:29
#1 0x000078b0cb550cee in g_main_context_poll (priority=<optimized out>, n_fds=2, fds=0x609c7dcebdb0, timeout=<optimized out>, context=0x609c7dd00110) at ../glib-2.64.2/glib/gmain.c:4346
#2 g_main_context_iterate (context=context@entry=0x609c7dd00110, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../glib-2.64.2/glib/gmain.c:4042
#3 0x000078b0cb550dbf in g_main_context_iteration (context=0x609c7dd00110, may_block=may_block@entry=1) at ../glib-2.64.2/glib/gmain.c:4108
#4 0x000078b0cb550e11 in glib_worker_main (data=<optimized out>) at ../glib-2.64.2/glib/gmain.c:5996
#5 0x000078b0cb52d51d in g_thread_proxy (data=0x609c7dcfd860) at ../glib-2.64.2/glib/gthread.c:807
#6 0x000078b0cb32ee67 in start_thread (arg=<optimized out>) at pthread_create.c:477
#7 0x000078b0cb26667f in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 3 (Thread 0x78b0c8a54700 (LWP 3118)):
#0 0x000078b0cb25c07f in __GI___poll (fds=0x609c7dd2c9b0, nfds=3, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
#1 0x000078b0cb550cee in g_main_context_poll (priority=<optimized out>, n_fds=3, fds=0x609c7dd2c9b0, timeout=<optimized out>, context=0x609c7dd2a290) at ../glib-2.64.2/glib/gmain.c:4346
#2 g_main_context_iterate (context=0x609c7dd2a290, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../glib-2.64.2/glib/gmain.c:4042
#3 0x000078b0cb551d03 in g_main_loop_run (loop=0x609c7dd2a380) at ../glib-2.64.2/glib/gmain.c:4241
#4 0x000078b0cb6cb2e6 in gdbus_shared_thread_func (user_data=0x609c7dd2a260) at ../glib-2.64.2/gio/gdbusprivate.c:279
#5 0x000078b0cb52d51d in g_thread_proxy (data=0x609c7dd21180) at ../glib-2.64.2/glib/gthread.c:807
#6 0x000078b0cb32ee67 in start_thread (arg=<optimized out>) at pthread_create.c:477
#7 0x000078b0cb26667f in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 2 (Thread 0x78b0c9255700 (LWP 13222)):
#0 syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38
#1 0x000078b0cb501de8 in g_cond_wait_until (end_time=<optimized out>, mutex=0x609c7deb6020, cond=0x609c7deb6028) at ../glib-2.64.2/glib/gthread-posix.c:1588
#2 g_cond_wait_until (cond=0x609c7deb6028, mutex=0x609c7deb6020, end_time=<optimized out>) at ../glib-2.64.2/glib/gthread-posix.c:1561
#3 0x000078b0cb57f271 in g_async_queue_pop_intern_unlocked (queue=0x609c7deb6020, wait=1, end_time=21627533588) at ../glib-2.64.2/glib/gasyncqueue.c:422
#4 0x000078b0cb5277ca in g_thread_pool_wait_for_new_task (pool=<optimized out>) at ../glib-2.64.2/glib/gthreadpool.c:278
#5 g_thread_pool_thread_proxy (data=<optimized out>) at ../glib-2.64.2/glib/gthreadpool.c:343
#6 0x000078b0cb52d51d in g_thread_proxy (data=0x609c7df20120) at ../glib-2.64.2/glib/gthread.c:807
#7 0x000078b0cb32ee67 in start_thread (arg=<optimized out>) at pthread_create.c:477
#8 0x000078b0cb26667f in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 1 (Thread 0x78b0ca1caac0 (LWP 3105)):
#0 0x000078b0cb547bef in g_source_set_ready_time (source=0x78b0c0002590, ready_time=0) at ../glib-2.64.2/glib/gmain.c:1942
#1 0x000078b0cb785856 in cancellable_source_cancelled (cancellable=<optimized out>, user_data=0x78b0c0002590) at ../glib-2.64.2/gio/gcancellable.c:666
#2 0x000078b0cb63328f in _g_closure_invoke_va (param_types=0x0, n_params=<optimized out>, args=0x7ffca5629770, instance=0x78b0bc006060, return_value=0x0, closure=0x78b0c00399e0) at ../glib-2.64.2/gobject/gclosure.c:873
#3 g_signal_emit_valist (instance=0x78b0bc006060, signal_id=<optimized out>, detail=<optimized out>, var_args=var_args@entry=0x7ffca5629770) at ../glib-2.64.2/gobject/gsignal.c:3407
#4 0x000078b0cb634757 in g_signal_emit (instance=instance@entry=0x78b0bc006060, signal_id=<optimized out>, detail=detail@entry=0) at ../glib-2.64.2/gobject/gsignal.c:3554
#5 0x000078b0cb786cc8 in g_cancellable_cancel (cancellable=0x78b0bc006060) at ../glib-2.64.2/gio/gcancellable.c:509
#6 g_cancellable_cancel (cancellable=0x78b0bc006060) at ../glib-2.64.2/gio/gcancellable.c:483
#7 0x000078b0cb6cf5e3 in _g_dbus_worker_close (worker=0x78b0bc004ac0, task=<optimized out>) at ../glib-2.64.2/gio/gdbusprivate.c:1721
#8 0x000078b0cb6e6166 in g_dbus_connection_close (user_data=0x0, callback=0x0, cancellable=<optimized out>, connection=0x609c7dd06c40) at ../glib-2.64.2/gio/gdbusconnection.c:1464
#9 g_dbus_connection_close (connection=0x609c7dd06c40, cancellable=<optimized out>, callback=0x0, user_data=0x0) at ../glib-2.64.2/gio/gdbusconnection.c:1447
#10 0x0000609c7bcf8adf in close_connection_in_idle (user_data=0x609c7ddfb660) at /var/tmp/portage/net-misc/networkmanager-1.18.6/work/NetworkManager-1.18.6/src/nm-dbus-manager.c:236
#11 0x000078b0cb54ee6d in g_main_dispatch (context=0x609c7dceabe0) at ../glib-2.64.2/glib/gmain.c:3309
#12 g_main_context_dispatch (context=context@entry=0x609c7dceabe0) at ../glib-2.64.2/glib/gmain.c:3974
#13 0x000078b0cb550d78 in g_main_context_iterate (context=0x609c7dceabe0, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../glib-2.64.2/glib/gmain.c:4047
#14 0x000078b0cb551d03 in g_main_loop_run (loop=0x609c7dcc4bf0) at ../glib-2.64.2/glib/gmain.c:4241
#15 0x0000609c7bced392 in main (argc=<optimized out>, argv=<optimized out>) at /var/tmp/portage/net-misc/networkmanager-1.18.6/work/NetworkManager-1.18.6/src/main.c:444
(gdb) p source->priv
$2 = (GSourcePrivate *) 0x0
These are the last few messages logged before it happened:
mai 10 19:54:16 mdontu-l NetworkManager[3105]: <info> [1589129656.0494] device (eno1): carrier: link connected
mai 10 19:54:23 mdontu-l NetworkManager[3105]: <info> [1589129663.0288] device (eno1): carrier: link connected
mai 10 19:54:44 mdontu-l NetworkManager[3105]: <info> [1589129684.1672] device (eno1): carrier: link connected
mai 10 19:55:00 mdontu-l NetworkManager[3105]: <info> [1589129700.0373] device (eno1): carrier: link connected
mai 10 22:13:24 mdontu-l NetworkManager[3105]: <info> [1589138004.5264] dhcp4 (wlp2s0): address 192.168.1.131
mai 10 22:13:24 mdontu-l NetworkManager[3105]: <info> [1589138004.5264] dhcp4 (wlp2s0): plen 24 (255.255.255.0)
mai 10 22:13:24 mdontu-l NetworkManager[3105]: <info> [1589138004.5264] dhcp4 (wlp2s0): gateway 192.168.1.1
mai 10 22:13:24 mdontu-l NetworkManager[3105]: <info> [1589138004.5264] dhcp4 (wlp2s0): lease time 43200
mai 10 22:13:24 mdontu-l NetworkManager[3105]: <info> [1589138004.5264] dhcp4 (wlp2s0): hostname 'mdontu-l'
mai 10 22:13:24 mdontu-l NetworkManager[3105]: <info> [1589138004.5264] dhcp4 (wlp2s0): nameserver '192.168.1.1'
mai 10 22:13:24 mdontu-l NetworkManager[3105]: <info> [1589138004.5264] dhcp4 (wlp2s0): domain name 'lan'
mai 10 22:13:24 mdontu-l NetworkManager[3105]: <info> [1589138004.5264] dhcp4 (wlp2s0): state changed bound -> bound
mai 10 22:49:09 mdontu-l NetworkManager[3105]: <info> [1589140149.4151] dhcp6 (wlp2s0): valid_lft 4294967295
mai 10 22:49:09 mdontu-l NetworkManager[3105]: <info> [1589140149.4152] dhcp6 (wlp2s0): preferred_lft 4294967295
mai 10 22:49:09 mdontu-l NetworkManager[3105]: <info> [1589140149.4152] dhcp6 (wlp2s0): address 2a02:2f01:5d05:8100::592
mai 10 22:49:09 mdontu-l NetworkManager[3105]: <info> [1589140149.4153] dhcp6 (wlp2s0): nameserver '2a02:2f01:5d05:8100::1'
mai 10 22:49:09 mdontu-l NetworkManager[3105]: <info> [1589140149.4153] dhcp6 (wlp2s0): state changed bound -> bound, event ID="6e:33:e9:9d|1589140149"