Re: gvfsd-dav SIGABRT when using WebDAV [& how-to-get-a-core?]

[Adam Tauno Williams <awilliam whitemice org>]

On Tue, 2013-01-08 at 13:55 +0100, Tomas Bzatek wrote:
> On Fri, 2013-01-04 at 09:46 -0500, Adam Tauno Williams wrote:
> > Backtrace
> > =========
> > Program received signal SIGABRT, Aborted.
> > 0x00007f61351aad25 in raise () from /lib64/libc.so.6
> > (gdb) bt
> Thanks for the backtrace, could you also try "t a a bt" to get backtrace

A BT from my current cores -

Thread 6 (Thread 0x7f5e2f7fe700 (LWP 5331)):
#0  0x00007f5e3838714f in poll () from /lib64/libc.so.6
#1  0x00007f5e388c4b67 in g_poll (fds=<optimized out>, nfds=<optimized
out>, 
    timeout=<optimized out>) at gpoll.c:132
#2  0x00007f5e38e2a538 in g_socket_condition_timed_wait
(socket=<optimized out>, 
    condition=<optimized out>, timeout=-1, cancellable=0x1458ed0, error=
    0x7f5e2f7fdbc8) at gsocket.c:3574
#3  0x00007f5e38e2a993 in g_socket_receive_with_blocking
(socket=0x1431f70, buffer=
    0x7f5e1c01ecb0 "x\a", size=8192, blocking=1, cancellable=0x1458ed0,
error=
    0x7f5e2f7fdbc8) at gsocket.c:2497
#4  0x00007f5e38e133db in g_input_stream_read (stream=0x7f5e2800dd00,
buffer=
    0x7f5e1c01ecb0, count=8192, cancellable=0x1458ed0,
error=0x7f5e2f7fdbc8)
    at ginputstream.c:204
#5  0x00007f5e3991215e in soup_filter_input_stream_read_until ()
   from /usr/lib64/libsoup-2.4.so.1
#6  0x00007f5e39912264 in soup_filter_input_stream_read_line ()
   from /usr/lib64/libsoup-2.4.so.1
#7  0x00007f5e3991b7b0 in ?? () from /usr/lib64/libsoup-2.4.so.1
#8  0x00007f5e3991be1b in ?? () from /usr/lib64/libsoup-2.4.so.1
#9  0x00007f5e3991c696 in ?? () from /usr/lib64/libsoup-2.4.so.1
#10 0x00007f5e3991989a in soup_message_send_request ()
   from /usr/lib64/libsoup-2.4.so.1
#11 0x00007f5e3992b31b in ?? () from /usr/lib64/libsoup-2.4.so.1
#12 0x00007f5e3992b685 in ?? () from /usr/lib64/libsoup-2.4.so.1
#13 0x000000000041042b in do_query_info (backend=0x141e840,
job=0x7f5e24005bd0, 
    filename=<optimized out>, flags=<optimized out>, info=<optimized
out>, 
    matcher=<optimized out>) at gvfsbackenddav.c:1969
#14 0x0000000000416b82 in g_vfs_job_run (job=0x7f5e24005bd0) at
gvfsjob.c:198
#15 0x00007f5e388dab72 in g_thread_pool_thread_proxy (data=<optimized
out>)
    at gthreadpool.c:309
#16 0x00007f5e388da355 in g_thread_proxy (data=0x1431d40) at
gthread.c:797
#17 0x00007f5e38657e0e in start_thread () from /lib64/libpthread.so.0
#18 0x00007f5e3838f2cd in clone () from /lib64/libc.so.6

Thread 5 (Thread 0x7f5e349a5700 (LWP 5525)):
#0  0x00007f5e3865bc61 in pthread_cond_timedwait@@GLIBC_2.3.2 ()
   from /lib64/libpthread.so.0
#1  0x00007f5e388f3e15 in g_cond_wait_until (cond=cond@entry=0x1423f78, 
    mutex=mutex@entry=0x1423f70, end_time=end_time@entry=1486581091)
    at gthread-posix.c:857
#2  0x00007f5e3888d401 in g_async_queue_pop_intern_unlocked
(queue=0x1423f70, 
---Type <return> to continue, or q <return> to quit--- 
    wait=wait@entry=1, end_time=1486581091) at gasyncqueue.c:424
