tracker-extract hangs
My extract process hangs up reproducibly at least once per boot. Miner hangs too but as far as I can see it just waits for extract.
Open files of the extract process:
$ ls -l /proc/2808/fd/
lr-x------ 1 fina fina 64 11. Nov 16:40 0 -> /dev/null
lrwx------ 1 fina fina 64 11. Nov 16:40 1 -> 'socket:[26700]'
lr-x------ 1 fina fina 64 11. Nov 16:40 10 -> /proc/2808/mountinfo
lr-x------ 1 fina fina 64 11. Nov 16:40 11 -> /home/fina/.local/share/gvfs-metadata/root
lr-x------ 1 fina fina 64 11. Nov 16:40 12 -> /home/fina/.local/share/gvfs-metadata/root-368b79c9.log
lr-x------ 1 fina fina 64 11. Nov 16:40 13 -> 'pipe:[31767]'
lrwx------ 1 fina fina 64 11. Nov 16:40 15 -> 'socket:[28074]'
l-wx------ 1 fina fina 64 11. Nov 16:40 16 -> 'pipe:[31768]'
lr-x------ 1 fina fina 64 11. Nov 16:40 17 -> 'pipe:[31768]'
lrwx------ 1 fina fina 64 11. Nov 16:40 2 -> 'socket:[26700]'
lrwx------ 1 fina fina 64 11. Nov 16:39 3 -> 'anon_inode:[eventfd]'
lrwx------ 1 fina fina 64 11. Nov 16:40 4 -> 'anon_inode:[eventfd]'
lrwx------ 1 fina fina 64 11. Nov 16:40 5 -> 'socket:[26718]'
lrwx------ 1 fina fina 64 11. Nov 16:40 6 -> 'anon_inode:[eventfd]'
lr-x------ 1 fina fina 64 11. Nov 16:40 7 -> anon_inode:inotify
lr-x------ 1 fina fina 64 11. Nov 16:40 8 -> /proc/2808/mountinfo
gdb backtrace for hung extract:
(gdb) t a a bt
Thread 6 (Thread 0x7f13ab7fe6c0 (LWP 3166) "single"):
#0 syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38
#1 0x00007f13c27f878f in g_cond_wait (cond=cond@entry=0x55ff4e404a78, mutex=mutex@entry=0x55ff4e404a70) at ../../../glib/gthread-posix.c:1590
#2 0x00007f13c27728ab in g_async_queue_pop_intern_unlocked (queue=0x55ff4e404a70, wait=1, end_time=-1) at ../../../glib/gasyncqueue.c:425
#3 0x00007f13c2772cf7 in g_async_queue_pop (queue=queue@entry=0x55ff4e404a70) at ../../../glib/gasyncqueue.c:459
#4 0x000055ff4dc418ab in single_thread_get_metadata (queue=0x55ff4e404a70) at ../src/tracker-extract/tracker-extract.c:538
#5 0x00007f13c27cecfd in g_thread_proxy (data=0x55ff4e56e180) at ../../../glib/gthread.c:831
#6 0x00007f13c226efd4 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
#7 0x00007f13c22ef5ac in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:81
Thread 5 (Thread 0x7f13abfff6c0 (LWP 3153) "single"):
#0 syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38
#1 0x00007f13c27f878f in g_cond_wait (cond=cond@entry=0x7f13ac0203a8, mutex=mutex@entry=0x7f13ac0203a0) at ../../../glib/gthread-posix.c:1590
#2 0x00007f13c27728ab in g_async_queue_pop_intern_unlocked (queue=0x7f13ac0203a0, wait=1, end_time=-1) at ../../../glib/gasyncqueue.c:425
#3 0x00007f13c2772cf7 in g_async_queue_pop (queue=queue@entry=0x7f13ac0203a0) at ../../../glib/gasyncqueue.c:459
#4 0x000055ff4dc418ab in single_thread_get_metadata (queue=0x7f13ac0203a0) at ../src/tracker-extract/tracker-extract.c:538
#5 0x00007f13c27cecfd in g_thread_proxy (data=0x55ff4e56c640) at ../../../glib/gthread.c:831
#6 0x00007f13c226efd4 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
#7 0x00007f13c22ef5ac in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:81
Thread 4 (Thread 0x7f13bb7fd6c0 (LWP 2835) "dconf worker"):
#0 0x00007f13c22e1fef in __GI___poll (fds=0x55ff4e099ba0, nfds=1, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
#1 0x00007f13c27a499e in g_main_context_poll (priority=<optimized out>, n_fds=1, fds=0x55ff4e099ba0, timeout=<optimized out>, context=0x55ff4e099c30) at ../../../glib/gmain.c:4543
#2 g_main_context_iterate (context=context@entry=0x55ff4e099c30, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../../../glib/gmain.c:4233
#3 0x00007f13c27a4abc in g_main_context_iteration (context=0x55ff4e099c30, may_block=1) at ../../../glib/gmain.c:4303
#4 0x00007f13c28a93bd in () at /usr/lib/x86_64-linux-gnu/gio/modules/libdconfsettings.so
#5 0x00007f13c27cecfd in g_thread_proxy (data=0x7f13ac007c60) at ../../../glib/gthread.c:831
#6 0x00007f13c226efd4 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
#7 0x00007f13c22ef5ac in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:81
Thread 3 (Thread 0x7f13bbffe6c0 (LWP 2826) "gdbus"):
#0 0x00007f13c22e1fef in __GI___poll (fds=0x55ff4e08aea0, nfds=2, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
#1 0x00007f13c27a499e in g_main_context_poll (priority=<optimized out>, n_fds=2, fds=0x55ff4e08aea0, timeout=<optimized out>, context=0x55ff4e089560) at ../../../glib/gmain.c:4543
#2 g_main_context_iterate (context=0x55ff4e089560, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../../../glib/gmain.c:4233
#3 0x00007f13c27a4cdf in g_main_loop_run (loop=0x55ff4e089650) at ../../../glib/gmain.c:4438
#4 0x00007f13c2685746 in gdbus_shared_thread_func (user_data=0x55ff4e073880) at ../../../gio/gdbusprivate.c:284
#5 0x00007f13c27cecfd in g_thread_proxy (data=0x55ff4e0834c0) at ../../../glib/gthread.c:831
#6 0x00007f13c226efd4 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
#7 0x00007f13c22ef5ac in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:81
Thread 2 (Thread 0x7f13bc7ff6c0 (LWP 2819) "gmain"):
#0 0x00007f13c22e1fef in __GI___poll (fds=0x55ff4e04b2c0, nfds=2, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
#1 0x00007f13c27a499e in g_main_context_poll (priority=<optimized out>, n_fds=2, fds=0x55ff4e04b2c0, timeout=<optimized out>, context=0x55ff4e078010) at ../../../glib/gmain.c:4543
#2 g_main_context_iterate (context=context@entry=0x55ff4e078010, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../../../glib/gmain.c:4233
#3 0x00007f13c27a4abc in g_main_context_iteration (context=0x55ff4e078010, may_block=may_block@entry=1) at ../../../glib/gmain.c:4303
#4 0x00007f13c27a4b01 in glib_worker_main (data=<optimized out>) at ../../../glib/gmain.c:6414
#5 0x00007f13c27cecfd in g_thread_proxy (data=0x55ff4e076d20) at ../../../glib/gthread.c:831
#6 0x00007f13c226efd4 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
#7 0x00007f13c22ef5ac in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:81
Thread 1 (Thread 0x7f13c0e59f80 (LWP 2808) "tracker-extract"):
#0 0x00007f13c22e1fef in __GI___poll (fds=fds@entry=0x7ffeb5ea1b20, nfds=nfds@entry=1, timeout=timeout@entry=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
#1 0x00007f13c27b4147 in poll (__timeout=__timeout@entry=-1, __nfds=1, __fds=__fds@entry=0x7ffeb5ea1b20) at /usr/include/x86_64-linux-gnu/bits/poll2.h:39
#2 0x00007f13c26430c0 in g_unix_output_stream_write (stream=<optimized out>, buffer=0x7ffeb5ea1c5c, count=4, cancellable=0x0, error=0x0) at ../../../gio/gunixoutputstream.c:350
#3 0x00007f13c25f8425 in g_output_stream_write (stream=0x7f1398017c20 [GUnixOutputStream], buffer=0x7ffeb5ea1c5c, count=4, cancellable=0x0, error=0x0) at ../../../gio/goutputstream.c:244
#4 0x00007f13c25f8425 in g_output_stream_write (stream=stream@entry=0x55ff4e573d50 [GDataOutputStream], buffer=buffer@entry=0x7ffeb5ea1c5c, count=count@entry=4, cancellable=cancellable@entry=0x0, error=error@entry=0x0) at ../../../gio/goutputstream.c:244
#5 0x00007f13c25f8590 in g_output_stream_write_all (stream=0x55ff4e573d50 [GDataOutputStream], buffer=buffer@entry=0x7ffeb5ea1c5c, count=count@entry=4, bytes_written=bytes_written@entry=0x7ffeb5ea1c60, cancellable=cancellable@entry=0x0, error=error@entry=0x0) at ../../../gio/goutputstream.c:303
#6 0x00007f13c25bd4d6 in g_data_output_stream_put_int32 (stream=0x55ff4e573d50 [GDataOutputStream], data=<optimized out>, cancellable=0x0, error=0x0) at ../../../gio/gdataoutputstream.c:374
#7 0x00007f13c24a5b86 in () at /lib/x86_64-linux-gnu/libtracker-sparql-3.0.so.0
#8 0x00007f13c28d5a43 in decorator_commit_info (decorator=decorator@entry=0x55ff4e56d180 [TrackerExtractDecorator]) at ../src/libtracker-miner/tracker-decorator.c:366
#9 0x00007f13c28d6809 in decorator_cache_items_cb (object=<optimized out>, result=0x55ff4e584030, user_data=user_data@entry=0x55ff4e56d180) at ../src/libtracker-miner/tracker-decorator.c:753
#10 0x00007f13c26196f9 in g_task_return_now (task=task@entry=0x55ff4e584030 [GTask]) at ../../../gio/gtask.c:1232
#11 0x00007f13c261a253 in g_task_return (type=<optimized out>, task=0x55ff4e584030 [GTask]) at ../../../gio/gtask.c:1301
#12 g_task_return (task=0x55ff4e584030 [GTask], type=<optimized out>) at ../../../gio/gtask.c:1258
#13 0x00007f13c24a82ee in () at /lib/x86_64-linux-gnu/libtracker-sparql-3.0.so.0
#14 0x00007f13c26196f9 in g_task_return_now (task=task@entry=0x55ff4e583490 [GTask]) at ../../../gio/gtask.c:1232
--Type <RET> for more, q to quit, c to continue without paging--
#15 0x00007f13c261a253 in g_task_return (type=<optimized out>, task=0x55ff4e583490 [GTask]) at ../../../gio/gtask.c:1301
#16 g_task_return (task=0x55ff4e583490 [GTask], type=<optimized out>) at ../../../gio/gtask.c:1258
#17 0x00007f13c24a51fd in () at /lib/x86_64-linux-gnu/libtracker-sparql-3.0.so.0
#18 0x00007f13c26196f9 in g_task_return_now (task=task@entry=0x55ff4e584330 [GTask]) at ../../../gio/gtask.c:1232
#19 0x00007f13c2619739 in complete_in_idle_cb (task=0x55ff4e584330) at ../../../gio/gtask.c:1246
#20 0x00007f13c27a466f in g_main_dispatch (context=0x55ff4e080180) at ../../../glib/gmain.c:3444
#21 g_main_context_dispatch (context=context@entry=0x55ff4e080180) at ../../../glib/gmain.c:4162
#22 0x00007f13c27a4a28 in g_main_context_iterate (context=0x55ff4e080180, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../../../glib/gmain.c:4238
#23 0x00007f13c27a4cdf in g_main_loop_run (loop=0x55ff4e122f70) at ../../../glib/gmain.c:4438
Journal repeats this line about 20 times until hangup
Nov 11 16:39:32 matcha tracker-extract[2808]: gst_discoverer_container_info_get_tags: assertion 'GST_IS_DISCOVERER_CONTAINER_INFO (info)' failed
Versions:
OS: Debian testing
Tracker: 3.4.0
Tell me if you need anything else