#3  0x00007f5e3888daa8 in g_async_queue_timeout_pop_unlocked
(queue=<optimized out>, 
    timeout=timeout@entry=500000) at gasyncqueue.c:572
#4  0x00007f5e388daa7a in g_thread_pool_wait_for_new_task
(pool=0x1423f10)
    at gthreadpool.c:264
#5  g_thread_pool_thread_proxy (data=<optimized out>) at
gthreadpool.c:298
#6  0x00007f5e388da355 in g_thread_proxy (data=0x7f5e24003c50) at
gthread.c:797
#7  0x00007f5e38657e0e in start_thread () from /lib64/libpthread.so.0
#8  0x00007f5e3838f2cd in clone () from /lib64/libc.so.6

Thread 4 (Thread 0x7f5e2ffff700 (LWP 4849)):
#0  0x00007f5e3838714f in poll () from /lib64/libc.so.6
#1  0x00007f5e388b6ac4 in g_main_context_poll (n_fds=3,
fds=0x7f5e240010c0, timeout=
    -1, context=0x7f5e3000f190, priority=<optimized out>) at
gmain.c:3584
#2  g_main_context_iterate (context=0x7f5e3000f190,
block=block@entry=1, 
    dispatch=dispatch@entry=1, 
    self=<error reading variable: Unhandled dwarf expression opcode
0xfa>)
    at gmain.c:3285
#3  0x00007f5e388b6f22 in g_main_loop_run (loop=0x7f5e3000f120) at
gmain.c:3484
#4  0x00007f5e38e84bf6 in gdbus_shared_thread_func
(user_data=0x7f5e3000f160)
    at gdbusprivate.c:277
#5  0x00007f5e388da355 in g_thread_proxy (data=0x7f5e3000c800) at
gthread.c:797
#6  0x00007f5e38657e0e in start_thread () from /lib64/libpthread.so.0
#7  0x00007f5e3838f2cd in clone () from /lib64/libc.so.6

Thread 3 (Thread 0x7f5e2effd700 (LWP 5539)):
#0  0x00007f5e3865ae20 in pthread_mutex_unlock ()
from /lib64/libpthread.so.0
#1  0x00007f5e388f3ad1 in g_mutex_unlock (mutex=<optimized out>)
    at gthread-posix.c:229
#2  0x00007f5e38b8eaa2 in g_signal_emit_valist ()
from /usr/lib64/libgobject-2.0.so.0
#3  0x00007f5e38b8ef22 in g_signal_emit ()
from /usr/lib64/libgobject-2.0.so.0
#4  0x00007f5e39908978 in soup_connection_set_state ()
   from /usr/lib64/libsoup-2.4.so.1
#5  0x00007f5e39926bfe in soup_session_unqueue_item ()
   from /usr/lib64/libsoup-2.4.so.1
#6  0x00007f5e3992b2be in ?? () from /usr/lib64/libsoup-2.4.so.1
#7  0x00007f5e3992b6b9 in ?? () from /usr/lib64/libsoup-2.4.so.1
#8  0x00007f5e388da355 in g_thread_proxy (data=0x7f5e2800b590) at
gthread.c:797
#9  0x00007f5e38657e0e in start_thread () from /lib64/libpthread.so.0
#10 0x00007f5e3838f2cd in clone () from /lib64/libc.so.6

Thread 2 (Thread 0x7f5e351a6700 (LWP 4847)):
---Type <return> to continue, or q <return> to quit---
#0  0x00007f5e3838714f in poll () from /lib64/libc.so.6
#1  0x00007f5e388b6ac4 in g_main_context_poll (n_fds=1,
fds=0x7f5e300010c0, timeout=
    -1, context=0x1427ce0, priority=<optimized out>) at gmain.c:3584
#2  g_main_context_iterate (context=context@entry=0x1427ce0,
block=block@entry=1, 
    dispatch=dispatch@entry=1, 
    self=<error reading variable: Unhandled dwarf expression opcode
0xfa>)
    at gmain.c:3285
#3  0x00007f5e388b6be4 in g_main_context_iteration
(context=context@entry=0x1427ce0, 
    may_block=may_block@entry=1) at gmain.c:3351
#4  0x00007f5e3592a90d in dconf_gdbus_worker_thread
(user_data=0x1427ce0)
    at dconf-gdbus-thread.c:81
#5  0x00007f5e388da355 in g_thread_proxy (data=0x141d720) at
gthread.c:797
#6  0x00007f5e38657e0e in start_thread () from /lib64/libpthread.so.0
#7  0x00007f5e3838f2cd in clone () from /lib64/libc.so.6

Thread 1 (Thread 0x7f5e3a3a37c0 (LWP 4846)):
#0  0x00007f5e382dfd25 in raise () from /lib64/libc.so.6
#1  0x00007f5e382e11a8 in abort () from /lib64/libc.so.6
#2  0x00007f5e388d8887 in g_assertion_message (domain=domain@entry=
    0x7f5e388f6e2e "GLib", file=file@entry=0x7f5e388fd9fa "gmain.c",
line=line@entry=
    2781, func=func@entry=
    0x7f5e388fe530 <__PRETTY_FUNCTION__.12117>
"g_main_context_acquire", 
    message=<optimized out>) at gtestutils.c:1877
#3  0x00007f5e388d8da4 in g_assertion_message_expr (domain=domain@entry=
    0x7f5e388f6e2e "GLib", file=file@entry=0x7f5e388fd9fa "gmain.c",
line=line@entry=
    2781, func=func@entry=
    0x7f5e388fe530 <__PRETTY_FUNCTION__.12117>
"g_main_context_acquire", 
    expr=expr@entry=0x7f5e388fdabc "context->owner_count == 0") at
gtestutils.c:1888
#4  0x00007f5e388b5df3 in g_main_context_acquire (context=<optimized
out>)
    at gmain.c:2781
#5  g_main_context_acquire (context=0x141a170) at gmain.c:2768
#6  0x00007f5e388b69a4 in g_main_context_iterate (context=0x141a170, 
    block=block@entry=1, dispatch=dispatch@entry=1, 
    self=<error reading variable: Unhandled dwarf expression opcode
0xfa>)
    at gmain.c:3240
#7  0x00007f5e388b6f22 in g_main_loop_run (loop=0x1411060) at
gmain.c:3484
#8  0x0000000000411fab in daemon_main (argc=argc@entry=4,
argv=argv@entry=
    0x7fff01a9b898, max_job_threads=max_job_threads@entry=1, 
    default_type=default_type@entry=0x4234cf "dav", 
    mountable_name=mountable_name@entry=0x0,
first_type_name=first_type_name@entry=
    0x4234cf "dav") at daemon-main.c:300
#9  0x000000000040a730 in main (argc=4, argv=0x7fff01a9b898)
---Type <return> to continue, or q <return> to quit---
    at daemon-main-generic.c:39

> of all active threads? Most likely there won't be more anyway.
> Installing debugging symbols would help to reveal more details too
> (usually -debuginfo or -dbg packages).
> > write(2, "**\nGLib:ERROR:gmain.c:2781:g_main_context_acquire: assertion
> > failed: (context->owner_count == 0)\n", 97) = 97
> This is important, yet it doesn't tell us much either. What is the glib
> and libsoup versions installed? 

libglib-2_0-0-2.34.2-1.2.x86_64
libsoup-2_4-1-2.40.2-1.4.x86_64

> Is the gvfs package compiled against
> these particular versions?

Not sure.  They are packages from the openSUSE repo.

> > DOH!  Ok, got that.   Right click -> New file from template ... CRASH!
> Tried to reproduce on two different systems, no luck. A gvfs daemon log
> would be useful for this. Try killing all gvfs processes and start
> `GVFS_DEBUG=1 GVFS_HTTP_DEBUG=all /usr/libexec/gvfsd -r` in the
> terminal, grabbing the output. That would tell us which operations were
> in progress.

AWESOME, thanks for this.  I've been really looking for a way to get a
logfile.

Except I've had problems where the DE kind of falls apart if I kill the
base gvfsd.  But I'll try.

-- 
Adam Tauno Williams  GPG D95ED383
Systems Administrator, Python Developer, LPI / NCLA